{"level":"warn","ts":"2026-04-27T12:06:00.29622Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-27T12:06:00.296322Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.83: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.19.213.83:2380","--initial-cluster=instance=https://199.19.213.83:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.83:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.83: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-04-27T12:06:00.296429Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-27T12:06:00.296467Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.83:2380"]} {"level":"info","ts":"2026-04-27T12:06:00.296533Z","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-04-27T12:06:00.297525Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.83:2379"]} {"level":"info","ts":"2026-04-27T12:06:00.297722Z","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.19.213.83:2380"],"listen-peer-urls":["https://199.19.213.83:2380"],"advertise-client-urls":["https://199.19.213.83:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.83:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.83: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-04-27T12:06:00.302721Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.634628ms"} {"level":"info","ts":"2026-04-27T12:06:00.308513Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5887d25060a34f1b","cluster-id":"8c4d9ec591b76d84"} {"level":"info","ts":"2026-04-27T12:06:00.308595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b switched to configuration voters=()"} {"level":"info","ts":"2026-04-27T12:06:00.308625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b became follower at term 0"} {"level":"info","ts":"2026-04-27T12:06:00.308633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5887d25060a34f1b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-27T12:06:00.308642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b became follower at term 1"} {"level":"info","ts":"2026-04-27T12:06:00.308714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b switched to configuration voters=(6379298639854128923)"} {"level":"warn","ts":"2026-04-27T12:06:00.316506Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-27T12:06:00.317531Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-27T12:06:00.318466Z","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-04-27T12:06:00.319956Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5887d25060a34f1b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-27T12:06:00.320104Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5887d25060a34f1b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-27T12:06:00.320582Z","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-04-27T12:06:00.320691Z","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-04-27T12:06:00.320704Z","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-04-27T12:06:00.321839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b switched to configuration voters=(6379298639854128923)"} {"level":"info","ts":"2026-04-27T12:06:00.321975Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8c4d9ec591b76d84","local-member-id":"5887d25060a34f1b","added-peer-id":"5887d25060a34f1b","added-peer-peer-urls":["https://199.19.213.83:2380"]} {"level":"info","ts":"2026-04-27T12:06:00.324257Z","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-04-27T12:06:00.324367Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.83:2380"} {"level":"info","ts":"2026-04-27T12:06:00.32439Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.83:2380"} {"level":"info","ts":"2026-04-27T12:06:00.324659Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5887d25060a34f1b","initial-advertise-peer-urls":["https://199.19.213.83:2380"],"listen-peer-urls":["https://199.19.213.83:2380"],"advertise-client-urls":["https://199.19.213.83:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.83:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-27T12:06:00.324727Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-27T12:06:00.709479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b is starting a new election at term 1"} {"level":"info","ts":"2026-04-27T12:06:00.709521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b became pre-candidate at term 1"} {"level":"info","ts":"2026-04-27T12:06:00.709537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b received MsgPreVoteResp from 5887d25060a34f1b at term 1"} {"level":"info","ts":"2026-04-27T12:06:00.709552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b became candidate at term 2"} {"level":"info","ts":"2026-04-27T12:06:00.709559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b received MsgVoteResp from 5887d25060a34f1b at term 2"} {"level":"info","ts":"2026-04-27T12:06:00.70957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b became leader at term 2"} {"level":"info","ts":"2026-04-27T12:06:00.709668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5887d25060a34f1b elected leader 5887d25060a34f1b at term 2"} {"level":"info","ts":"2026-04-27T12:06:00.710669Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T12:06:00.711742Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8c4d9ec591b76d84","local-member-id":"5887d25060a34f1b","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T12:06:00.711804Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T12:06:00.711856Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T12:06:00.711893Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T12:06:00.711926Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5887d25060a34f1b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.83:2379]}","request-path":"/0/members/5887d25060a34f1b/attributes","cluster-id":"8c4d9ec591b76d84","publish-timeout":"7s"} {"level":"info","ts":"2026-04-27T12:06:00.711916Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T12:06:00.71433Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.83:2379"} {"level":"info","ts":"2026-04-27T12:06:00.714441Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-27T12:06:00.714542Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-27T12:06:00.715905Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-27T12:06:40.774981Z","caller":"traceutil/trace.go:171","msg":"trace[1010903927] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"121.470093ms","start":"2026-04-27T12:06:40.653479Z","end":"2026-04-27T12:06:40.774949Z","steps":["trace[1010903927] 'process raft request' (duration: 87.182628ms)","trace[1010903927] 'compare' (duration: 34.056569ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:06:40.775077Z","caller":"traceutil/trace.go:171","msg":"trace[734584400] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:549; }","duration":"121.316409ms","start":"2026-04-27T12:06:40.65373Z","end":"2026-04-27T12:06:40.775047Z","steps":["trace[734584400] 'read index received' (duration: 87.007934ms)","trace[734584400] 'applied index is now lower than readState.Index' (duration: 34.305675ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:06:40.775231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.471684ms","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-04-27T12:06:40.775297Z","caller":"traceutil/trace.go:171","msg":"trace[259606160] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:527; }","duration":"121.579287ms","start":"2026-04-27T12:06:40.653702Z","end":"2026-04-27T12:06:40.775281Z","steps":["trace[259606160] 'agreement among raft nodes before linearized reading' (duration: 121.449454ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:06:40.775783Z","caller":"traceutil/trace.go:171","msg":"trace[1124618062] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"113.03751ms","start":"2026-04-27T12:06:40.662731Z","end":"2026-04-27T12:06:40.775769Z","steps":["trace[1124618062] 'process raft request' (duration: 112.156618ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:06:41.81014Z","caller":"traceutil/trace.go:171","msg":"trace[827466548] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"184.953561ms","start":"2026-04-27T12:06:41.625165Z","end":"2026-04-27T12:06:41.810119Z","steps":["trace[827466548] 'process raft request' (duration: 179.384017ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:06:41.810507Z","caller":"traceutil/trace.go:171","msg":"trace[106094857] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"151.021215ms","start":"2026-04-27T12:06:41.659457Z","end":"2026-04-27T12:06:41.810479Z","steps":["trace[106094857] 'process raft request' (duration: 150.714637ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:06:46.943007Z","caller":"traceutil/trace.go:171","msg":"trace[1518018903] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:625; }","duration":"137.882927ms","start":"2026-04-27T12:06:46.805097Z","end":"2026-04-27T12:06:46.94298Z","steps":["trace[1518018903] 'read index received' (duration: 81.367228ms)","trace[1518018903] 'applied index is now lower than readState.Index' (duration: 56.514339ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:06:46.94322Z","caller":"traceutil/trace.go:171","msg":"trace[1976294177] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"248.203951ms","start":"2026-04-27T12:06:46.694986Z","end":"2026-04-27T12:06:46.94319Z","steps":["trace[1976294177] 'process raft request' (duration: 191.451606ms)","trace[1976294177] 'compare' (duration: 56.284274ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:06:46.943263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.144683ms","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-04-27T12:06:46.943306Z","caller":"traceutil/trace.go:171","msg":"trace[169402761] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:603; }","duration":"138.223386ms","start":"2026-04-27T12:06:46.805069Z","end":"2026-04-27T12:06:46.943292Z","steps":["trace[169402761] 'agreement among raft nodes before linearized reading' (duration: 138.048942ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:06:46.943338Z","caller":"traceutil/trace.go:171","msg":"trace[1079249796] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"196.59442ms","start":"2026-04-27T12:06:46.746713Z","end":"2026-04-27T12:06:46.943308Z","steps":["trace[1079249796] 'process raft request' (duration: 196.219431ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:06:47.649752Z","caller":"traceutil/trace.go:171","msg":"trace[482819066] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"268.727025ms","start":"2026-04-27T12:06:47.380933Z","end":"2026-04-27T12:06:47.64966Z","steps":["trace[482819066] 'read index received' (duration: 267.141548ms)","trace[482819066] 'applied index is now lower than readState.Index' (duration: 1.583927ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:06:47.649868Z","caller":"traceutil/trace.go:171","msg":"trace[797566373] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"290.257705ms","start":"2026-04-27T12:06:47.359592Z","end":"2026-04-27T12:06:47.64985Z","steps":["trace[797566373] 'process raft request' (duration: 288.996084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:47.64999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.005063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:06:47.650085Z","caller":"traceutil/trace.go:171","msg":"trace[1623215880] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"269.171957ms","start":"2026-04-27T12:06:47.38089Z","end":"2026-04-27T12:06:47.650062Z","steps":["trace[1623215880] 'agreement among raft nodes before linearized reading' (duration: 268.970622ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:03.004377Z","caller":"traceutil/trace.go:171","msg":"trace[1298340566] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"147.652442ms","start":"2026-04-27T12:07:02.856694Z","end":"2026-04-27T12:07:03.004347Z","steps":["trace[1298340566] 'process raft request' (duration: 147.488358ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:03.217464Z","caller":"traceutil/trace.go:171","msg":"trace[2098363223] linearizableReadLoop","detail":"{readStateIndex:792; appliedIndex:791; }","duration":"184.315496ms","start":"2026-04-27T12:07:03.033122Z","end":"2026-04-27T12:07:03.217437Z","steps":["trace[2098363223] 'read index received' (duration: 183.878135ms)","trace[2098363223] 'applied index is now lower than readState.Index' (duration: 435.801µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:07:03.217546Z","caller":"traceutil/trace.go:171","msg":"trace[531308162] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"195.903925ms","start":"2026-04-27T12:07:03.021607Z","end":"2026-04-27T12:07:03.217511Z","steps":["trace[531308162] 'process raft request' (duration: 195.443074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:03.217712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.352835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T12:07:03.217733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.596852ms","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-04-27T12:07:03.217806Z","caller":"traceutil/trace.go:171","msg":"trace[808650179] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:764; }","duration":"122.499808ms","start":"2026-04-27T12:07:03.095289Z","end":"2026-04-27T12:07:03.217788Z","steps":["trace[808650179] 'agreement among raft nodes before linearized reading' (duration: 122.339984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:03.217783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.263423ms","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-04-27T12:07:03.217817Z","caller":"traceutil/trace.go:171","msg":"trace[1078455633] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:764; }","duration":"184.730045ms","start":"2026-04-27T12:07:03.033069Z","end":"2026-04-27T12:07:03.2178Z","steps":["trace[1078455633] 'agreement among raft nodes before linearized reading' (duration: 184.453829ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:03.217881Z","caller":"traceutil/trace.go:171","msg":"trace[1404027693] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:764; }","duration":"174.406466ms","start":"2026-04-27T12:07:03.043462Z","end":"2026-04-27T12:07:03.217869Z","steps":["trace[1404027693] 'agreement among raft nodes before linearized reading' (duration: 174.117259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:58.097559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.233261ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T12:07:58.097588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.915108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T12:07:58.097673Z","caller":"traceutil/trace.go:171","msg":"trace[111973132] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1046; }","duration":"221.353714ms","start":"2026-04-27T12:07:57.876301Z","end":"2026-04-27T12:07:58.097654Z","steps":["trace[111973132] 'range keys from in-memory index tree' (duration: 221.221061ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:58.097701Z","caller":"traceutil/trace.go:171","msg":"trace[284710515] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1046; }","duration":"151.073261ms","start":"2026-04-27T12:07:57.946601Z","end":"2026-04-27T12:07:58.097675Z","steps":["trace[284710515] 'count revisions from in-memory index tree' (duration: 150.851697ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:58.735091Z","caller":"traceutil/trace.go:171","msg":"trace[1189307744] linearizableReadLoop","detail":"{readStateIndex:1090; appliedIndex:1089; }","duration":"201.676271ms","start":"2026-04-27T12:07:58.533384Z","end":"2026-04-27T12:07:58.73506Z","steps":["trace[1189307744] 'read index received' (duration: 201.476146ms)","trace[1189307744] 'applied index is now lower than readState.Index' (duration: 198.825µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:07:58.735204Z","caller":"traceutil/trace.go:171","msg":"trace[664765213] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"247.526136ms","start":"2026-04-27T12:07:58.487649Z","end":"2026-04-27T12:07:58.735175Z","steps":["trace[664765213] 'process raft request' (duration: 247.233819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:58.735258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.850406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T12:07:58.735332Z","caller":"traceutil/trace.go:171","msg":"trace[1598852916] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1047; }","duration":"201.975628ms","start":"2026-04-27T12:07:58.533342Z","end":"2026-04-27T12:07:58.735317Z","steps":["trace[1598852916] 'agreement among raft nodes before linearized reading' (duration: 201.835295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:08:13.783629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.932891ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T12:08:13.783848Z","caller":"traceutil/trace.go:171","msg":"trace[1057862480] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"158.708499ms","start":"2026-04-27T12:08:13.625122Z","end":"2026-04-27T12:08:13.78383Z","steps":["trace[1057862480] 'process raft request' (duration: 158.612906ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:08:13.784006Z","caller":"traceutil/trace.go:171","msg":"trace[490770577] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"160.318889ms","start":"2026-04-27T12:08:13.623654Z","end":"2026-04-27T12:08:13.783973Z","steps":["trace[490770577] 'process raft request' (duration: 45.398103ms)","trace[490770577] 'compare' (duration: 113.808918ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:08:39.13709Z","caller":"traceutil/trace.go:171","msg":"trace[1235665444] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"139.467579ms","start":"2026-04-27T12:08:38.997595Z","end":"2026-04-27T12:08:39.137062Z","steps":["trace[1235665444] 'process raft request' (duration: 139.207253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:08:39.239743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.795167ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-27T12:08:39.239889Z","caller":"traceutil/trace.go:171","msg":"trace[271378726] linearizableReadLoop","detail":"{readStateIndex:1353; appliedIndex:1350; }","duration":"188.573107ms","start":"2026-04-27T12:08:39.051255Z","end":"2026-04-27T12:08:39.239829Z","steps":["trace[271378726] 'read index received' (duration: 9.278039ms)","trace[271378726] 'applied index is now lower than readState.Index' (duration: 179.293478ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:08:39.240165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.497813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-27T12:08:39.240126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.824633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T12:08:39.240214Z","caller":"traceutil/trace.go:171","msg":"trace[1192868191] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1299; }","duration":"131.604225ms","start":"2026-04-27T12:08:39.108597Z","end":"2026-04-27T12:08:39.240201Z","steps":["trace[1192868191] 'agreement among raft nodes before linearized reading' (duration: 131.421841ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:08:39.240245Z","caller":"traceutil/trace.go:171","msg":"trace[2143816188] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1299; }","duration":"188.971937ms","start":"2026-04-27T12:08:39.051248Z","end":"2026-04-27T12:08:39.24022Z","steps":["trace[2143816188] 'agreement among raft nodes before linearized reading' (duration: 188.754541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:08:39.24049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.60627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-04-27T12:08:39.240536Z","caller":"traceutil/trace.go:171","msg":"trace[449992884] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1299; }","duration":"110.689072ms","start":"2026-04-27T12:08:39.129834Z","end":"2026-04-27T12:08:39.240523Z","steps":["trace[449992884] 'agreement among raft nodes before linearized reading' (duration: 110.58449ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:08:39.385215Z","caller":"traceutil/trace.go:171","msg":"trace[388853791] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"140.636847ms","start":"2026-04-27T12:08:39.244556Z","end":"2026-04-27T12:08:39.385193Z","steps":["trace[388853791] 'process raft request' (duration: 140.456812ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:08:39.422175Z","caller":"traceutil/trace.go:171","msg":"trace[993479247] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"176.409877ms","start":"2026-04-27T12:08:39.245733Z","end":"2026-04-27T12:08:39.422143Z","steps":["trace[993479247] 'process raft request' (duration: 176.132609ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:08:39.422438Z","caller":"traceutil/trace.go:171","msg":"trace[1565633178] linearizableReadLoop","detail":"{readStateIndex:1356; appliedIndex:1353; }","duration":"174.186102ms","start":"2026-04-27T12:08:39.248217Z","end":"2026-04-27T12:08:39.422403Z","steps":["trace[1565633178] 'read index received' (duration: 136.770402ms)","trace[1565633178] 'applied index is now lower than readState.Index' (duration: 37.41335ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:08:39.42253Z","caller":"traceutil/trace.go:171","msg":"trace[34450856] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"176.241612ms","start":"2026-04-27T12:08:39.246251Z","end":"2026-04-27T12:08:39.422492Z","steps":["trace[34450856] 'process raft request' (duration: 175.841543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:08:39.422809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.552961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T12:08:39.422904Z","caller":"traceutil/trace.go:171","msg":"trace[1567755097] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1302; }","duration":"174.722676ms","start":"2026-04-27T12:08:39.24816Z","end":"2026-04-27T12:08:39.422883Z","steps":["trace[1567755097] 'agreement among raft nodes before linearized reading' (duration: 174.508101ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:08:44.054693Z","caller":"traceutil/trace.go:171","msg":"trace[1334921490] linearizableReadLoop","detail":"{readStateIndex:1377; appliedIndex:1375; }","duration":"110.455396ms","start":"2026-04-27T12:08:43.944212Z","end":"2026-04-27T12:08:44.054667Z","steps":["trace[1334921490] 'read index received' (duration: 110.334073ms)","trace[1334921490] 'applied index is now lower than readState.Index' (duration: 120.252µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:08:44.054864Z","caller":"traceutil/trace.go:171","msg":"trace[1517457483] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"160.470265ms","start":"2026-04-27T12:08:43.894364Z","end":"2026-04-27T12:08:44.054834Z","steps":["trace[1517457483] 'process raft request' (duration: 160.111786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:08:44.054921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.696621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-04-27T12:08:44.054957Z","caller":"traceutil/trace.go:171","msg":"trace[976923016] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1322; }","duration":"110.812404ms","start":"2026-04-27T12:08:43.944133Z","end":"2026-04-27T12:08:44.054945Z","steps":["trace[976923016] 'agreement among raft nodes before linearized reading' (duration: 110.6381ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:08:44.05489Z","caller":"traceutil/trace.go:171","msg":"trace[1881759646] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"160.039654ms","start":"2026-04-27T12:08:43.894822Z","end":"2026-04-27T12:08:44.054862Z","steps":["trace[1881759646] 'process raft request' (duration: 159.803019ms)"],"step_count":1}