{"level":"warn","ts":"2026-04-22T00:21:27.836829Z","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-22T00:21:27.836947Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.246:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.246:2380","--initial-cluster=instance=https://199.204.45.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.246: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-22T00:21:27.837035Z","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-22T00:21:27.837046Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-04-22T00:21:27.83707Z","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-22T00:21:27.837894Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"]} {"level":"info","ts":"2026-04-22T00:21:27.837987Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.246: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-22T00:21:27.84483Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.729703ms"} {"level":"info","ts":"2026-04-22T00:21:27.855666Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab97322ebdd79664","cluster-id":"87bc89380c3dc2c"} {"level":"info","ts":"2026-04-22T00:21:27.855828Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=()"} {"level":"info","ts":"2026-04-22T00:21:27.855858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 0"} {"level":"info","ts":"2026-04-22T00:21:27.855884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab97322ebdd79664 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-22T00:21:27.855897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 1"} {"level":"info","ts":"2026-04-22T00:21:27.855946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"warn","ts":"2026-04-22T00:21:27.865521Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-22T00:21:27.868463Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-22T00:21:27.874496Z","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-22T00:21:27.878048Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab97322ebdd79664","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-22T00:21:27.878831Z","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-22T00:21:27.878908Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab97322ebdd79664","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-22T00:21:27.87904Z","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-22T00:21:27.879089Z","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-22T00:21:27.88521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"info","ts":"2026-04-22T00:21:27.885438Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","added-peer-id":"ab97322ebdd79664","added-peer-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-04-22T00:21:27.887416Z","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-22T00:21:27.887739Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-04-22T00:21:27.887936Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab97322ebdd79664","initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-22T00:21:27.888019Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-22T00:21:27.887969Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-04-22T00:21:28.657147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 is starting a new election at term 1"} {"level":"info","ts":"2026-04-22T00:21:28.657223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-22T00:21:28.657259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgPreVoteResp from ab97322ebdd79664 at term 1"} {"level":"info","ts":"2026-04-22T00:21:28.657279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became candidate at term 2"} {"level":"info","ts":"2026-04-22T00:21:28.657287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgVoteResp from ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-04-22T00:21:28.657302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became leader at term 2"} {"level":"info","ts":"2026-04-22T00:21:28.657314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab97322ebdd79664 elected leader ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-04-22T00:21:28.658537Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T00:21:28.659053Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T00:21:28.659034Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab97322ebdd79664","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.246:2379]}","request-path":"/0/members/ab97322ebdd79664/attributes","cluster-id":"87bc89380c3dc2c","publish-timeout":"7s"} {"level":"info","ts":"2026-04-22T00:21:28.659246Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T00:21:28.661702Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-22T00:21:28.66177Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-22T00:21:28.662056Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.246:2379"} {"level":"info","ts":"2026-04-22T00:21:28.662265Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-22T00:21:28.666015Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T00:21:28.669272Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T00:21:28.669414Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-22T00:21:32.711673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.315068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T00:21:32.711796Z","caller":"traceutil/trace.go:171","msg":"trace[849553954] linearizableReadLoop","detail":"{readStateIndex:133; appliedIndex:132; }","duration":"328.068418ms","start":"2026-04-22T00:21:32.383714Z","end":"2026-04-22T00:21:32.711782Z","steps":["trace[849553954] 'read index received' (duration: 135.082371ms)","trace[849553954] 'applied index is now lower than readState.Index' (duration: 192.984467ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:21:32.711878Z","caller":"traceutil/trace.go:171","msg":"trace[1040764916] transaction","detail":"{read_only:false; response_revision:129; number_of_response:1; }","duration":"641.164306ms","start":"2026-04-22T00:21:32.070699Z","end":"2026-04-22T00:21:32.711864Z","steps":["trace[1040764916] 'process raft request' (duration: 448.18003ms)","trace[1040764916] 'compare' (duration: 192.182171ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:21:32.711929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:21:32.07067Z","time spent":"641.239154ms","remote":"127.0.0.1:47872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":975,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T00:21:32.712794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.834804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:21:32.713012Z","caller":"traceutil/trace.go:171","msg":"trace[724987401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:129; }","duration":"329.333894ms","start":"2026-04-22T00:21:32.383647Z","end":"2026-04-22T00:21:32.712981Z","steps":["trace[724987401] 'agreement among raft nodes before linearized reading' (duration: 328.317733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:21:32.71311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:21:32.383621Z","time spent":"329.475863ms","remote":"127.0.0.1:47518","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-22T00:21:32.713329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.792597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:21:32.713434Z","caller":"traceutil/trace.go:171","msg":"trace[1244219814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:130; }","duration":"170.948014ms","start":"2026-04-22T00:21:32.542453Z","end":"2026-04-22T00:21:32.713401Z","steps":["trace[1244219814] 'agreement among raft nodes before linearized reading' (duration: 170.763388ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:21:32.713556Z","caller":"traceutil/trace.go:171","msg":"trace[314087858] transaction","detail":"{read_only:false; response_revision:130; number_of_response:1; }","duration":"197.921387ms","start":"2026-04-22T00:21:32.515586Z","end":"2026-04-22T00:21:32.713507Z","steps":["trace[314087858] 'process raft request' (duration: 197.485504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:21:33.290702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.850976ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T00:21:33.290923Z","caller":"traceutil/trace.go:171","msg":"trace[98222226] transaction","detail":"{read_only:false; response_revision:132; number_of_response:1; }","duration":"572.364455ms","start":"2026-04-22T00:21:32.718539Z","end":"2026-04-22T00:21:33.290904Z","steps":["trace[98222226] 'process raft request' (duration: 572.299976ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:21:33.290941Z","caller":"traceutil/trace.go:171","msg":"trace[1892872461] transaction","detail":"{read_only:false; response_revision:131; number_of_response:1; }","duration":"572.863095ms","start":"2026-04-22T00:21:32.718041Z","end":"2026-04-22T00:21:33.290904Z","steps":["trace[1892872461] 'process raft request' (duration: 224.725723ms)","trace[1892872461] 'compare' (duration: 347.652579ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:21:33.291004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:21:32.718507Z","time spent":"572.468072ms","remote":"127.0.0.1:47872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":648,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T00:21:33.291006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:21:32.71802Z","time spent":"572.957363ms","remote":"127.0.0.1:47786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T00:21:35.539284Z","caller":"traceutil/trace.go:171","msg":"trace[1448567820] linearizableReadLoop","detail":"{readStateIndex:235; appliedIndex:233; }","duration":"136.953081ms","start":"2026-04-22T00:21:35.402296Z","end":"2026-04-22T00:21:35.539249Z","steps":["trace[1448567820] 'read index received' (duration: 60.56188ms)","trace[1448567820] 'applied index is now lower than readState.Index' (duration: 76.389481ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:21:35.539481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.157059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-22T00:21:35.539496Z","caller":"traceutil/trace.go:171","msg":"trace[218472962] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"171.833158ms","start":"2026-04-22T00:21:35.367637Z","end":"2026-04-22T00:21:35.53947Z","steps":["trace[218472962] 'process raft request' (duration: 171.429224ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:21:35.539513Z","caller":"traceutil/trace.go:171","msg":"trace[1641879534] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:229; }","duration":"137.262707ms","start":"2026-04-22T00:21:35.40224Z","end":"2026-04-22T00:21:35.539502Z","steps":["trace[1641879534] 'agreement among raft nodes before linearized reading' (duration: 137.161438ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:21:35.790436Z","caller":"traceutil/trace.go:171","msg":"trace[30759438] linearizableReadLoop","detail":"{readStateIndex:239; appliedIndex:238; }","duration":"165.997359ms","start":"2026-04-22T00:21:35.624402Z","end":"2026-04-22T00:21:35.790399Z","steps":["trace[30759438] 'read index received' (duration: 163.390016ms)","trace[30759438] 'applied index is now lower than readState.Index' (duration: 2.605423ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:21:35.790689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.282235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-22T00:21:35.790763Z","caller":"traceutil/trace.go:171","msg":"trace[301549088] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:233; }","duration":"166.429893ms","start":"2026-04-22T00:21:35.62432Z","end":"2026-04-22T00:21:35.79075Z","steps":["trace[301549088] 'agreement among raft nodes before linearized reading' (duration: 166.258395ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:21:35.790674Z","caller":"traceutil/trace.go:171","msg":"trace[132647521] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"168.438475ms","start":"2026-04-22T00:21:35.622164Z","end":"2026-04-22T00:21:35.790602Z","steps":["trace[132647521] 'process raft request' (duration: 165.593033ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:21:42.792991Z","caller":"traceutil/trace.go:171","msg":"trace[25832949] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"362.651459ms","start":"2026-04-22T00:21:42.430317Z","end":"2026-04-22T00:21:42.792969Z","steps":["trace[25832949] 'process raft request' (duration: 362.49929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:21:42.793662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:21:42.430282Z","time spent":"362.840588ms","remote":"127.0.0.1:47710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7096,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:21:43.188775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.129677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:21:43.189685Z","caller":"traceutil/trace.go:171","msg":"trace[1205715696] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:358; }","duration":"207.075495ms","start":"2026-04-22T00:21:42.982566Z","end":"2026-04-22T00:21:43.189642Z","steps":["trace[1205715696] 'range keys from in-memory index tree' (duration: 206.019078ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:21:43.536448Z","caller":"traceutil/trace.go:171","msg":"trace[2073618163] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"104.89768ms","start":"2026-04-22T00:21:43.431526Z","end":"2026-04-22T00:21:43.536423Z","steps":["trace[2073618163] 'process raft request' (duration: 104.407482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:21:45.84852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.885921ms","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-22T00:21:45.848642Z","caller":"traceutil/trace.go:171","msg":"trace[1465394074] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:365; }","duration":"183.037302ms","start":"2026-04-22T00:21:45.66558Z","end":"2026-04-22T00:21:45.848617Z","steps":["trace[1465394074] 'range keys from in-memory index tree' (duration: 182.724381ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:21:46.759181Z","caller":"traceutil/trace.go:171","msg":"trace[2112372350] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"100.857401ms","start":"2026-04-22T00:21:46.658291Z","end":"2026-04-22T00:21:46.759148Z","steps":["trace[2112372350] 'process raft request' (duration: 100.621321ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:21:46.897199Z","caller":"traceutil/trace.go:171","msg":"trace[489409006] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"128.484437ms","start":"2026-04-22T00:21:46.768689Z","end":"2026-04-22T00:21:46.897174Z","steps":["trace[489409006] 'process raft request' (duration: 128.348267ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:07.799908Z","caller":"traceutil/trace.go:171","msg":"trace[928634589] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"370.185163ms","start":"2026-04-22T00:22:07.429691Z","end":"2026-04-22T00:22:07.799876Z","steps":["trace[928634589] 'process raft request' (duration: 369.930878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:07.800141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:07.429668Z","time spent":"370.366196ms","remote":"127.0.0.1:47710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3622,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:22:07.800207Z","caller":"traceutil/trace.go:171","msg":"trace[102710143] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"368.649021ms","start":"2026-04-22T00:22:07.43153Z","end":"2026-04-22T00:22:07.800179Z","steps":["trace[102710143] 'process raft request' (duration: 368.518138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:07.800432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:07.4315Z","time spent":"368.792574ms","remote":"127.0.0.1:47982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4294,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:22:07.800472Z","caller":"traceutil/trace.go:171","msg":"trace[47031726] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"370.611772ms","start":"2026-04-22T00:22:07.429804Z","end":"2026-04-22T00:22:07.800416Z","steps":["trace[47031726] 'process raft request' (duration: 369.99873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:07.800767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:07.429793Z","time spent":"370.853407ms","remote":"127.0.0.1:47600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":736,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T00:22:07.808348Z","caller":"traceutil/trace.go:171","msg":"trace[1938158562] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"360.036716ms","start":"2026-04-22T00:22:07.44828Z","end":"2026-04-22T00:22:07.808317Z","steps":["trace[1938158562] 'process raft request' (duration: 359.806171ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:07.808499Z","caller":"traceutil/trace.go:171","msg":"trace[368213817] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"359.929853ms","start":"2026-04-22T00:22:07.448535Z","end":"2026-04-22T00:22:07.808465Z","steps":["trace[368213817] 'process raft request' (duration: 359.690619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:07.808628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:07.448253Z","time spent":"360.25156ms","remote":"127.0.0.1:47600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":653,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T00:22:07.808712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:07.448504Z","time spent":"360.095057ms","remote":"127.0.0.1:47958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4617,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:22:07.808763Z","caller":"traceutil/trace.go:171","msg":"trace[1061375554] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"360.123578ms","start":"2026-04-22T00:22:07.44857Z","end":"2026-04-22T00:22:07.808694Z","steps":["trace[1061375554] 'process raft request' (duration: 359.73696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:07.8091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:07.44856Z","time spent":"360.353213ms","remote":"127.0.0.1:47710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15061,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:22:07.80918Z","caller":"traceutil/trace.go:171","msg":"trace[1124846036] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"359.507105ms","start":"2026-04-22T00:22:07.449617Z","end":"2026-04-22T00:22:07.809124Z","steps":["trace[1124846036] 'process raft request' (duration: 358.868822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:07.80927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:07.449609Z","time spent":"359.615797ms","remote":"127.0.0.1:47976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14448,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:22:07.811158Z","caller":"traceutil/trace.go:171","msg":"trace[1378688880] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"318.263844ms","start":"2026-04-22T00:22:07.492861Z","end":"2026-04-22T00:22:07.811125Z","steps":["trace[1378688880] 'process raft request' (duration: 315.755022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:07.811334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:07.492819Z","time spent":"318.412597ms","remote":"127.0.0.1:47610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317275,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:22:10.642339Z","caller":"traceutil/trace.go:171","msg":"trace[117667783] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"564.381887ms","start":"2026-04-22T00:22:10.077924Z","end":"2026-04-22T00:22:10.642306Z","steps":["trace[117667783] 'process raft request' (duration: 552.7185ms)","trace[117667783] 'compare' (duration: 11.218046ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:10.64257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:10.077893Z","time spent":"564.597082ms","remote":"127.0.0.1:47566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23066,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:22:10.642518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.616632ms","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-22T00:22:10.642758Z","caller":"traceutil/trace.go:171","msg":"trace[342294965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:523; }","duration":"415.879768ms","start":"2026-04-22T00:22:10.226868Z","end":"2026-04-22T00:22:10.642748Z","steps":["trace[342294965] 'agreement among raft nodes before linearized reading' (duration: 415.57052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:10.642785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:10.226802Z","time spent":"415.974642ms","remote":"127.0.0.1:47786","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":"info","ts":"2026-04-22T00:22:10.642416Z","caller":"traceutil/trace.go:171","msg":"trace[1062896526] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:539; }","duration":"415.51877ms","start":"2026-04-22T00:22:10.226886Z","end":"2026-04-22T00:22:10.642404Z","steps":["trace[1062896526] 'read index received' (duration: 403.924656ms)","trace[1062896526] 'applied index is now lower than readState.Index' (duration: 11.593244ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:10.642993Z","caller":"traceutil/trace.go:171","msg":"trace[1446119992] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"425.936598ms","start":"2026-04-22T00:22:10.217039Z","end":"2026-04-22T00:22:10.642976Z","steps":["trace[1446119992] 'process raft request' (duration: 425.283803ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:10.643008Z","caller":"traceutil/trace.go:171","msg":"trace[1874292686] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"553.989259ms","start":"2026-04-22T00:22:10.088999Z","end":"2026-04-22T00:22:10.642988Z","steps":["trace[1874292686] 'process raft request' (duration: 553.212732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:10.64305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:10.217001Z","time spent":"426.02326ms","remote":"127.0.0.1:47566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T00:22:10.643105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:10.088969Z","time spent":"554.077521ms","remote":"127.0.0.1:47566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:22:19.021206Z","caller":"traceutil/trace.go:171","msg":"trace[594080400] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"203.126495ms","start":"2026-04-22T00:22:18.818002Z","end":"2026-04-22T00:22:19.021129Z","steps":["trace[594080400] 'process raft request' (duration: 199.958642ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:19.690073Z","caller":"traceutil/trace.go:171","msg":"trace[286599260] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"604.632211ms","start":"2026-04-22T00:22:19.085392Z","end":"2026-04-22T00:22:19.690024Z","steps":["trace[286599260] 'process raft request' (duration: 604.411834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:19.690312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:19.085363Z","time spent":"604.829137ms","remote":"127.0.0.1:48004","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-22T00:22:19.840277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.627144ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-22T00:22:19.840444Z","caller":"traceutil/trace.go:171","msg":"trace[1109530536] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:622; }","duration":"297.026974ms","start":"2026-04-22T00:22:19.543395Z","end":"2026-04-22T00:22:19.840422Z","steps":["trace[1109530536] 'read index received' (duration: 146.406225ms)","trace[1109530536] 'applied index is now lower than readState.Index' (duration: 150.618779ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:19.840647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.380341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T00:22:19.840685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.294684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8bm6c\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-22T00:22:19.840702Z","caller":"traceutil/trace.go:171","msg":"trace[1688675115] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:603; }","duration":"137.494264ms","start":"2026-04-22T00:22:19.70319Z","end":"2026-04-22T00:22:19.840684Z","steps":["trace[1688675115] 'agreement among raft nodes before linearized reading' (duration: 137.394761ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:19.840715Z","caller":"traceutil/trace.go:171","msg":"trace[1082695962] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8bm6c; range_end:; response_count:1; response_revision:603; }","duration":"297.369005ms","start":"2026-04-22T00:22:19.543335Z","end":"2026-04-22T00:22:19.840704Z","steps":["trace[1082695962] 'agreement among raft nodes before linearized reading' (duration: 297.207321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:20.460885Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959993609237791,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T00:22:20.961128Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959993609237791,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T00:22:20.984031Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.12511681s","expected-duration":"1s"} {"level":"info","ts":"2026-04-22T00:22:20.985343Z","caller":"traceutil/trace.go:171","msg":"trace[630446013] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"1.126556053s","start":"2026-04-22T00:22:19.858738Z","end":"2026-04-22T00:22:20.985294Z","steps":["trace[630446013] 'process raft request' (duration: 1.126051818s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:20.985645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:19.858704Z","time spent":"1.126826751s","remote":"127.0.0.1:47710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:22:21.722479Z","caller":"traceutil/trace.go:171","msg":"trace[1129816225] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"1.420294095s","start":"2026-04-22T00:22:20.302142Z","end":"2026-04-22T00:22:21.722436Z","steps":["trace[1129816225] 'process raft request' (duration: 1.414419284s)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:21.722572Z","caller":"traceutil/trace.go:171","msg":"trace[536198406] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:624; }","duration":"1.769860177s","start":"2026-04-22T00:22:19.952679Z","end":"2026-04-22T00:22:21.722539Z","steps":["trace[536198406] 'read index received' (duration: 1.031969831s)","trace[536198406] 'applied index is now lower than readState.Index' (duration: 737.886926ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:21.722794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.770085004s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-22T00:22:21.722878Z","caller":"traceutil/trace.go:171","msg":"trace[1575760326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:606; }","duration":"1.770225627s","start":"2026-04-22T00:22:19.95263Z","end":"2026-04-22T00:22:21.722855Z","steps":["trace[1575760326] 'agreement among raft nodes before linearized reading' (duration: 1.769989361s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:21.722924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:19.952606Z","time spent":"1.7703045s","remote":"127.0.0.1:47786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T00:22:21.722928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011213419s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-22T00:22:21.72293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.591590029s","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-22T00:22:21.72295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.606771716s","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-22T00:22:21.722976Z","caller":"traceutil/trace.go:171","msg":"trace[1627039481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:606; }","duration":"1.011327142s","start":"2026-04-22T00:22:20.711634Z","end":"2026-04-22T00:22:21.722961Z","steps":["trace[1627039481] 'agreement among raft nodes before linearized reading' (duration: 1.011116665s)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:21.722982Z","caller":"traceutil/trace.go:171","msg":"trace[803777752] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:606; }","duration":"1.591704982s","start":"2026-04-22T00:22:20.131262Z","end":"2026-04-22T00:22:21.722967Z","steps":["trace[803777752] 'agreement among raft nodes before linearized reading' (duration: 1.591502306s)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:21.722998Z","caller":"traceutil/trace.go:171","msg":"trace[2058231510] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:606; }","duration":"1.606866948s","start":"2026-04-22T00:22:20.116115Z","end":"2026-04-22T00:22:21.722982Z","steps":["trace[2058231510] 'agreement among raft nodes before linearized reading' (duration: 1.606704453s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:21.723013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:20.131238Z","time spent":"1.591765634s","remote":"127.0.0.1:47786","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-22T00:22:21.723013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:20.711608Z","time spent":"1.011395714s","remote":"127.0.0.1:47786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T00:22:21.723035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:20.116089Z","time spent":"1.6069371s","remote":"127.0.0.1:47786","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-22T00:22:21.723156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.738336601s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:22:21.723185Z","caller":"traceutil/trace.go:171","msg":"trace[1879872773] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"1.738405693s","start":"2026-04-22T00:22:19.98477Z","end":"2026-04-22T00:22:21.723176Z","steps":["trace[1879872773] 'agreement among raft nodes before linearized reading' (duration: 1.737966489s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:21.723207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:19.984741Z","time spent":"1.738460816s","remote":"127.0.0.1:47518","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-22T00:22:21.723607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:20.302102Z","time spent":"1.420470331s","remote":"127.0.0.1:47610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T00:22:22.725996Z","caller":"traceutil/trace.go:171","msg":"trace[1864170632] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"155.000127ms","start":"2026-04-22T00:22:22.570961Z","end":"2026-04-22T00:22:22.725962Z","steps":["trace[1864170632] 'process raft request' (duration: 152.471777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:23.69989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.499349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8bm6c\" ","response":"range_response_count:1 size:17247"} {"level":"info","ts":"2026-04-22T00:22:23.700004Z","caller":"traceutil/trace.go:171","msg":"trace[1633201018] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8bm6c; range_end:; response_count:1; response_revision:620; }","duration":"138.681235ms","start":"2026-04-22T00:22:23.561298Z","end":"2026-04-22T00:22:23.69998Z","steps":["trace[1633201018] 'range keys from in-memory index tree' (duration: 138.277603ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:27.971796Z","caller":"traceutil/trace.go:171","msg":"trace[218023938] linearizableReadLoop","detail":"{readStateIndex:713; appliedIndex:712; }","duration":"166.304184ms","start":"2026-04-22T00:22:27.805466Z","end":"2026-04-22T00:22:27.97177Z","steps":["trace[218023938] 'read index received' (duration: 165.90717ms)","trace[218023938] 'applied index is now lower than readState.Index' (duration: 396.014µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:22:27.972115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.625904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-04-22T00:22:27.972188Z","caller":"traceutil/trace.go:171","msg":"trace[714510982] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:691; }","duration":"166.770579ms","start":"2026-04-22T00:22:27.805403Z","end":"2026-04-22T00:22:27.972174Z","steps":["trace[714510982] 'agreement among raft nodes before linearized reading' (duration: 166.563842ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:27.972155Z","caller":"traceutil/trace.go:171","msg":"trace[928466874] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"170.02932ms","start":"2026-04-22T00:22:27.80208Z","end":"2026-04-22T00:22:27.97211Z","steps":["trace[928466874] 'process raft request' (duration: 169.382738ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:34.070382Z","caller":"traceutil/trace.go:171","msg":"trace[466495844] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:747; }","duration":"163.191743ms","start":"2026-04-22T00:22:33.907161Z","end":"2026-04-22T00:22:34.070352Z","steps":["trace[466495844] 'read index received' (duration: 47.852587ms)","trace[466495844] 'applied index is now lower than readState.Index' (duration: 115.337576ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:34.071132Z","caller":"traceutil/trace.go:171","msg":"trace[1845327082] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"165.712209ms","start":"2026-04-22T00:22:33.9054Z","end":"2026-04-22T00:22:34.071112Z","steps":["trace[1845327082] 'process raft request' (duration: 94.637003ms)","trace[1845327082] 'compare' (duration: 70.110839ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:34.071507Z","caller":"traceutil/trace.go:171","msg":"trace[962811030] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"166.098344ms","start":"2026-04-22T00:22:33.905399Z","end":"2026-04-22T00:22:34.071497Z","steps":["trace[962811030] 'process raft request' (duration: 164.902688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:34.075293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.69655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:22:34.075374Z","caller":"traceutil/trace.go:171","msg":"trace[342667929] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:726; }","duration":"168.255837ms","start":"2026-04-22T00:22:33.9071Z","end":"2026-04-22T00:22:34.075356Z","steps":["trace[342667929] 'agreement among raft nodes before linearized reading' (duration: 164.712151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:41.272879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.536254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:22:41.272985Z","caller":"traceutil/trace.go:171","msg":"trace[1807263182] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:775; }","duration":"288.692122ms","start":"2026-04-22T00:22:40.984265Z","end":"2026-04-22T00:22:41.272958Z","steps":["trace[1807263182] 'range keys from in-memory index tree' (duration: 288.391318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:41.273015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.147899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ns7vr\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-22T00:22:41.273125Z","caller":"traceutil/trace.go:171","msg":"trace[1556281564] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ns7vr; range_end:; response_count:1; response_revision:775; }","duration":"595.300566ms","start":"2026-04-22T00:22:40.677798Z","end":"2026-04-22T00:22:41.273099Z","steps":["trace[1556281564] 'range keys from in-memory index tree' (duration: 594.954041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:41.273169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:22:40.677774Z","time spent":"595.383018ms","remote":"127.0.0.1:47710","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-ns7vr\" "} {"level":"info","ts":"2026-04-22T00:22:58.142198Z","caller":"traceutil/trace.go:171","msg":"trace[98896111] linearizableReadLoop","detail":"{readStateIndex:987; appliedIndex:986; }","duration":"105.350592ms","start":"2026-04-22T00:22:58.036822Z","end":"2026-04-22T00:22:58.142173Z","steps":["trace[98896111] 'read index received' (duration: 105.087149ms)","trace[98896111] 'applied index is now lower than readState.Index' (duration: 262.583µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:58.1423Z","caller":"traceutil/trace.go:171","msg":"trace[31301138] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"136.975289ms","start":"2026-04-22T00:22:58.005292Z","end":"2026-04-22T00:22:58.142268Z","steps":["trace[31301138] 'process raft request' (duration: 136.719687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:58.142475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.637985ms","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-22T00:22:58.142505Z","caller":"traceutil/trace.go:171","msg":"trace[2096068605] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:956; }","duration":"105.720219ms","start":"2026-04-22T00:22:58.036776Z","end":"2026-04-22T00:22:58.142497Z","steps":["trace[2096068605] 'agreement among raft nodes before linearized reading' (duration: 105.52633ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:58.248384Z","caller":"traceutil/trace.go:171","msg":"trace[326547097] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"102.058459ms","start":"2026-04-22T00:22:58.146281Z","end":"2026-04-22T00:22:58.248339Z","steps":["trace[326547097] 'process raft request' (duration: 59.764905ms)","trace[326547097] 'compare' (duration: 42.180718ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:58.254179Z","caller":"traceutil/trace.go:171","msg":"trace[899243451] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"107.781491ms","start":"2026-04-22T00:22:58.146361Z","end":"2026-04-22T00:22:58.254143Z","steps":["trace[899243451] 'process raft request' (duration: 107.589992ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:58.433746Z","caller":"traceutil/trace.go:171","msg":"trace[429702802] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"164.252172ms","start":"2026-04-22T00:22:58.269461Z","end":"2026-04-22T00:22:58.433713Z","steps":["trace[429702802] 'process raft request' (duration: 143.68963ms)","trace[429702802] 'compare' (duration: 20.419126ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:58.438154Z","caller":"traceutil/trace.go:171","msg":"trace[173960940] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:989; }","duration":"114.694772ms","start":"2026-04-22T00:22:58.323431Z","end":"2026-04-22T00:22:58.438126Z","steps":["trace[173960940] 'read index received' (duration: 89.802045ms)","trace[173960940] 'applied index is now lower than readState.Index' (duration: 24.891347ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:22:58.438385Z","caller":"traceutil/trace.go:171","msg":"trace[1019760414] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"150.137647ms","start":"2026-04-22T00:22:58.288181Z","end":"2026-04-22T00:22:58.438319Z","steps":["trace[1019760414] 'process raft request' (duration: 149.707105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:22:58.438548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.08129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-22T00:22:58.438656Z","caller":"traceutil/trace.go:171","msg":"trace[554270915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:960; }","duration":"115.250728ms","start":"2026-04-22T00:22:58.323381Z","end":"2026-04-22T00:22:58.438631Z","steps":["trace[554270915] 'agreement among raft nodes before linearized reading' (duration: 114.955174ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:22:58.642071Z","caller":"traceutil/trace.go:171","msg":"trace[1963731261] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"195.995097ms","start":"2026-04-22T00:22:58.446045Z","end":"2026-04-22T00:22:58.64204Z","steps":["trace[1963731261] 'process raft request' (duration: 121.150459ms)","trace[1963731261] 'compare' (duration: 74.706221ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:23:26.568742Z","caller":"traceutil/trace.go:171","msg":"trace[163513509] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"159.461229ms","start":"2026-04-22T00:23:26.409245Z","end":"2026-04-22T00:23:26.568706Z","steps":["trace[163513509] 'process raft request' (duration: 159.260357ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:26.571006Z","caller":"traceutil/trace.go:171","msg":"trace[1557939245] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"158.414768ms","start":"2026-04-22T00:23:26.412556Z","end":"2026-04-22T00:23:26.570971Z","steps":["trace[1557939245] 'process raft request' (duration: 158.202606ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:48.937947Z","caller":"traceutil/trace.go:171","msg":"trace[2273657] linearizableReadLoop","detail":"{readStateIndex:1176; appliedIndex:1175; }","duration":"124.403717ms","start":"2026-04-22T00:23:48.813504Z","end":"2026-04-22T00:23:48.937908Z","steps":["trace[2273657] 'read index received' (duration: 71.027006ms)","trace[2273657] 'applied index is now lower than readState.Index' (duration: 53.375281ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:23:48.938106Z","caller":"traceutil/trace.go:171","msg":"trace[130880828] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"127.522092ms","start":"2026-04-22T00:23:48.810572Z","end":"2026-04-22T00:23:48.938094Z","steps":["trace[130880828] 'process raft request' (duration: 73.933417ms)","trace[130880828] 'compare' (duration: 53.251944ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:23:48.9392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.648564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-22T00:23:48.939339Z","caller":"traceutil/trace.go:171","msg":"trace[955776007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1134; }","duration":"125.830035ms","start":"2026-04-22T00:23:48.813469Z","end":"2026-04-22T00:23:48.939299Z","steps":["trace[955776007] 'agreement among raft nodes before linearized reading' (duration: 124.748849ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:49.091989Z","caller":"traceutil/trace.go:171","msg":"trace[495243814] linearizableReadLoop","detail":"{readStateIndex:1178; appliedIndex:1177; }","duration":"107.754909ms","start":"2026-04-22T00:23:48.984207Z","end":"2026-04-22T00:23:49.091962Z","steps":["trace[495243814] 'read index received' (duration: 107.378527ms)","trace[495243814] 'applied index is now lower than readState.Index' (duration: 372.432µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:23:49.092129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.896069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:23:49.092153Z","caller":"traceutil/trace.go:171","msg":"trace[217149343] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1136; }","duration":"108.004506ms","start":"2026-04-22T00:23:48.984141Z","end":"2026-04-22T00:23:49.092146Z","steps":["trace[217149343] 'agreement among raft nodes before linearized reading' (duration: 107.92479ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:49.092311Z","caller":"traceutil/trace.go:171","msg":"trace[893066730] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"139.112225ms","start":"2026-04-22T00:23:48.953151Z","end":"2026-04-22T00:23:49.092263Z","steps":["trace[893066730] 'process raft request' (duration: 138.624914ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:23:51.237553Z","caller":"traceutil/trace.go:171","msg":"trace[2014605709] linearizableReadLoop","detail":"{readStateIndex:1184; appliedIndex:1183; }","duration":"284.32417ms","start":"2026-04-22T00:23:50.953203Z","end":"2026-04-22T00:23:51.237527Z","steps":["trace[2014605709] 'read index received' (duration: 284.099416ms)","trace[2014605709] 'applied index is now lower than readState.Index' (duration: 223.894µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:23:51.237649Z","caller":"traceutil/trace.go:171","msg":"trace[1598706920] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"289.819613ms","start":"2026-04-22T00:23:50.947779Z","end":"2026-04-22T00:23:51.237598Z","steps":["trace[1598706920] 'process raft request' (duration: 289.588739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:51.237815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.581566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-22T00:23:51.237849Z","caller":"traceutil/trace.go:171","msg":"trace[1932740324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1141; }","duration":"284.677522ms","start":"2026-04-22T00:23:50.953162Z","end":"2026-04-22T00:23:51.23784Z","steps":["trace[1932740324] 'agreement among raft nodes before linearized reading' (duration: 284.510572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:51.23785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.399692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:23:51.237883Z","caller":"traceutil/trace.go:171","msg":"trace[617186716] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1141; }","duration":"253.478117ms","start":"2026-04-22T00:23:50.984396Z","end":"2026-04-22T00:23:51.237874Z","steps":["trace[617186716] 'agreement among raft nodes before linearized reading' (duration: 253.404602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:23:51.238112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.257783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-22T00:23:51.238178Z","caller":"traceutil/trace.go:171","msg":"trace[46248317] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1141; }","duration":"141.38481ms","start":"2026-04-22T00:23:51.096776Z","end":"2026-04-22T00:23:51.238161Z","steps":["trace[46248317] 'agreement among raft nodes before linearized reading' (duration: 141.132664ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T00:24:31.658177Z","caller":"traceutil/trace.go:171","msg":"trace[2120440395] linearizableReadLoop","detail":"{readStateIndex:1325; appliedIndex:1324; }","duration":"157.721092ms","start":"2026-04-22T00:24:31.500419Z","end":"2026-04-22T00:24:31.65814Z","steps":["trace[2120440395] 'read index received' (duration: 157.431752ms)","trace[2120440395] 'applied index is now lower than readState.Index' (duration: 287.3µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T00:24:31.658358Z","caller":"traceutil/trace.go:171","msg":"trace[190022614] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"166.555496ms","start":"2026-04-22T00:24:31.491787Z","end":"2026-04-22T00:24:31.658342Z","steps":["trace[190022614] 'process raft request' (duration: 166.154589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:24:31.658824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.418679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-22T00:24:31.658955Z","caller":"traceutil/trace.go:171","msg":"trace[2146882463] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1274; }","duration":"158.604152ms","start":"2026-04-22T00:24:31.500305Z","end":"2026-04-22T00:24:31.658909Z","steps":["trace[2146882463] 'agreement among raft nodes before linearized reading' (duration: 158.276729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:24:53.493876Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959993609239662,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-22T00:24:53.557234Z","caller":"traceutil/trace.go:171","msg":"trace[831371320] linearizableReadLoop","detail":"{readStateIndex:1431; appliedIndex:1430; }","duration":"563.579961ms","start":"2026-04-22T00:24:52.993635Z","end":"2026-04-22T00:24:53.557215Z","steps":["trace[831371320] 'read index received' (duration: 563.430221ms)","trace[831371320] 'applied index is now lower than readState.Index' (duration: 148.69µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T00:24:53.557365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.714031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T00:24:53.557394Z","caller":"traceutil/trace.go:171","msg":"trace[364066669] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1374; }","duration":"563.821767ms","start":"2026-04-22T00:24:52.993565Z","end":"2026-04-22T00:24:53.557387Z","steps":["trace[364066669] 'agreement among raft nodes before linearized reading' (duration: 563.743442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:24:53.557426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:24:52.993537Z","time spent":"563.882012ms","remote":"127.0.0.1:47518","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-22T00:24:53.557597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.651822ms","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-22T00:24:53.557697Z","caller":"traceutil/trace.go:171","msg":"trace[381162573] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1374; }","duration":"338.818724ms","start":"2026-04-22T00:24:53.218856Z","end":"2026-04-22T00:24:53.557674Z","steps":["trace[381162573] 'agreement among raft nodes before linearized reading' (duration: 338.526034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:24:53.557752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:24:53.218825Z","time spent":"338.91874ms","remote":"127.0.0.1:47786","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":"info","ts":"2026-04-22T00:24:53.558133Z","caller":"traceutil/trace.go:171","msg":"trace[1763556356] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"686.326266ms","start":"2026-04-22T00:24:52.870995Z","end":"2026-04-22T00:24:53.557321Z","steps":["trace[1763556356] 'process raft request' (duration: 686.073908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:24:53.558256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:24:52.870965Z","time spent":"687.211187ms","remote":"127.0.0.1:47786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T00:24:53.558244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.229377ms","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-22T00:24:53.558367Z","caller":"traceutil/trace.go:171","msg":"trace[613820803] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1374; }","duration":"397.600442ms","start":"2026-04-22T00:24:53.160738Z","end":"2026-04-22T00:24:53.558338Z","steps":["trace[613820803] 'agreement among raft nodes before linearized reading' (duration: 396.619924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T00:24:53.558442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T00:24:53.160719Z","time spent":"397.707749ms","remote":"127.0.0.1:47786","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\" "}