{"level":"warn","ts":"2026-04-21T10:05:12.947292Z","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-21T10:05:12.947461Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.129: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.129:2380","--initial-cluster=instance=https://199.19.213.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.129: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-21T10:05:12.947572Z","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-21T10:05:12.947623Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.129:2380"]} {"level":"info","ts":"2026-04-21T10:05:12.947702Z","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-21T10:05:12.948618Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.129:2379"]} {"level":"info","ts":"2026-04-21T10:05:12.948762Z","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.129:2380"],"listen-peer-urls":["https://199.19.213.129:2380"],"advertise-client-urls":["https://199.19.213.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.129: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-21T10:05:12.987956Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"36.510772ms"} {"level":"info","ts":"2026-04-21T10:05:12.999473Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d319cd7bf0a1887b","cluster-id":"2ab213cc48e2c85a"} {"level":"info","ts":"2026-04-21T10:05:12.999596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b switched to configuration voters=()"} {"level":"info","ts":"2026-04-21T10:05:12.999627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b became follower at term 0"} {"level":"info","ts":"2026-04-21T10:05:12.999644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d319cd7bf0a1887b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-21T10:05:12.999654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b became follower at term 1"} {"level":"info","ts":"2026-04-21T10:05:13.000448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b switched to configuration voters=(15211415148622350459)"} {"level":"warn","ts":"2026-04-21T10:05:13.007262Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-21T10:05:13.010131Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-21T10:05:13.011921Z","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-21T10:05:13.014613Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d319cd7bf0a1887b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-21T10:05:13.014899Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d319cd7bf0a1887b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-21T10:05:13.015202Z","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-21T10:05:13.015283Z","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-21T10:05:13.015305Z","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-21T10:05:13.017699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b switched to configuration voters=(15211415148622350459)"} {"level":"info","ts":"2026-04-21T10:05:13.018104Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2ab213cc48e2c85a","local-member-id":"d319cd7bf0a1887b","added-peer-id":"d319cd7bf0a1887b","added-peer-peer-urls":["https://199.19.213.129:2380"]} {"level":"info","ts":"2026-04-21T10:05:13.022384Z","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-21T10:05:13.022651Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.129:2380"} {"level":"info","ts":"2026-04-21T10:05:13.022697Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.129:2380"} {"level":"info","ts":"2026-04-21T10:05:13.024572Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d319cd7bf0a1887b","initial-advertise-peer-urls":["https://199.19.213.129:2380"],"listen-peer-urls":["https://199.19.213.129:2380"],"advertise-client-urls":["https://199.19.213.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-21T10:05:13.024803Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-21T10:05:13.200972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b is starting a new election at term 1"} {"level":"info","ts":"2026-04-21T10:05:13.20111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b became pre-candidate at term 1"} {"level":"info","ts":"2026-04-21T10:05:13.201168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b received MsgPreVoteResp from d319cd7bf0a1887b at term 1"} {"level":"info","ts":"2026-04-21T10:05:13.201223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b became candidate at term 2"} {"level":"info","ts":"2026-04-21T10:05:13.201245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b received MsgVoteResp from d319cd7bf0a1887b at term 2"} {"level":"info","ts":"2026-04-21T10:05:13.201266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d319cd7bf0a1887b became leader at term 2"} {"level":"info","ts":"2026-04-21T10:05:13.201311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d319cd7bf0a1887b elected leader d319cd7bf0a1887b at term 2"} {"level":"info","ts":"2026-04-21T10:05:17.016589Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-21T10:05:17.18809Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.986612143s","expected-duration":"1s"} {"level":"info","ts":"2026-04-21T10:05:17.895017Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-21T10:05:17.895348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.359354ms","expected-duration":"100ms","prefix":"","request":"ID:9834627588390627329 Method:\"PUT\" Path:\"/0/members/d319cd7bf0a1887b/attributes\" Val:\"{\\\"name\\\":\\\"instance\\\",\\\"clientURLs\\\":[\\\"https://199.19.213.129:2379\\\"]}\" ","response":""} {"level":"info","ts":"2026-04-21T10:05:17.895513Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2ab213cc48e2c85a","local-member-id":"d319cd7bf0a1887b","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T10:05:17.895641Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T10:05:17.895695Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T10:05:17.895732Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T10:05:17.895729Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d319cd7bf0a1887b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.129:2379]}","request-path":"/0/members/d319cd7bf0a1887b/attributes","cluster-id":"2ab213cc48e2c85a","publish-timeout":"7s"} {"level":"info","ts":"2026-04-21T10:05:17.895857Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T10:05:17.898429Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-21T10:05:17.898518Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-21T10:05:17.8999Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.129:2379"} {"level":"info","ts":"2026-04-21T10:05:17.900474Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-21T10:05:17.910117Z","caller":"membership/cluster.go:576","msg":"updated cluster version","cluster-id":"2ab213cc48e2c85a","local-member-id":"d319cd7bf0a1887b","from":"3.5","to":"3.5"} {"level":"info","ts":"2026-04-21T10:05:17.910354Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T10:05:18.273867Z","caller":"traceutil/trace.go:171","msg":"trace[1871430587] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:5; }","duration":"222.892815ms","start":"2026-04-21T10:05:18.050923Z","end":"2026-04-21T10:05:18.273815Z","steps":["trace[1871430587] 'read index received' (duration: 12.79µs)","trace[1871430587] 'applied index is now lower than readState.Index' (duration: 222.877315ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T10:05:18.300005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.750706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-21T10:05:18.300005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.115936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-21T10:05:18.300064Z","caller":"traceutil/trace.go:171","msg":"trace[1169307709] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"276.061083ms","start":"2026-04-21T10:05:18.023985Z","end":"2026-04-21T10:05:18.300046Z","steps":["trace[1169307709] 'count revisions from in-memory index tree' (duration: 275.693735ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:18.300084Z","caller":"traceutil/trace.go:171","msg":"trace[307295046] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"249.261489ms","start":"2026-04-21T10:05:18.050803Z","end":"2026-04-21T10:05:18.300065Z","steps":["trace[307295046] 'agreement among raft nodes before linearized reading' (duration: 223.514488ms)","trace[307295046] 'range keys from in-memory index tree' (duration: 25.585517ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T10:05:18.300329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.06322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-21T10:05:18.300335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.772085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-21T10:05:18.300365Z","caller":"traceutil/trace.go:171","msg":"trace[915143253] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"261.111892ms","start":"2026-04-21T10:05:18.039245Z","end":"2026-04-21T10:05:18.300357Z","steps":["trace[915143253] 'range keys from in-memory index tree' (duration: 261.008259ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:18.30037Z","caller":"traceutil/trace.go:171","msg":"trace[1518044577] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"301.823477ms","start":"2026-04-21T10:05:17.998537Z","end":"2026-04-21T10:05:18.300361Z","steps":["trace[1518044577] 'range keys from in-memory index tree' (duration: 301.684263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:05:18.300399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:05:17.998522Z","time spent":"301.865428ms","remote":"127.0.0.1:42486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 "} {"level":"warn","ts":"2026-04-21T10:05:18.300449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.317149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-21T10:05:18.300503Z","caller":"traceutil/trace.go:171","msg":"trace[1195459687] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"276.37391ms","start":"2026-04-21T10:05:18.024105Z","end":"2026-04-21T10:05:18.300479Z","steps":["trace[1195459687] 'range keys from in-memory index tree' (duration: 276.249688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:05:18.300486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.604272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-21T10:05:18.30052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.747304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-21T10:05:18.30053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.144422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-21T10:05:18.300599Z","caller":"traceutil/trace.go:171","msg":"trace[1062509750] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"261.223964ms","start":"2026-04-21T10:05:18.039367Z","end":"2026-04-21T10:05:18.300591Z","steps":["trace[1062509750] 'count revisions from in-memory index tree' (duration: 261.116771ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:18.300607Z","caller":"traceutil/trace.go:171","msg":"trace[1792476360] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"288.833717ms","start":"2026-04-21T10:05:18.011743Z","end":"2026-04-21T10:05:18.300577Z","steps":["trace[1792476360] 'range keys from in-memory index tree' (duration: 288.461409ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:18.300602Z","caller":"traceutil/trace.go:171","msg":"trace[769825376] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"301.870658ms","start":"2026-04-21T10:05:17.998711Z","end":"2026-04-21T10:05:18.300582Z","steps":["trace[769825376] 'count revisions from in-memory index tree' (duration: 301.678694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:05:18.300662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:05:17.998704Z","time spent":"301.941939ms","remote":"127.0.0.1:42486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-04-21T10:05:18.300299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.343351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-21T10:05:18.30074Z","caller":"traceutil/trace.go:171","msg":"trace[1993711082] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"249.824211ms","start":"2026-04-21T10:05:18.050906Z","end":"2026-04-21T10:05:18.30073Z","steps":["trace[1993711082] 'agreement among raft nodes before linearized reading' (duration: 223.5801ms)","trace[1993711082] 'count revisions from in-memory index tree' (duration: 25.754481ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T10:05:18.300851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.161603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-21T10:05:18.300889Z","caller":"traceutil/trace.go:171","msg":"trace[1753828638] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"289.237065ms","start":"2026-04-21T10:05:18.011641Z","end":"2026-04-21T10:05:18.300878Z","steps":["trace[1753828638] 'count revisions from in-memory index tree' (duration: 289.095732ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:24.145471Z","caller":"traceutil/trace.go:171","msg":"trace[1775441845] linearizableReadLoop","detail":"{readStateIndex:314; appliedIndex:313; }","duration":"489.787318ms","start":"2026-04-21T10:05:23.655647Z","end":"2026-04-21T10:05:24.145434Z","steps":["trace[1775441845] 'read index received' (duration: 489.586273ms)","trace[1775441845] 'applied index is now lower than readState.Index' (duration: 199.565µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T10:05:24.145564Z","caller":"traceutil/trace.go:171","msg":"trace[1832520702] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"531.403624ms","start":"2026-04-21T10:05:23.614101Z","end":"2026-04-21T10:05:24.145504Z","steps":["trace[1832520702] 'process raft request' (duration: 531.150069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:05:24.145783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.101924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-21T10:05:24.145825Z","caller":"traceutil/trace.go:171","msg":"trace[57614385] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:305; }","duration":"490.214917ms","start":"2026-04-21T10:05:23.655598Z","end":"2026-04-21T10:05:24.145813Z","steps":["trace[57614385] 'agreement among raft nodes before linearized reading' (duration: 490.015432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:05:24.145882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:05:23.65557Z","time spent":"490.286877ms","remote":"127.0.0.1:42688","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-04-21T10:05:24.146315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:05:23.614051Z","time spent":"531.625679ms","remote":"127.0.0.1:42618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":186,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T10:05:24.674529Z","caller":"traceutil/trace.go:171","msg":"trace[1102978585] linearizableReadLoop","detail":"{readStateIndex:315; appliedIndex:314; }","duration":"528.921777ms","start":"2026-04-21T10:05:24.145576Z","end":"2026-04-21T10:05:24.674497Z","steps":["trace[1102978585] 'read index received' (duration: 485.857266ms)","trace[1102978585] 'applied index is now lower than readState.Index' (duration: 43.062811ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T10:05:24.674632Z","caller":"traceutil/trace.go:171","msg":"trace[481436875] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"963.686371ms","start":"2026-04-21T10:05:23.710889Z","end":"2026-04-21T10:05:24.674575Z","steps":["trace[481436875] 'process raft request' (duration: 920.524918ms)","trace[481436875] 'compare' (duration: 42.908327ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T10:05:24.674831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.3865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T10:05:24.674884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:05:23.71085Z","time spent":"963.936266ms","remote":"127.0.0.1:42618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":170,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T10:05:24.674902Z","caller":"traceutil/trace.go:171","msg":"trace[1891165578] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:306; }","duration":"723.541673ms","start":"2026-04-21T10:05:23.951344Z","end":"2026-04-21T10:05:24.674886Z","steps":["trace[1891165578] 'agreement among raft nodes before linearized reading' (duration: 723.37527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:05:24.674892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.851525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T10:05:24.674954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:05:23.951316Z","time spent":"723.628415ms","remote":"127.0.0.1:42688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-21T10:05:24.675029Z","caller":"traceutil/trace.go:171","msg":"trace[581784522] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:306; }","duration":"571.052809ms","start":"2026-04-21T10:05:24.103946Z","end":"2026-04-21T10:05:24.674999Z","steps":["trace[581784522] 'agreement among raft nodes before linearized reading' (duration: 570.841934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:05:24.675088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:05:24.103731Z","time spent":"571.347434ms","remote":"127.0.0.1:35804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T10:05:24.675317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.019569583s","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-04-21T10:05:24.675433Z","caller":"traceutil/trace.go:171","msg":"trace[1434693597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:306; }","duration":"1.019703545s","start":"2026-04-21T10:05:23.655709Z","end":"2026-04-21T10:05:24.675412Z","steps":["trace[1434693597] 'agreement among raft nodes before linearized reading' (duration: 1.019022581s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:05:24.675532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:05:23.655697Z","time spent":"1.019821779s","remote":"127.0.0.1:42688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-21T10:05:25.175266Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834627588390628021,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-21T10:05:25.230377Z","caller":"traceutil/trace.go:171","msg":"trace[1015763936] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"1.080105449s","start":"2026-04-21T10:05:24.150246Z","end":"2026-04-21T10:05:25.230351Z","steps":["trace[1015763936] 'process raft request' (duration: 1.079774063s)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:25.230733Z","caller":"traceutil/trace.go:171","msg":"trace[318602440] transaction","detail":"{read_only:false; number_of_response:0; response_revision:307; }","duration":"893.91233ms","start":"2026-04-21T10:05:24.336817Z","end":"2026-04-21T10:05:25.230729Z","steps":["trace[318602440] 'process raft request' (duration: 893.748687ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:25.230784Z","caller":"traceutil/trace.go:171","msg":"trace[1382973644] transaction","detail":"{read_only:false; number_of_response:0; response_revision:307; }","duration":"893.075893ms","start":"2026-04-21T10:05:24.337676Z","end":"2026-04-21T10:05:25.230752Z","steps":["trace[1382973644] 'process raft request' (duration: 893.032472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:05:25.230863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:05:24.336777Z","time spent":"894.003511ms","remote":"127.0.0.1:42604","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T10:05:25.230951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:05:24.337666Z","time spent":"893.210295ms","remote":"127.0.0.1:42604","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T10:05:25.230866Z","caller":"traceutil/trace.go:171","msg":"trace[1499990625] transaction","detail":"{read_only:false; number_of_response:0; response_revision:307; }","duration":"893.234505ms","start":"2026-04-21T10:05:24.33755Z","end":"2026-04-21T10:05:25.230785Z","steps":["trace[1499990625] 'process raft request' (duration: 893.095493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:05:25.231287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:05:24.150213Z","time spent":"1.080528938s","remote":"127.0.0.1:42688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T10:05:25.23129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:05:24.337539Z","time spent":"893.659865ms","remote":"127.0.0.1:42604","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T10:05:25.230661Z","caller":"traceutil/trace.go:171","msg":"trace[562985521] transaction","detail":"{read_only:false; number_of_response:0; response_revision:307; }","duration":"893.90409ms","start":"2026-04-21T10:05:24.336719Z","end":"2026-04-21T10:05:25.230623Z","steps":["trace[562985521] 'process raft request' (duration: 893.594364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:05:25.231768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:05:24.33664Z","time spent":"895.110384ms","remote":"127.0.0.1:42604","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T10:05:25.239355Z","caller":"traceutil/trace.go:171","msg":"trace[871378827] linearizableReadLoop","detail":"{readStateIndex:323; appliedIndex:315; }","duration":"564.643514ms","start":"2026-04-21T10:05:24.674699Z","end":"2026-04-21T10:05:25.239343Z","steps":["trace[871378827] 'read index received' (duration: 555.279848ms)","trace[871378827] 'applied index is now lower than readState.Index' (duration: 9.362916ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T10:05:25.239505Z","caller":"traceutil/trace.go:171","msg":"trace[653909542] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"559.077066ms","start":"2026-04-21T10:05:24.680422Z","end":"2026-04-21T10:05:25.2395Z","steps":["trace[653909542] 'process raft request' (duration: 558.749389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:05:25.239543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:05:24.680379Z","time spent":"559.144708ms","remote":"127.0.0.1:42758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":420,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T10:05:25.239725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.421792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"warn","ts":"2026-04-21T10:05:25.23981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.325989ms","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-21T10:05:25.239843Z","caller":"traceutil/trace.go:171","msg":"trace[1328385842] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:310; }","duration":"818.422352ms","start":"2026-04-21T10:05:24.421414Z","end":"2026-04-21T10:05:25.239836Z","steps":["trace[1328385842] 'agreement among raft nodes before linearized reading' (duration: 818.368861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:05:25.239861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:05:24.421373Z","time spent":"818.483322ms","remote":"127.0.0.1:42688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-21T10:05:25.239751Z","caller":"traceutil/trace.go:171","msg":"trace[758450636] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"557.943633ms","start":"2026-04-21T10:05:24.681782Z","end":"2026-04-21T10:05:25.239726Z","steps":["trace[758450636] 'process raft request' (duration: 557.525944ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:25.239809Z","caller":"traceutil/trace.go:171","msg":"trace[1442746206] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:310; }","duration":"905.595585ms","start":"2026-04-21T10:05:24.334195Z","end":"2026-04-21T10:05:25.239791Z","steps":["trace[1442746206] 'agreement among raft nodes before linearized reading' (duration: 905.405671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:05:25.23994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:05:24.334164Z","time spent":"905.770849ms","remote":"127.0.0.1:42604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":2943,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-04-21T10:05:25.239746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.08919585s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-21T10:05:25.239991Z","caller":"traceutil/trace.go:171","msg":"trace[1668609159] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:310; }","duration":"1.089493116s","start":"2026-04-21T10:05:24.15049Z","end":"2026-04-21T10:05:25.239984Z","steps":["trace[1668609159] 'agreement among raft nodes before linearized reading' (duration: 1.089142219s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:05:25.240013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:05:24.150468Z","time spent":"1.089540618s","remote":"127.0.0.1:42618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-04-21T10:05:25.240105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:05:24.681767Z","time spent":"558.222908ms","remote":"127.0.0.1:42688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T10:05:25.240235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.616721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T10:05:25.240262Z","caller":"traceutil/trace.go:171","msg":"trace[359753727] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:310; }","duration":"562.690873ms","start":"2026-04-21T10:05:24.677564Z","end":"2026-04-21T10:05:25.240255Z","steps":["trace[359753727] 'agreement among raft nodes before linearized reading' (duration: 562.109331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:05:25.240295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:05:24.676809Z","time spent":"563.48276ms","remote":"127.0.0.1:35790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-21T10:05:25.240502Z","caller":"traceutil/trace.go:171","msg":"trace[1498191182] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"559.025886ms","start":"2026-04-21T10:05:24.681454Z","end":"2026-04-21T10:05:25.240479Z","steps":["trace[1498191182] 'process raft request' (duration: 557.828721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:05:25.240591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:05:24.681444Z","time spent":"559.119738ms","remote":"127.0.0.1:42688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T10:05:26.882987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.483218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-21T10:05:26.88312Z","caller":"traceutil/trace.go:171","msg":"trace[1155528375] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:342; }","duration":"131.714493ms","start":"2026-04-21T10:05:26.751374Z","end":"2026-04-21T10:05:26.883088Z","steps":["trace[1155528375] 'range keys from in-memory index tree' (duration: 131.344976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:05:27.383426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.200064ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T10:05:27.383575Z","caller":"traceutil/trace.go:171","msg":"trace[1370624961] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"200.29226ms","start":"2026-04-21T10:05:27.183246Z","end":"2026-04-21T10:05:27.383539Z","steps":["trace[1370624961] 'process raft request' (duration: 95.717368ms)","trace[1370624961] 'compare' (duration: 104.044161ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T10:05:27.383685Z","caller":"traceutil/trace.go:171","msg":"trace[1384261308] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"119.49726ms","start":"2026-04-21T10:05:27.26412Z","end":"2026-04-21T10:05:27.383617Z","steps":["trace[1384261308] 'process raft request' (duration: 119.437219ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:27.38393Z","caller":"traceutil/trace.go:171","msg":"trace[257904919] linearizableReadLoop","detail":"{readStateIndex:360; appliedIndex:358; }","duration":"182.339939ms","start":"2026-04-21T10:05:27.201336Z","end":"2026-04-21T10:05:27.383676Z","steps":["trace[257904919] 'read index received' (duration: 58.172372ms)","trace[257904919] 'applied index is now lower than readState.Index' (duration: 124.149027ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T10:05:27.384133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.791308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-04-21T10:05:27.384176Z","caller":"traceutil/trace.go:171","msg":"trace[2007338475] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:348; }","duration":"182.871729ms","start":"2026-04-21T10:05:27.201292Z","end":"2026-04-21T10:05:27.384164Z","steps":["trace[2007338475] 'agreement among raft nodes before linearized reading' (duration: 182.738396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:05:27.385202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.560431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-21T10:05:27.38527Z","caller":"traceutil/trace.go:171","msg":"trace[950610911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:348; }","duration":"149.762055ms","start":"2026-04-21T10:05:27.23549Z","end":"2026-04-21T10:05:27.385252Z","steps":["trace[950610911] 'agreement among raft nodes before linearized reading' (duration: 149.588742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:05:27.386101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.394956ms","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-21T10:05:27.386142Z","caller":"traceutil/trace.go:171","msg":"trace[120488517] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:348; }","duration":"133.449888ms","start":"2026-04-21T10:05:27.252684Z","end":"2026-04-21T10:05:27.386134Z","steps":["trace[120488517] 'agreement among raft nodes before linearized reading' (duration: 133.364276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:05:27.38631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.275019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-21T10:05:27.386372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.505082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-04-21T10:05:27.386376Z","caller":"traceutil/trace.go:171","msg":"trace[1674497101] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:348; }","duration":"125.368332ms","start":"2026-04-21T10:05:27.260992Z","end":"2026-04-21T10:05:27.38636Z","steps":["trace[1674497101] 'agreement among raft nodes before linearized reading' (duration: 125.261489ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:27.386398Z","caller":"traceutil/trace.go:171","msg":"trace[748484542] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:348; }","duration":"135.547202ms","start":"2026-04-21T10:05:27.250844Z","end":"2026-04-21T10:05:27.386391Z","steps":["trace[748484542] 'agreement among raft nodes before linearized reading' (duration: 135.489821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:05:27.386711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.380083ms","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-04-21T10:05:27.386772Z","caller":"traceutil/trace.go:171","msg":"trace[1032644989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:348; }","duration":"142.482745ms","start":"2026-04-21T10:05:27.244275Z","end":"2026-04-21T10:05:27.386758Z","steps":["trace[1032644989] 'agreement among raft nodes before linearized reading' (duration: 142.102617ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:27.547496Z","caller":"traceutil/trace.go:171","msg":"trace[1482539198] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"153.198457ms","start":"2026-04-21T10:05:27.394261Z","end":"2026-04-21T10:05:27.54746Z","steps":["trace[1482539198] 'process raft request' (duration: 138.565875ms)","trace[1482539198] 'compare' (duration: 14.433748ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T10:05:27.547937Z","caller":"traceutil/trace.go:171","msg":"trace[1317769337] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"153.604035ms","start":"2026-04-21T10:05:27.3943Z","end":"2026-04-21T10:05:27.547904Z","steps":["trace[1317769337] 'process raft request' (duration: 153.117125ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:27.547997Z","caller":"traceutil/trace.go:171","msg":"trace[1231944286] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"150.00725ms","start":"2026-04-21T10:05:27.397958Z","end":"2026-04-21T10:05:27.547965Z","steps":["trace[1231944286] 'process raft request' (duration: 149.720954ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:27.548136Z","caller":"traceutil/trace.go:171","msg":"trace[1136444042] linearizableReadLoop","detail":"{readStateIndex:369; appliedIndex:362; }","duration":"146.448406ms","start":"2026-04-21T10:05:27.401658Z","end":"2026-04-21T10:05:27.548106Z","steps":["trace[1136444042] 'read index received' (duration: 82.711029ms)","trace[1136444042] 'applied index is now lower than readState.Index' (duration: 63.736187ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T10:05:27.548252Z","caller":"traceutil/trace.go:171","msg":"trace[1842935466] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"149.853607ms","start":"2026-04-21T10:05:27.398304Z","end":"2026-04-21T10:05:27.548158Z","steps":["trace[1842935466] 'process raft request' (duration: 149.647113ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:27.548469Z","caller":"traceutil/trace.go:171","msg":"trace[33884178] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"150.373968ms","start":"2026-04-21T10:05:27.398069Z","end":"2026-04-21T10:05:27.548443Z","steps":["trace[33884178] 'process raft request' (duration: 149.837787ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:27.548337Z","caller":"traceutil/trace.go:171","msg":"trace[910726405] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"149.787745ms","start":"2026-04-21T10:05:27.398537Z","end":"2026-04-21T10:05:27.548325Z","steps":["trace[910726405] 'process raft request' (duration: 149.476539ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:27.54856Z","caller":"traceutil/trace.go:171","msg":"trace[941189957] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"148.002168ms","start":"2026-04-21T10:05:27.400544Z","end":"2026-04-21T10:05:27.548546Z","steps":["trace[941189957] 'process raft request' (duration: 147.510518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:05:27.548758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.081961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-04-21T10:05:27.548794Z","caller":"traceutil/trace.go:171","msg":"trace[94886326] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:356; }","duration":"147.157592ms","start":"2026-04-21T10:05:27.401628Z","end":"2026-04-21T10:05:27.548786Z","steps":["trace[94886326] 'agreement among raft nodes before linearized reading' (duration: 147.08826ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:29.646174Z","caller":"traceutil/trace.go:171","msg":"trace[1776617867] linearizableReadLoop","detail":"{readStateIndex:415; appliedIndex:414; }","duration":"156.984026ms","start":"2026-04-21T10:05:29.489163Z","end":"2026-04-21T10:05:29.646147Z","steps":["trace[1776617867] 'read index received' (duration: 156.709501ms)","trace[1776617867] 'applied index is now lower than readState.Index' (duration: 273.195µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T10:05:29.646324Z","caller":"traceutil/trace.go:171","msg":"trace[1263232751] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"287.606125ms","start":"2026-04-21T10:05:29.358665Z","end":"2026-04-21T10:05:29.646271Z","steps":["trace[1263232751] 'process raft request' (duration: 287.296868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:05:29.646349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.17184ms","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-21T10:05:29.646559Z","caller":"traceutil/trace.go:171","msg":"trace[726410048] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:401; }","duration":"157.448775ms","start":"2026-04-21T10:05:29.489094Z","end":"2026-04-21T10:05:29.646543Z","steps":["trace[726410048] 'agreement among raft nodes before linearized reading' (duration: 157.17693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:05:29.656709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.756212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-21T10:05:29.656788Z","caller":"traceutil/trace.go:171","msg":"trace[1842618091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:401; }","duration":"101.906175ms","start":"2026-04-21T10:05:29.554865Z","end":"2026-04-21T10:05:29.656771Z","steps":["trace[1842618091] 'agreement among raft nodes before linearized reading' (duration: 91.639795ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:33.823377Z","caller":"traceutil/trace.go:171","msg":"trace[45520349] linearizableReadLoop","detail":"{readStateIndex:433; appliedIndex:432; }","duration":"149.132889ms","start":"2026-04-21T10:05:33.674212Z","end":"2026-04-21T10:05:33.823345Z","steps":["trace[45520349] 'read index received' (duration: 148.844703ms)","trace[45520349] 'applied index is now lower than readState.Index' (duration: 286.596µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T10:05:33.823884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.6399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-21T10:05:33.823907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.404175ms","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-04-21T10:05:33.823963Z","caller":"traceutil/trace.go:171","msg":"trace[1386050418] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"162.815171ms","start":"2026-04-21T10:05:33.661122Z","end":"2026-04-21T10:05:33.823937Z","steps":["trace[1386050418] 'process raft request' (duration: 161.994544ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:33.823978Z","caller":"traceutil/trace.go:171","msg":"trace[2128047873] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:418; }","duration":"148.531367ms","start":"2026-04-21T10:05:33.675423Z","end":"2026-04-21T10:05:33.823954Z","steps":["trace[2128047873] 'agreement among raft nodes before linearized reading' (duration: 148.417805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:05:33.82398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.356274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-21T10:05:33.824082Z","caller":"traceutil/trace.go:171","msg":"trace[1333778494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:418; }","duration":"148.531247ms","start":"2026-04-21T10:05:33.67553Z","end":"2026-04-21T10:05:33.824061Z","steps":["trace[1333778494] 'agreement among raft nodes before linearized reading' (duration: 148.334653ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:33.823948Z","caller":"traceutil/trace.go:171","msg":"trace[1535017329] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:418; }","duration":"149.763902ms","start":"2026-04-21T10:05:33.674167Z","end":"2026-04-21T10:05:33.823931Z","steps":["trace[1535017329] 'agreement among raft nodes before linearized reading' (duration: 149.598969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:05:33.824188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.562928ms","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-04-21T10:05:33.824333Z","caller":"traceutil/trace.go:171","msg":"trace[1096991997] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:418; }","duration":"148.777762ms","start":"2026-04-21T10:05:33.67553Z","end":"2026-04-21T10:05:33.824307Z","steps":["trace[1096991997] 'agreement among raft nodes before linearized reading' (duration: 148.361044ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:33.950468Z","caller":"traceutil/trace.go:171","msg":"trace[1757130565] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"120.732868ms","start":"2026-04-21T10:05:33.829696Z","end":"2026-04-21T10:05:33.950429Z","steps":["trace[1757130565] 'process raft request' (duration: 102.738071ms)","trace[1757130565] 'compare' (duration: 17.713811ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T10:05:33.950812Z","caller":"traceutil/trace.go:171","msg":"trace[55894249] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"117.454743ms","start":"2026-04-21T10:05:33.833336Z","end":"2026-04-21T10:05:33.950791Z","steps":["trace[55894249] 'process raft request' (duration: 117.223668ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:33.950812Z","caller":"traceutil/trace.go:171","msg":"trace[1248940432] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"117.380761ms","start":"2026-04-21T10:05:33.833259Z","end":"2026-04-21T10:05:33.95064Z","steps":["trace[1248940432] 'process raft request' (duration: 117.117646ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:33.95114Z","caller":"traceutil/trace.go:171","msg":"trace[2024612928] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"116.542455ms","start":"2026-04-21T10:05:33.834206Z","end":"2026-04-21T10:05:33.950748Z","steps":["trace[2024612928] 'process raft request' (duration: 116.475644ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:52.069181Z","caller":"traceutil/trace.go:171","msg":"trace[732039249] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"213.455679ms","start":"2026-04-21T10:05:51.855703Z","end":"2026-04-21T10:05:52.069158Z","steps":["trace[732039249] 'process raft request' (duration: 213.330756ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:52.076158Z","caller":"traceutil/trace.go:171","msg":"trace[1789541687] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"198.932383ms","start":"2026-04-21T10:05:51.877201Z","end":"2026-04-21T10:05:52.076134Z","steps":["trace[1789541687] 'process raft request' (duration: 198.590507ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:52.076375Z","caller":"traceutil/trace.go:171","msg":"trace[226542894] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"210.024467ms","start":"2026-04-21T10:05:51.866304Z","end":"2026-04-21T10:05:52.076329Z","steps":["trace[226542894] 'process raft request' (duration: 209.245464ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:52.076434Z","caller":"traceutil/trace.go:171","msg":"trace[960279313] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"208.955367ms","start":"2026-04-21T10:05:51.867463Z","end":"2026-04-21T10:05:52.076419Z","steps":["trace[960279313] 'process raft request' (duration: 208.274796ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:52.076536Z","caller":"traceutil/trace.go:171","msg":"trace[1010711116] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"183.329468ms","start":"2026-04-21T10:05:51.893192Z","end":"2026-04-21T10:05:52.076521Z","steps":["trace[1010711116] 'process raft request' (duration: 182.735698ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:52.076543Z","caller":"traceutil/trace.go:171","msg":"trace[1329932038] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"181.484466ms","start":"2026-04-21T10:05:51.895048Z","end":"2026-04-21T10:05:52.076532Z","steps":["trace[1329932038] 'process raft request' (duration: 180.931957ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:52.076627Z","caller":"traceutil/trace.go:171","msg":"trace[599788170] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"195.533043ms","start":"2026-04-21T10:05:51.881081Z","end":"2026-04-21T10:05:52.076614Z","steps":["trace[599788170] 'process raft request' (duration: 194.757649ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:52.076633Z","caller":"traceutil/trace.go:171","msg":"trace[1969955440] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"195.484462ms","start":"2026-04-21T10:05:51.881138Z","end":"2026-04-21T10:05:52.076623Z","steps":["trace[1969955440] 'process raft request' (duration: 194.746619ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:52.076755Z","caller":"traceutil/trace.go:171","msg":"trace[671913033] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"175.612262ms","start":"2026-04-21T10:05:51.901133Z","end":"2026-04-21T10:05:52.076745Z","steps":["trace[671913033] 'process raft request' (duration: 174.89429ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:52.07688Z","caller":"traceutil/trace.go:171","msg":"trace[206031918] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"172.131041ms","start":"2026-04-21T10:05:51.904736Z","end":"2026-04-21T10:05:52.076867Z","steps":["trace[206031918] 'process raft request' (duration: 171.402829ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:52.081203Z","caller":"traceutil/trace.go:171","msg":"trace[42178506] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"146.652454ms","start":"2026-04-21T10:05:51.934522Z","end":"2026-04-21T10:05:52.081175Z","steps":["trace[42178506] 'process raft request' (duration: 141.676547ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:54.22834Z","caller":"traceutil/trace.go:171","msg":"trace[698155397] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"118.103405ms","start":"2026-04-21T10:05:54.110181Z","end":"2026-04-21T10:05:54.228285Z","steps":["trace[698155397] 'process raft request' (duration: 117.925092ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:05:54.961893Z","caller":"traceutil/trace.go:171","msg":"trace[1006259069] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"818.247897ms","start":"2026-04-21T10:05:54.143606Z","end":"2026-04-21T10:05:54.961854Z","steps":["trace[1006259069] 'read index received' (duration: 84.75236ms)","trace[1006259069] 'applied index is now lower than readState.Index' (duration: 733.493547ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T10:05:54.962Z","caller":"traceutil/trace.go:171","msg":"trace[647746038] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"850.387461ms","start":"2026-04-21T10:05:54.111502Z","end":"2026-04-21T10:05:54.961889Z","steps":["trace[647746038] 'process raft request' (duration: 794.433247ms)","trace[647746038] 'compare' (duration: 55.473066ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T10:05:54.96225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.610423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-21T10:05:54.962302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:05:54.111484Z","time spent":"850.666905ms","remote":"127.0.0.1:42688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T10:05:54.962362Z","caller":"traceutil/trace.go:171","msg":"trace[2007235403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:606; }","duration":"818.756625ms","start":"2026-04-21T10:05:54.143582Z","end":"2026-04-21T10:05:54.962338Z","steps":["trace[2007235403] 'agreement among raft nodes before linearized reading' (duration: 818.502351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:05:54.962419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:05:54.143571Z","time spent":"818.838328ms","remote":"127.0.0.1:42688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-21T10:05:54.962775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.069861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-21T10:05:54.962891Z","caller":"traceutil/trace.go:171","msg":"trace[659616571] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"495.017389ms","start":"2026-04-21T10:05:54.467838Z","end":"2026-04-21T10:05:54.962855Z","steps":["trace[659616571] 'process raft request' (duration: 494.142213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:05:54.963622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:05:54.46781Z","time spent":"495.70908ms","remote":"127.0.0.1:42688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T10:05:54.963451Z","caller":"traceutil/trace.go:171","msg":"trace[1072452728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:607; }","duration":"819.756624ms","start":"2026-04-21T10:05:54.14366Z","end":"2026-04-21T10:05:54.963416Z","steps":["trace[1072452728] 'agreement among raft nodes before linearized reading' (duration: 818.99626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:05:54.964102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:05:54.143654Z","time spent":"820.411945ms","remote":"127.0.0.1:42688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-21T10:05:58.773874Z","caller":"traceutil/trace.go:171","msg":"trace[1156127277] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"328.414828ms","start":"2026-04-21T10:05:58.44543Z","end":"2026-04-21T10:05:58.773844Z","steps":["trace[1156127277] 'process raft request' (duration: 328.189954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:05:58.774072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:05:58.445396Z","time spent":"328.575071ms","remote":"127.0.0.1:42604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T10:05:59.476041Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834627588390628689,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T10:05:59.977056Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834627588390628689,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T10:06:00.314323Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.538454871s","expected-duration":"1s"} {"level":"info","ts":"2026-04-21T10:06:00.316487Z","caller":"traceutil/trace.go:171","msg":"trace[1101626109] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"1.340477859s","start":"2026-04-21T10:05:58.975971Z","end":"2026-04-21T10:06:00.316449Z","steps":["trace[1101626109] 'read index received' (duration: 1.338544377s)","trace[1101626109] 'applied index is now lower than readState.Index' (duration: 1.932522ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T10:06:00.316587Z","caller":"traceutil/trace.go:171","msg":"trace[668043187] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"1.667641877s","start":"2026-04-21T10:05:58.648933Z","end":"2026-04-21T10:06:00.316575Z","steps":["trace[668043187] 'process raft request' (duration: 1.665934878s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:06:00.316758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:05:58.648883Z","time spent":"1.667732028s","remote":"127.0.0.1:42498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T10:06:00.317151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.331233063s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-21T10:06:00.317236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.341271922s","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-21T10:06:00.317275Z","caller":"traceutil/trace.go:171","msg":"trace[971220259] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:633; }","duration":"1.341369973s","start":"2026-04-21T10:05:58.975894Z","end":"2026-04-21T10:06:00.317263Z","steps":["trace[971220259] 'agreement among raft nodes before linearized reading' (duration: 1.341238751s)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:06:00.317284Z","caller":"traceutil/trace.go:171","msg":"trace[609889078] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:633; }","duration":"1.331444236s","start":"2026-04-21T10:05:58.985805Z","end":"2026-04-21T10:06:00.317249Z","steps":["trace[609889078] 'agreement among raft nodes before linearized reading' (duration: 1.33104716s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:06:00.317319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:05:58.975852Z","time spent":"1.341460495s","remote":"127.0.0.1:42688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-21T10:06:00.317356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:05:58.985774Z","time spent":"1.331568509s","remote":"127.0.0.1:42688","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-04-21T10:06:00.317419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.211177692s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T10:06:00.317448Z","caller":"traceutil/trace.go:171","msg":"trace[159290205] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"1.211239422s","start":"2026-04-21T10:05:59.106201Z","end":"2026-04-21T10:06:00.317441Z","steps":["trace[159290205] 'agreement among raft nodes before linearized reading' (duration: 1.211196782s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:06:00.317506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:05:59.106181Z","time spent":"1.211312003s","remote":"127.0.0.1:35790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T10:06:00.317656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.322514976s","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-04-21T10:06:00.317702Z","caller":"traceutil/trace.go:171","msg":"trace[442675866] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:633; }","duration":"1.322581307s","start":"2026-04-21T10:05:58.995107Z","end":"2026-04-21T10:06:00.317688Z","steps":["trace[442675866] 'agreement among raft nodes before linearized reading' (duration: 1.322448845s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:06:00.317738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:05:58.995092Z","time spent":"1.322636818s","remote":"127.0.0.1:42688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-21T10:06:00.833871Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9834627588390628697,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T10:06:01.524795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.632468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T10:06:01.525116Z","caller":"traceutil/trace.go:171","msg":"trace[387039440] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:657; }","duration":"1.192231646s","start":"2026-04-21T10:06:00.33287Z","end":"2026-04-21T10:06:01.525102Z","steps":["trace[387039440] 'read index received' (duration: 540.263733ms)","trace[387039440] 'applied index is now lower than readState.Index' (duration: 651.966733ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T10:06:01.525584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.192709283s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T10:06:01.525625Z","caller":"traceutil/trace.go:171","msg":"trace[1549120152] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"1.192775994s","start":"2026-04-21T10:06:00.332836Z","end":"2026-04-21T10:06:01.525612Z","steps":["trace[1549120152] 'agreement among raft nodes before linearized reading' (duration: 1.192713413s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:06:01.525647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:06:00.332824Z","time spent":"1.192818755s","remote":"127.0.0.1:35790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-21T10:06:01.525625Z","caller":"traceutil/trace.go:171","msg":"trace[919532941] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"1.198958316s","start":"2026-04-21T10:06:00.326602Z","end":"2026-04-21T10:06:01.525561Z","steps":["trace[919532941] 'process raft request' (duration: 1.198269635s)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:06:01.525654Z","caller":"traceutil/trace.go:171","msg":"trace[874011631] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"1.19916225s","start":"2026-04-21T10:06:00.326437Z","end":"2026-04-21T10:06:01.525599Z","steps":["trace[874011631] 'process raft request' (duration: 546.571657ms)","trace[874011631] 'compare' (duration: 651.498645ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T10:06:01.525682Z","caller":"traceutil/trace.go:171","msg":"trace[2014325003] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.198965077s","start":"2026-04-21T10:06:00.326676Z","end":"2026-04-21T10:06:01.525641Z","steps":["trace[2014325003] 'process raft request' (duration: 1.198239375s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:06:01.525851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:06:00.326594Z","time spent":"1.19913688s","remote":"127.0.0.1:42688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T10:06:01.525874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:06:00.326426Z","time spent":"1.199332362s","remote":"127.0.0.1:42688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T10:06:01.525896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:06:00.326665Z","time spent":"1.199119999s","remote":"127.0.0.1:42688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T10:06:01.525871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.361761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.129\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-21T10:06:01.525983Z","caller":"traceutil/trace.go:171","msg":"trace[619517124] range","detail":"{range_begin:/registry/masterleases/199.19.213.129; range_end:; response_count:1; response_revision:639; }","duration":"103.518904ms","start":"2026-04-21T10:06:01.422433Z","end":"2026-04-21T10:06:01.525952Z","steps":["trace[619517124] 'agreement among raft nodes before linearized reading' (duration: 103.194868ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:06:01.526056Z","caller":"traceutil/trace.go:171","msg":"trace[331538957] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.10661928s","start":"2026-04-21T10:06:00.419426Z","end":"2026-04-21T10:06:01.526046Z","steps":["trace[331538957] 'process raft request' (duration: 1.105644224s)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:06:01.526058Z","caller":"traceutil/trace.go:171","msg":"trace[747769975] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.198550199s","start":"2026-04-21T10:06:00.327494Z","end":"2026-04-21T10:06:01.526044Z","steps":["trace[747769975] 'process raft request' (duration: 1.197507842s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:06:01.526111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:06:00.4194Z","time spent":"1.106682521s","remote":"127.0.0.1:42688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T10:06:01.526121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:06:00.326788Z","time spent":"1.199296772s","remote":"127.0.0.1:42688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T10:06:01.52637Z","caller":"traceutil/trace.go:171","msg":"trace[506159562] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"1.129476211s","start":"2026-04-21T10:06:00.396884Z","end":"2026-04-21T10:06:01.526361Z","steps":["trace[506159562] 'process raft request' (duration: 1.128149969s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:06:01.526416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:06:00.396857Z","time spent":"1.129534222s","remote":"127.0.0.1:42932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T10:06:17.000035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.119466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-fqqlg\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-21T10:06:17.000143Z","caller":"traceutil/trace.go:171","msg":"trace[568897847] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-fqqlg; range_end:; response_count:1; response_revision:798; }","duration":"455.306578ms","start":"2026-04-21T10:06:16.544812Z","end":"2026-04-21T10:06:17.000119Z","steps":["trace[568897847] 'range keys from in-memory index tree' (duration: 454.850482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:06:17.000197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:06:16.544786Z","time spent":"455.40138ms","remote":"127.0.0.1:42604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-fqqlg\" "} {"level":"info","ts":"2026-04-21T10:07:02.166757Z","caller":"traceutil/trace.go:171","msg":"trace[419447527] linearizableReadLoop","detail":"{readStateIndex:1120; appliedIndex:1119; }","duration":"239.427825ms","start":"2026-04-21T10:07:01.927307Z","end":"2026-04-21T10:07:02.166734Z","steps":["trace[419447527] 'read index received' (duration: 233.690961ms)","trace[419447527] 'applied index is now lower than readState.Index' (duration: 5.736074ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T10:07:02.166826Z","caller":"traceutil/trace.go:171","msg":"trace[1271567330] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"265.645185ms","start":"2026-04-21T10:07:01.901131Z","end":"2026-04-21T10:07:02.166777Z","steps":["trace[1271567330] 'process raft request' (duration: 259.847121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:07:02.167309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.459977ms","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-21T10:07:02.16739Z","caller":"traceutil/trace.go:171","msg":"trace[603980024] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1078; }","duration":"179.553048ms","start":"2026-04-21T10:07:01.987812Z","end":"2026-04-21T10:07:02.167365Z","steps":["trace[603980024] 'agreement among raft nodes before linearized reading' (duration: 179.321264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:07:02.167075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.351564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-21T10:07:02.167649Z","caller":"traceutil/trace.go:171","msg":"trace[1411407396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1078; }","duration":"239.968093ms","start":"2026-04-21T10:07:01.927669Z","end":"2026-04-21T10:07:02.167637Z","steps":["trace[1411407396] 'agreement among raft nodes before linearized reading' (duration: 239.259313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:07:02.169256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.935948ms","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-04-21T10:07:02.169299Z","caller":"traceutil/trace.go:171","msg":"trace[401363575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1078; }","duration":"242.045709ms","start":"2026-04-21T10:07:01.927244Z","end":"2026-04-21T10:07:02.16929Z","steps":["trace[401363575] 'agreement among raft nodes before linearized reading' (duration: 239.625278ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:07:09.808395Z","caller":"traceutil/trace.go:171","msg":"trace[836817178] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"214.934796ms","start":"2026-04-21T10:07:09.593432Z","end":"2026-04-21T10:07:09.808367Z","steps":["trace[836817178] 'process raft request' (duration: 214.728914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:07:10.691259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.658075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-21T10:07:10.691361Z","caller":"traceutil/trace.go:171","msg":"trace[2036359878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1103; }","duration":"425.817176ms","start":"2026-04-21T10:07:10.265516Z","end":"2026-04-21T10:07:10.691333Z","steps":["trace[2036359878] 'range keys from in-memory index tree' (duration: 425.390441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:07:10.691349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.756796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-21T10:07:10.691373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.766365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T10:07:10.691426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:07:10.265497Z","time spent":"425.914057ms","remote":"127.0.0.1:42688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-21T10:07:10.691451Z","caller":"traceutil/trace.go:171","msg":"trace[1712675811] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1103; }","duration":"429.934959ms","start":"2026-04-21T10:07:10.261495Z","end":"2026-04-21T10:07:10.69143Z","steps":["trace[1712675811] 'range keys from in-memory index tree' (duration: 429.533593ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:07:10.691471Z","caller":"traceutil/trace.go:171","msg":"trace[1279865911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1103; }","duration":"584.917956ms","start":"2026-04-21T10:07:10.106527Z","end":"2026-04-21T10:07:10.691445Z","steps":["trace[1279865911] 'range keys from in-memory index tree' (duration: 584.674483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:07:10.691457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.964029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-21T10:07:10.691616Z","caller":"traceutil/trace.go:171","msg":"trace[1575383590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1103; }","duration":"430.188382ms","start":"2026-04-21T10:07:10.261404Z","end":"2026-04-21T10:07:10.691592Z","steps":["trace[1575383590] 'range keys from in-memory index tree' (duration: 429.360321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:07:10.691509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:07:10.261468Z","time spent":"430.030009ms","remote":"127.0.0.1:42688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-21T10:07:10.691378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.511112ms","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-04-21T10:07:10.691876Z","caller":"traceutil/trace.go:171","msg":"trace[1022480431] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1103; }","duration":"474.06801ms","start":"2026-04-21T10:07:10.217794Z","end":"2026-04-21T10:07:10.691862Z","steps":["trace[1022480431] 'range keys from in-memory index tree' (duration: 473.276959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:07:10.691913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:07:10.217761Z","time spent":"474.141911ms","remote":"127.0.0.1:42688","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-04-21T10:07:10.691517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:07:10.106503Z","time spent":"585.005417ms","remote":"127.0.0.1:35804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T10:07:10.692145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:07:10.261375Z","time spent":"430.347164ms","remote":"127.0.0.1:42688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-21T10:07:22.251006Z","caller":"traceutil/trace.go:171","msg":"trace[853361471] linearizableReadLoop","detail":"{readStateIndex:1188; appliedIndex:1187; }","duration":"146.46519ms","start":"2026-04-21T10:07:22.104511Z","end":"2026-04-21T10:07:22.250976Z","steps":["trace[853361471] 'read index received' (duration: 146.42589ms)","trace[853361471] 'applied index is now lower than readState.Index' (duration: 38.17µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T10:07:22.251204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.657002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T10:07:22.251436Z","caller":"traceutil/trace.go:171","msg":"trace[746445448] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1142; }","duration":"146.908245ms","start":"2026-04-21T10:07:22.104463Z","end":"2026-04-21T10:07:22.251371Z","steps":["trace[746445448] 'agreement among raft nodes before linearized reading' (duration: 146.621291ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:07:22.252119Z","caller":"traceutil/trace.go:171","msg":"trace[1376096673] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"373.489ms","start":"2026-04-21T10:07:21.878611Z","end":"2026-04-21T10:07:22.2521Z","steps":["trace[1376096673] 'process raft request' (duration: 372.218794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:07:22.252336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:07:21.878591Z","time spent":"373.57984ms","remote":"127.0.0.1:42688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T10:07:22.637288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.37092ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T10:07:22.637462Z","caller":"traceutil/trace.go:171","msg":"trace[225837917] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"481.715725ms","start":"2026-04-21T10:07:22.155714Z","end":"2026-04-21T10:07:22.63743Z","steps":["trace[225837917] 'process raft request' (duration: 356.117592ms)","trace[225837917] 'compare' (duration: 125.156937ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T10:07:22.63762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:07:22.155683Z","time spent":"481.850486ms","remote":"127.0.0.1:42688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T10:07:52.804083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.288671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T10:07:52.804195Z","caller":"traceutil/trace.go:171","msg":"trace[1676856323] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1241; }","duration":"108.459783ms","start":"2026-04-21T10:07:52.695703Z","end":"2026-04-21T10:07:52.804162Z","steps":["trace[1676856323] 'count revisions from in-memory index tree' (duration: 108.18085ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:07:57.44388Z","caller":"traceutil/trace.go:171","msg":"trace[2064331556] linearizableReadLoop","detail":"{readStateIndex:1310; appliedIndex:1309; }","duration":"150.009031ms","start":"2026-04-21T10:07:57.293837Z","end":"2026-04-21T10:07:57.443846Z","steps":["trace[2064331556] 'read index received' (duration: 149.723068ms)","trace[2064331556] 'applied index is now lower than readState.Index' (duration: 283.993µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T10:07:57.4439Z","caller":"traceutil/trace.go:171","msg":"trace[2137664356] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"189.011716ms","start":"2026-04-21T10:07:57.254839Z","end":"2026-04-21T10:07:57.44385Z","steps":["trace[2137664356] 'process raft request' (duration: 188.776573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:07:57.444339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.466396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-21T10:07:57.444338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.7448ms","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-04-21T10:07:57.444402Z","caller":"traceutil/trace.go:171","msg":"trace[86364531] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1257; }","duration":"150.597907ms","start":"2026-04-21T10:07:57.29379Z","end":"2026-04-21T10:07:57.444388Z","steps":["trace[86364531] 'agreement among raft nodes before linearized reading' (duration: 150.203293ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:07:57.444479Z","caller":"traceutil/trace.go:171","msg":"trace[2131590594] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1257; }","duration":"138.862621ms","start":"2026-04-21T10:07:57.305539Z","end":"2026-04-21T10:07:57.444402Z","steps":["trace[2131590594] 'agreement among raft nodes before linearized reading' (duration: 138.640049ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:08:15.658939Z","caller":"traceutil/trace.go:171","msg":"trace[817104946] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"112.364682ms","start":"2026-04-21T10:08:15.546537Z","end":"2026-04-21T10:08:15.658901Z","steps":["trace[817104946] 'process raft request' (duration: 112.179675ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:08:15.713887Z","caller":"traceutil/trace.go:171","msg":"trace[152987705] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"166.730392ms","start":"2026-04-21T10:08:15.547122Z","end":"2026-04-21T10:08:15.713853Z","steps":["trace[152987705] 'process raft request' (duration: 166.529533ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:08:15.714085Z","caller":"traceutil/trace.go:171","msg":"trace[620190800] linearizableReadLoop","detail":"{readStateIndex:1411; appliedIndex:1408; }","duration":"164.512586ms","start":"2026-04-21T10:08:15.549526Z","end":"2026-04-21T10:08:15.714039Z","steps":["trace[620190800] 'read index received' (duration: 109.301102ms)","trace[620190800] 'applied index is now lower than readState.Index' (duration: 55.207103ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T10:08:15.714315Z","caller":"traceutil/trace.go:171","msg":"trace[1756764864] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"167.110948ms","start":"2026-04-21T10:08:15.547192Z","end":"2026-04-21T10:08:15.714303Z","steps":["trace[1756764864] 'process raft request' (duration: 166.607798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:08:15.714424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.878199ms","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-04-21T10:08:15.714489Z","caller":"traceutil/trace.go:171","msg":"trace[2123325885] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1353; }","duration":"165.007174ms","start":"2026-04-21T10:08:15.549465Z","end":"2026-04-21T10:08:15.714472Z","steps":["trace[2123325885] 'agreement among raft nodes before linearized reading' (duration: 164.720143ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:08:18.143361Z","caller":"traceutil/trace.go:171","msg":"trace[1564966520] linearizableReadLoop","detail":"{readStateIndex:1421; appliedIndex:1420; }","duration":"161.479674ms","start":"2026-04-21T10:08:17.981855Z","end":"2026-04-21T10:08:18.143335Z","steps":["trace[1564966520] 'read index received' (duration: 161.243905ms)","trace[1564966520] 'applied index is now lower than readState.Index' (duration: 234.739µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T10:08:18.143426Z","caller":"traceutil/trace.go:171","msg":"trace[376183146] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"340.775139ms","start":"2026-04-21T10:08:17.802614Z","end":"2026-04-21T10:08:18.143389Z","steps":["trace[376183146] 'process raft request' (duration: 340.54696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:08:18.143494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.61653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T10:08:18.143531Z","caller":"traceutil/trace.go:171","msg":"trace[1398925735] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1362; }","duration":"161.721043ms","start":"2026-04-21T10:08:17.981799Z","end":"2026-04-21T10:08:18.14352Z","steps":["trace[1398925735] 'agreement among raft nodes before linearized reading' (duration: 161.62988ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:08:18.143612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T10:08:17.802601Z","time spent":"340.903005ms","remote":"127.0.0.1:42688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T10:08:33.877188Z","caller":"traceutil/trace.go:171","msg":"trace[1962719405] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"127.933658ms","start":"2026-04-21T10:08:33.749206Z","end":"2026-04-21T10:08:33.87714Z","steps":["trace[1962719405] 'process raft request' (duration: 127.70374ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T10:08:34.331704Z","caller":"traceutil/trace.go:171","msg":"trace[1339733718] linearizableReadLoop","detail":"{readStateIndex:1483; appliedIndex:1482; }","duration":"109.537176ms","start":"2026-04-21T10:08:34.222137Z","end":"2026-04-21T10:08:34.331674Z","steps":["trace[1339733718] 'read index received' (duration: 109.383051ms)","trace[1339733718] 'applied index is now lower than readState.Index' (duration: 153.015µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T10:08:34.331781Z","caller":"traceutil/trace.go:171","msg":"trace[1897768669] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"110.879734ms","start":"2026-04-21T10:08:34.220874Z","end":"2026-04-21T10:08:34.331754Z","steps":["trace[1897768669] 'process raft request' (duration: 110.621675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T10:08:34.331901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.719733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:775"} {"level":"info","ts":"2026-04-21T10:08:34.332023Z","caller":"traceutil/trace.go:171","msg":"trace[1364614830] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:1421; }","duration":"109.871139ms","start":"2026-04-21T10:08:34.222112Z","end":"2026-04-21T10:08:34.331983Z","steps":["trace[1364614830] 'agreement among raft nodes before linearized reading' (duration: 109.668621ms)"],"step_count":1}