{"level":"warn","ts":"2026-04-13T17:22:16.562374Z","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-13T17:22:16.562525Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.224: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.224:2380","--initial-cluster=instance=https://199.19.213.224:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.224:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.224: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-13T17:22:16.562639Z","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-13T17:22:16.562659Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.224:2380"]} {"level":"info","ts":"2026-04-13T17:22:16.562703Z","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-13T17:22:16.563672Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.224:2379"]} {"level":"info","ts":"2026-04-13T17:22:16.563785Z","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.224:2380"],"listen-peer-urls":["https://199.19.213.224:2380"],"advertise-client-urls":["https://199.19.213.224:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.224:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.224: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-13T17:22:16.568932Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.867037ms"} {"level":"info","ts":"2026-04-13T17:22:16.579693Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9870e1635c99d2ec","cluster-id":"185398c074a8a76b"} {"level":"info","ts":"2026-04-13T17:22:16.579819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec switched to configuration voters=()"} {"level":"info","ts":"2026-04-13T17:22:16.579885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec became follower at term 0"} {"level":"info","ts":"2026-04-13T17:22:16.579903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9870e1635c99d2ec [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-13T17:22:16.579914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec became follower at term 1"} {"level":"info","ts":"2026-04-13T17:22:16.57996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec switched to configuration voters=(10984527308028236524)"} {"level":"warn","ts":"2026-04-13T17:22:16.589222Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-13T17:22:16.593861Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-13T17:22:16.599963Z","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-13T17:22:16.603582Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9870e1635c99d2ec","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-13T17:22:16.603843Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9870e1635c99d2ec","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-13T17:22:16.603842Z","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-13T17:22:16.604026Z","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-13T17:22:16.604066Z","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-13T17:22:16.604771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec switched to configuration voters=(10984527308028236524)"} {"level":"info","ts":"2026-04-13T17:22:16.604969Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"185398c074a8a76b","local-member-id":"9870e1635c99d2ec","added-peer-id":"9870e1635c99d2ec","added-peer-peer-urls":["https://199.19.213.224:2380"]} {"level":"info","ts":"2026-04-13T17:22:16.607798Z","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-13T17:22:16.60792Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.224:2380"} {"level":"info","ts":"2026-04-13T17:22:16.607984Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.224:2380"} {"level":"info","ts":"2026-04-13T17:22:16.60809Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9870e1635c99d2ec","initial-advertise-peer-urls":["https://199.19.213.224:2380"],"listen-peer-urls":["https://199.19.213.224:2380"],"advertise-client-urls":["https://199.19.213.224:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.224:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-13T17:22:16.608159Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-13T17:22:16.980831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec is starting a new election at term 1"} {"level":"info","ts":"2026-04-13T17:22:16.980934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec became pre-candidate at term 1"} {"level":"info","ts":"2026-04-13T17:22:16.980994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec received MsgPreVoteResp from 9870e1635c99d2ec at term 1"} {"level":"info","ts":"2026-04-13T17:22:16.981022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec became candidate at term 2"} {"level":"info","ts":"2026-04-13T17:22:16.981042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec received MsgVoteResp from 9870e1635c99d2ec at term 2"} {"level":"info","ts":"2026-04-13T17:22:16.981061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec became leader at term 2"} {"level":"info","ts":"2026-04-13T17:22:16.981083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9870e1635c99d2ec elected leader 9870e1635c99d2ec at term 2"} {"level":"info","ts":"2026-04-13T17:22:16.982558Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9870e1635c99d2ec","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.224:2379]}","request-path":"/0/members/9870e1635c99d2ec/attributes","cluster-id":"185398c074a8a76b","publish-timeout":"7s"} {"level":"info","ts":"2026-04-13T17:22:16.985933Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T17:22:16.986142Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:22:16.986472Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T17:22:16.986688Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-13T17:22:16.986766Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-13T17:22:16.992796Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-13T17:22:16.993217Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"185398c074a8a76b","local-member-id":"9870e1635c99d2ec","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:22:16.994256Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:22:16.994323Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.224:2379"} {"level":"info","ts":"2026-04-13T17:22:16.994337Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:22:18.72632Z","caller":"traceutil/trace.go:171","msg":"trace[431433558] linearizableReadLoop","detail":"{readStateIndex:70; appliedIndex:69; }","duration":"147.032102ms","start":"2026-04-13T17:22:18.579248Z","end":"2026-04-13T17:22:18.72628Z","steps":["trace[431433558] 'read index received' (duration: 79.64836ms)","trace[431433558] 'applied index is now lower than readState.Index' (duration: 67.382592ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:22:18.72647Z","caller":"traceutil/trace.go:171","msg":"trace[1450444150] transaction","detail":"{read_only:false; response_revision:66; number_of_response:1; }","duration":"177.078832ms","start":"2026-04-13T17:22:18.549334Z","end":"2026-04-13T17:22:18.726413Z","steps":["trace[1450444150] 'process raft request' (duration: 109.533236ms)","trace[1450444150] 'compare' (duration: 67.224367ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:22:18.726666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.33136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/kube-controller-manager\" ","response":"range_response_count:1 size:1128"} {"level":"info","ts":"2026-04-13T17:22:18.726746Z","caller":"traceutil/trace.go:171","msg":"trace[643589973] range","detail":"{range_begin:/registry/flowschemas/kube-controller-manager; range_end:; response_count:1; response_revision:66; }","duration":"147.534496ms","start":"2026-04-13T17:22:18.579196Z","end":"2026-04-13T17:22:18.72673Z","steps":["trace[643589973] 'agreement among raft nodes before linearized reading' (duration: 147.224998ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:22:19.458735Z","caller":"traceutil/trace.go:171","msg":"trace[337408123] linearizableReadLoop","detail":"{readStateIndex:80; appliedIndex:79; }","duration":"104.695271ms","start":"2026-04-13T17:22:19.354001Z","end":"2026-04-13T17:22:19.458696Z","steps":["trace[337408123] 'read index received' (duration: 44.600979ms)","trace[337408123] 'applied index is now lower than readState.Index' (duration: 60.092652ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:22:19.458921Z","caller":"traceutil/trace.go:171","msg":"trace[1998314744] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"106.436658ms","start":"2026-04-13T17:22:19.352434Z","end":"2026-04-13T17:22:19.458871Z","steps":["trace[1998314744] 'process raft request' (duration: 46.244463ms)","trace[1998314744] 'compare' (duration: 59.841406ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:22:19.459104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.064771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-13T17:22:19.459155Z","caller":"traceutil/trace.go:171","msg":"trace[853451063] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:76; }","duration":"105.147573ms","start":"2026-04-13T17:22:19.353994Z","end":"2026-04-13T17:22:19.459142Z","steps":["trace[853451063] 'agreement among raft nodes before linearized reading' (duration: 104.920757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:22:21.347171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.442241ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:22:21.347407Z","caller":"traceutil/trace.go:171","msg":"trace[1013568319] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"205.079754ms","start":"2026-04-13T17:22:21.142251Z","end":"2026-04-13T17:22:21.34733Z","steps":["trace[1013568319] 'process raft request' (duration: 56.747149ms)","trace[1013568319] 'compare' (duration: 147.225555ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:22:21.46065Z","caller":"traceutil/trace.go:171","msg":"trace[483837750] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"105.237944ms","start":"2026-04-13T17:22:21.355346Z","end":"2026-04-13T17:22:21.460584Z","steps":["trace[483837750] 'process raft request' (duration: 102.211983ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:22:24.755037Z","caller":"traceutil/trace.go:171","msg":"trace[1785137567] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"243.206917ms","start":"2026-04-13T17:22:24.511806Z","end":"2026-04-13T17:22:24.755013Z","steps":["trace[1785137567] 'process raft request' (duration: 243.142546ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:22:24.755336Z","caller":"traceutil/trace.go:171","msg":"trace[780395629] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"249.201462ms","start":"2026-04-13T17:22:24.506011Z","end":"2026-04-13T17:22:24.755212Z","steps":["trace[780395629] 'process raft request' (duration: 191.55031ms)","trace[780395629] 'compare' (duration: 57.223731ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:22:32.534715Z","caller":"traceutil/trace.go:171","msg":"trace[1831581194] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"115.555481ms","start":"2026-04-13T17:22:32.419119Z","end":"2026-04-13T17:22:32.534675Z","steps":["trace[1831581194] 'process raft request' (duration: 115.343906ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:22:35.721803Z","caller":"traceutil/trace.go:171","msg":"trace[708827319] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"168.336139ms","start":"2026-04-13T17:22:35.553433Z","end":"2026-04-13T17:22:35.721769Z","steps":["trace[708827319] 'process raft request' (duration: 168.133584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:22:39.343808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.486561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12669"} {"level":"info","ts":"2026-04-13T17:22:39.343914Z","caller":"traceutil/trace.go:171","msg":"trace[1097024585] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:423; }","duration":"295.648485ms","start":"2026-04-13T17:22:39.048241Z","end":"2026-04-13T17:22:39.343889Z","steps":["trace[1097024585] 'range keys from in-memory index tree' (duration: 295.353309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:22:41.652404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.296173ms","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-13T17:22:41.652518Z","caller":"traceutil/trace.go:171","msg":"trace[1453284641] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:462; }","duration":"297.466907ms","start":"2026-04-13T17:22:41.355028Z","end":"2026-04-13T17:22:41.652495Z","steps":["trace[1453284641] 'range keys from in-memory index tree' (duration: 297.1285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:22:41.652401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.854023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:22:41.652745Z","caller":"traceutil/trace.go:171","msg":"trace[1624863235] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:462; }","duration":"111.252942ms","start":"2026-04-13T17:22:41.541465Z","end":"2026-04-13T17:22:41.652718Z","steps":["trace[1624863235] 'range keys from in-memory index tree' (duration: 110.774542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:22:43.24065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.315429ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-04-13T17:22:43.240991Z","caller":"traceutil/trace.go:171","msg":"trace[1071945370] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"739.409289ms","start":"2026-04-13T17:22:42.501566Z","end":"2026-04-13T17:22:43.240976Z","steps":["trace[1071945370] 'process raft request' (duration: 739.195344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:22:43.241059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:22:42.501553Z","time spent":"739.48037ms","remote":"127.0.0.1:58112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T17:22:43.241156Z","caller":"traceutil/trace.go:171","msg":"trace[193615258] transaction","detail":"{read_only:false; number_of_response:0; response_revision:480; }","duration":"743.454746ms","start":"2026-04-13T17:22:42.497671Z","end":"2026-04-13T17:22:43.241126Z","steps":["trace[193615258] 'process raft request' (duration: 352.567844ms)","trace[193615258] 'compare' (duration: 390.273518ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:22:43.241299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:22:42.497655Z","time spent":"743.611039ms","remote":"127.0.0.1:58636","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-13T17:22:43.242172Z","caller":"traceutil/trace.go:171","msg":"trace[808418842] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"735.165827ms","start":"2026-04-13T17:22:42.506996Z","end":"2026-04-13T17:22:43.242161Z","steps":["trace[808418842] 'process raft request' (duration: 735.011153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:22:43.242427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:22:42.506982Z","time spent":"735.220359ms","remote":"127.0.0.1:58112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:22:43.242513Z","caller":"traceutil/trace.go:171","msg":"trace[1088504079] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"733.345137ms","start":"2026-04-13T17:22:42.509147Z","end":"2026-04-13T17:22:43.242492Z","steps":["trace[1088504079] 'process raft request' (duration: 732.940728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:22:43.242636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:22:42.509139Z","time spent":"733.42786ms","remote":"127.0.0.1:58112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:22:43.242767Z","caller":"traceutil/trace.go:171","msg":"trace[986297106] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"730.502026ms","start":"2026-04-13T17:22:42.512256Z","end":"2026-04-13T17:22:43.242758Z","steps":["trace[986297106] 'process raft request' (duration: 730.124329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:22:43.242812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:22:42.512239Z","time spent":"730.555467ms","remote":"127.0.0.1:58112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T17:22:43.242881Z","caller":"traceutil/trace.go:171","msg":"trace[656768620] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"730.281602ms","start":"2026-04-13T17:22:42.512593Z","end":"2026-04-13T17:22:43.242875Z","steps":["trace[656768620] 'process raft request' (duration: 729.949765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:22:43.242914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:22:42.512583Z","time spent":"730.317252ms","remote":"127.0.0.1:58636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T17:22:43.24292Z","caller":"traceutil/trace.go:171","msg":"trace[1628869832] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"723.658539ms","start":"2026-04-13T17:22:42.519248Z","end":"2026-04-13T17:22:43.242906Z","steps":["trace[1628869832] 'process raft request' (duration: 723.380763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:22:43.243117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:22:42.519234Z","time spent":"723.840362ms","remote":"127.0.0.1:58112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:22:43.243338Z","caller":"traceutil/trace.go:171","msg":"trace[1706402656] linearizableReadLoop","detail":"{readStateIndex:505; appliedIndex:497; }","duration":"698.25321ms","start":"2026-04-13T17:22:42.545015Z","end":"2026-04-13T17:22:43.243268Z","steps":["trace[1706402656] 'read index received' (duration: 305.307905ms)","trace[1706402656] 'applied index is now lower than readState.Index' (duration: 392.943895ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:22:43.2434Z","caller":"traceutil/trace.go:171","msg":"trace[776765461] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"421.638485ms","start":"2026-04-13T17:22:42.821754Z","end":"2026-04-13T17:22:43.243392Z","steps":["trace[776765461] 'process raft request' (duration: 421.42652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:22:43.243441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:22:42.821729Z","time spent":"421.689375ms","remote":"127.0.0.1:58340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:22:43.243549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.516296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:22:43.243613Z","caller":"traceutil/trace.go:171","msg":"trace[1190176916] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:489; }","duration":"698.618518ms","start":"2026-04-13T17:22:42.544982Z","end":"2026-04-13T17:22:43.243601Z","steps":["trace[1190176916] 'agreement among raft nodes before linearized reading' (duration: 698.518825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:22:43.243682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:22:42.544966Z","time spent":"698.70695ms","remote":"127.0.0.1:58070","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-13T17:22:43.243735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.209688ms","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-13T17:22:43.24378Z","caller":"traceutil/trace.go:171","msg":"trace[918969219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:489; }","duration":"576.27835ms","start":"2026-04-13T17:22:42.667489Z","end":"2026-04-13T17:22:43.243767Z","steps":["trace[918969219] 'agreement among raft nodes before linearized reading' (duration: 576.149198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:22:43.243817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:22:42.667472Z","time spent":"576.335521ms","remote":"127.0.0.1:58340","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-13T17:22:43.243367Z","caller":"traceutil/trace.go:171","msg":"trace[644297154] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"579.340545ms","start":"2026-04-13T17:22:42.664014Z","end":"2026-04-13T17:22:43.243355Z","steps":["trace[644297154] 'process raft request' (duration: 578.940767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:22:43.24399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:22:42.663993Z","time spent":"579.965569ms","remote":"127.0.0.1:58112","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-13T17:22:43.244179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.735395ms","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-13T17:22:43.244245Z","caller":"traceutil/trace.go:171","msg":"trace[83463371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:489; }","duration":"375.803256ms","start":"2026-04-13T17:22:42.868418Z","end":"2026-04-13T17:22:43.244222Z","steps":["trace[83463371] 'agreement among raft nodes before linearized reading' (duration: 375.655923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:22:43.244276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:22:42.868405Z","time spent":"375.864087ms","remote":"127.0.0.1:58340","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":"info","ts":"2026-04-13T17:22:43.243236Z","caller":"traceutil/trace.go:171","msg":"trace[36396661] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"720.081251ms","start":"2026-04-13T17:22:42.523137Z","end":"2026-04-13T17:22:43.243219Z","steps":["trace[36396661] 'process raft request' (duration: 719.652592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:22:43.245665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:22:42.523123Z","time spent":"722.504443ms","remote":"127.0.0.1:58112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:22:44.764866Z","caller":"traceutil/trace.go:171","msg":"trace[737978029] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"203.106748ms","start":"2026-04-13T17:22:44.561736Z","end":"2026-04-13T17:22:44.764843Z","steps":["trace[737978029] 'process raft request' (duration: 202.70977ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:22:44.771799Z","caller":"traceutil/trace.go:171","msg":"trace[1948316628] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:558; }","duration":"181.632393ms","start":"2026-04-13T17:22:44.590147Z","end":"2026-04-13T17:22:44.771779Z","steps":["trace[1948316628] 'read index received' (duration: 173.93711ms)","trace[1948316628] 'applied index is now lower than readState.Index' (duration: 7.694663ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:22:44.771835Z","caller":"traceutil/trace.go:171","msg":"trace[2062574380] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"184.133555ms","start":"2026-04-13T17:22:44.587681Z","end":"2026-04-13T17:22:44.771815Z","steps":["trace[2062574380] 'process raft request' (duration: 183.918851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:22:44.772062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.903029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:570105"} {"level":"info","ts":"2026-04-13T17:22:44.772093Z","caller":"traceutil/trace.go:171","msg":"trace[678738349] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:542; }","duration":"182.004481ms","start":"2026-04-13T17:22:44.59008Z","end":"2026-04-13T17:22:44.772084Z","steps":["trace[678738349] 'agreement among raft nodes before linearized reading' (duration: 181.769336ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:22:46.843112Z","caller":"traceutil/trace.go:171","msg":"trace[1688556636] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"246.699821ms","start":"2026-04-13T17:22:46.596378Z","end":"2026-04-13T17:22:46.843078Z","steps":["trace[1688556636] 'process raft request' (duration: 245.529248ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:22:49.851912Z","caller":"traceutil/trace.go:171","msg":"trace[1596906736] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"116.471811ms","start":"2026-04-13T17:22:49.735393Z","end":"2026-04-13T17:22:49.851865Z","steps":["trace[1596906736] 'read index received' (duration: 116.225366ms)","trace[1596906736] 'applied index is now lower than readState.Index' (duration: 245.355µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:22:49.852304Z","caller":"traceutil/trace.go:171","msg":"trace[2084129827] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"247.398895ms","start":"2026-04-13T17:22:49.604896Z","end":"2026-04-13T17:22:49.852295Z","steps":["trace[2084129827] 'process raft request' (duration: 246.812713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:22:49.852592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.176926ms","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-13T17:22:49.852627Z","caller":"traceutil/trace.go:171","msg":"trace[286222800] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:573; }","duration":"117.274948ms","start":"2026-04-13T17:22:49.735342Z","end":"2026-04-13T17:22:49.852617Z","steps":["trace[286222800] 'agreement among raft nodes before linearized reading' (duration: 117.127335ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:22:50.085342Z","caller":"traceutil/trace.go:171","msg":"trace[1057225050] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"220.2029ms","start":"2026-04-13T17:22:49.8651Z","end":"2026-04-13T17:22:50.085303Z","steps":["trace[1057225050] 'process raft request' (duration: 210.998636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:22:50.638292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.172076ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:22:50.638419Z","caller":"traceutil/trace.go:171","msg":"trace[1644135158] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:574; }","duration":"484.315669ms","start":"2026-04-13T17:22:50.154084Z","end":"2026-04-13T17:22:50.6384Z","steps":["trace[1644135158] 'range keys from in-memory index tree' (duration: 484.156906ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:22:50.638931Z","caller":"traceutil/trace.go:171","msg":"trace[1954897715] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"773.799154ms","start":"2026-04-13T17:22:49.865101Z","end":"2026-04-13T17:22:50.6389Z","steps":["trace[1954897715] 'process raft request' (duration: 728.876956ms)","trace[1954897715] 'compare' (duration: 44.203895ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:22:50.639052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:22:49.86506Z","time spent":"773.921567ms","remote":"127.0.0.1:58340","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-13T17:22:50.639403Z","caller":"traceutil/trace.go:171","msg":"trace[1926239291] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:593; }","duration":"143.136184ms","start":"2026-04-13T17:22:50.496241Z","end":"2026-04-13T17:22:50.639378Z","steps":["trace[1926239291] 'read index received' (duration: 97.565382ms)","trace[1926239291] 'applied index is now lower than readState.Index' (duration: 45.570072ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:22:50.639704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.442179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.224\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-13T17:22:50.639789Z","caller":"traceutil/trace.go:171","msg":"trace[1525913411] range","detail":"{range_begin:/registry/masterleases/199.19.213.224; range_end:; response_count:1; response_revision:576; }","duration":"143.595993ms","start":"2026-04-13T17:22:50.496171Z","end":"2026-04-13T17:22:50.639767Z","steps":["trace[1525913411] 'agreement among raft nodes before linearized reading' (duration: 143.381089ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:22:50.640049Z","caller":"traceutil/trace.go:171","msg":"trace[923427796] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"769.449266ms","start":"2026-04-13T17:22:49.870582Z","end":"2026-04-13T17:22:50.640031Z","steps":["trace[923427796] 'process raft request' (duration: 768.406235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:22:50.640179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:22:49.870552Z","time spent":"769.540688ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:22:51.787602Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198696049331636417,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T17:22:52.287793Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198696049331636417,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T17:22:52.375641Z","caller":"traceutil/trace.go:171","msg":"trace[1505307777] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"1.089067682s","start":"2026-04-13T17:22:51.286546Z","end":"2026-04-13T17:22:52.375614Z","steps":["trace[1505307777] 'read index received' (duration: 1.088109683s)","trace[1505307777] 'applied index is now lower than readState.Index' (duration: 957.179µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:22:52.376109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.08952564s","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-13T17:22:52.37617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.680739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:22:52.376235Z","caller":"traceutil/trace.go:171","msg":"trace[1243869296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:579; }","duration":"1.089707814s","start":"2026-04-13T17:22:51.286504Z","end":"2026-04-13T17:22:52.376212Z","steps":["trace[1243869296] 'agreement among raft nodes before linearized reading' (duration: 1.089345117s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:22:52.376258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.987858ms","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-13T17:22:52.376227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.080953952s","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-13T17:22:52.376313Z","caller":"traceutil/trace.go:171","msg":"trace[1816350882] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:579; }","duration":"287.09879ms","start":"2026-04-13T17:22:52.089197Z","end":"2026-04-13T17:22:52.376296Z","steps":["trace[1816350882] 'agreement among raft nodes before linearized reading' (duration: 286.904946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:22:52.376313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:22:51.286481Z","time spent":"1.089816766s","remote":"127.0.0.1:58340","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-13T17:22:52.376235Z","caller":"traceutil/trace.go:171","msg":"trace[1688189599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"831.781981ms","start":"2026-04-13T17:22:51.544435Z","end":"2026-04-13T17:22:52.376217Z","steps":["trace[1688189599] 'agreement among raft nodes before linearized reading' (duration: 831.682279ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:22:52.376338Z","caller":"traceutil/trace.go:171","msg":"trace[214046235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:579; }","duration":"1.081112974s","start":"2026-04-13T17:22:51.2952Z","end":"2026-04-13T17:22:52.376313Z","steps":["trace[214046235] 'agreement among raft nodes before linearized reading' (duration: 1.080777538s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:22:52.376382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:22:51.544411Z","time spent":"831.959155ms","remote":"127.0.0.1:58070","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-13T17:22:52.376396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:22:51.295189Z","time spent":"1.081197676s","remote":"127.0.0.1:58340","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":"info","ts":"2026-04-13T17:22:52.376317Z","caller":"traceutil/trace.go:171","msg":"trace[251612278] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"1.219435166s","start":"2026-04-13T17:22:51.156622Z","end":"2026-04-13T17:22:52.376057Z","steps":["trace[251612278] 'process raft request' (duration: 1.218371905s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:22:52.377101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:22:51.156601Z","time spent":"1.220414305s","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:22:54.130794Z","caller":"traceutil/trace.go:171","msg":"trace[422201347] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"259.614881ms","start":"2026-04-13T17:22:53.871156Z","end":"2026-04-13T17:22:54.130771Z","steps":["trace[422201347] 'process raft request' (duration: 259.260535ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:23:00.834582Z","caller":"traceutil/trace.go:171","msg":"trace[510273070] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"107.07679ms","start":"2026-04-13T17:23:00.727474Z","end":"2026-04-13T17:23:00.834551Z","steps":["trace[510273070] 'process raft request' (duration: 106.911267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:23:08.233874Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198696049331636811,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T17:23:08.308186Z","caller":"traceutil/trace.go:171","msg":"trace[1587277176] linearizableReadLoop","detail":"{readStateIndex:769; appliedIndex:768; }","duration":"575.180833ms","start":"2026-04-13T17:23:07.732975Z","end":"2026-04-13T17:23:08.308156Z","steps":["trace[1587277176] 'read index received' (duration: 574.897319ms)","trace[1587277176] 'applied index is now lower than readState.Index' (duration: 281.964µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:23:08.308599Z","caller":"traceutil/trace.go:171","msg":"trace[1221134739] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"585.890848ms","start":"2026-04-13T17:23:07.722667Z","end":"2026-04-13T17:23:08.308558Z","steps":["trace[1221134739] 'process raft request' (duration: 585.306938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:23:08.308723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.718542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-13T17:23:08.30879Z","caller":"traceutil/trace.go:171","msg":"trace[1665752174] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:745; }","duration":"575.845235ms","start":"2026-04-13T17:23:07.732924Z","end":"2026-04-13T17:23:08.30877Z","steps":["trace[1665752174] 'agreement among raft nodes before linearized reading' (duration: 575.353036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:23:08.308803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:23:07.722631Z","time spent":"586.05602ms","remote":"127.0.0.1:58310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:23:08.308845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:23:07.732902Z","time spent":"575.929496ms","remote":"127.0.0.1:58340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-13T17:24:48.751547Z","caller":"traceutil/trace.go:171","msg":"trace[1749259066] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"140.493611ms","start":"2026-04-13T17:24:48.611018Z","end":"2026-04-13T17:24:48.751512Z","steps":["trace[1749259066] 'process raft request' (duration: 140.174098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:24:54.055893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.059525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:24:54.055987Z","caller":"traceutil/trace.go:171","msg":"trace[1232043013] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1462; }","duration":"125.223478ms","start":"2026-04-13T17:24:53.930745Z","end":"2026-04-13T17:24:54.055969Z","steps":["trace[1232043013] 'count revisions from in-memory index tree' (duration: 124.950234ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:24:54.306211Z","caller":"traceutil/trace.go:171","msg":"trace[699111250] transaction","detail":"{read_only:false; response_revision:1463; number_of_response:1; }","duration":"158.356567ms","start":"2026-04-13T17:24:54.147821Z","end":"2026-04-13T17:24:54.306178Z","steps":["trace[699111250] 'process raft request' (duration: 158.131345ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:09.503172Z","caller":"traceutil/trace.go:171","msg":"trace[1601709654] linearizableReadLoop","detail":"{readStateIndex:1703; appliedIndex:1702; }","duration":"229.350459ms","start":"2026-04-13T17:25:09.273789Z","end":"2026-04-13T17:25:09.503139Z","steps":["trace[1601709654] 'read index received' (duration: 229.168817ms)","trace[1601709654] 'applied index is now lower than readState.Index' (duration: 180.082µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:25:09.503276Z","caller":"traceutil/trace.go:171","msg":"trace[1782936188] transaction","detail":"{read_only:false; response_revision:1650; number_of_response:1; }","duration":"246.386003ms","start":"2026-04-13T17:25:09.256834Z","end":"2026-04-13T17:25:09.50322Z","steps":["trace[1782936188] 'process raft request' (duration: 246.09151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:09.503479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.662963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-13T17:25:09.503521Z","caller":"traceutil/trace.go:171","msg":"trace[1465009239] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1650; }","duration":"229.761893ms","start":"2026-04-13T17:25:09.273748Z","end":"2026-04-13T17:25:09.50351Z","steps":["trace[1465009239] 'agreement among raft nodes before linearized reading' (duration: 229.563481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:09.508317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.46702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:25:09.508429Z","caller":"traceutil/trace.go:171","msg":"trace[2077283047] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1651; }","duration":"105.652621ms","start":"2026-04-13T17:25:09.402749Z","end":"2026-04-13T17:25:09.508401Z","steps":["trace[2077283047] 'agreement among raft nodes before linearized reading' (duration: 105.446639ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:09.655305Z","caller":"traceutil/trace.go:171","msg":"trace[1790557769] linearizableReadLoop","detail":"{readStateIndex:1708; appliedIndex:1707; }","duration":"112.283626ms","start":"2026-04-13T17:25:09.542985Z","end":"2026-04-13T17:25:09.655268Z","steps":["trace[1790557769] 'read index received' (duration: 111.066144ms)","trace[1790557769] 'applied index is now lower than readState.Index' (duration: 1.215592ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:25:09.655536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.523918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:25:09.655598Z","caller":"traceutil/trace.go:171","msg":"trace[824854184] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1655; }","duration":"112.647229ms","start":"2026-04-13T17:25:09.542931Z","end":"2026-04-13T17:25:09.655579Z","steps":["trace[824854184] 'agreement among raft nodes before linearized reading' (duration: 112.480237ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:09.65562Z","caller":"traceutil/trace.go:171","msg":"trace[234567134] transaction","detail":"{read_only:false; response_revision:1655; number_of_response:1; }","duration":"138.46824ms","start":"2026-04-13T17:25:09.517075Z","end":"2026-04-13T17:25:09.655543Z","steps":["trace[234567134] 'process raft request' (duration: 137.181457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:10.319684Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198696049331638693,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T17:25:10.550054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.632979ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:25:10.550188Z","caller":"traceutil/trace.go:171","msg":"trace[644471736] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1655; }","duration":"395.780851ms","start":"2026-04-13T17:25:10.154387Z","end":"2026-04-13T17:25:10.550168Z","steps":["trace[644471736] 'range keys from in-memory index tree' (duration: 395.612919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:10.550197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.830507ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:25:10.550292Z","caller":"traceutil/trace.go:171","msg":"trace[1631616020] transaction","detail":"{read_only:false; response_revision:1656; number_of_response:1; }","duration":"887.456003ms","start":"2026-04-13T17:25:09.662819Z","end":"2026-04-13T17:25:10.550275Z","steps":["trace[1631616020] 'process raft request' (duration: 682.465684ms)","trace[1631616020] 'compare' (duration: 204.642976ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:25:10.550337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:09.662784Z","time spent":"887.532374ms","remote":"127.0.0.1:58348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1733,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:25:10.731359Z","caller":"traceutil/trace.go:171","msg":"trace[794947953] transaction","detail":"{read_only:false; response_revision:1657; number_of_response:1; }","duration":"1.068025895s","start":"2026-04-13T17:25:09.663293Z","end":"2026-04-13T17:25:10.731319Z","steps":["trace[794947953] 'process raft request' (duration: 1.057760596s)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:10.731278Z","caller":"traceutil/trace.go:171","msg":"trace[1156009522] linearizableReadLoop","detail":"{readStateIndex:1711; appliedIndex:1708; }","duration":"912.053896ms","start":"2026-04-13T17:25:09.819196Z","end":"2026-04-13T17:25:10.73125Z","steps":["trace[1156009522] 'read index received' (duration: 526.080181ms)","trace[1156009522] 'applied index is now lower than readState.Index' (duration: 385.972735ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:25:10.731685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.480532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:25:10.731735Z","caller":"traceutil/trace.go:171","msg":"trace[1208004808] range","detail":"{range_begin:/registry/leases/ingress-nginx/ingress-nginx-leader; range_end:; response_count:0; response_revision:1658; }","duration":"912.599353ms","start":"2026-04-13T17:25:09.819124Z","end":"2026-04-13T17:25:10.731723Z","steps":["trace[1208004808] 'agreement among raft nodes before linearized reading' (duration: 912.489672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:10.731768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:09.819015Z","time spent":"912.745294ms","remote":"127.0.0.1:58340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" "} {"level":"warn","ts":"2026-04-13T17:25:10.732293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:09.663219Z","time spent":"1.068237388s","remote":"127.0.0.1:58234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1165,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:25:10.732533Z","caller":"traceutil/trace.go:171","msg":"trace[1099693510] transaction","detail":"{read_only:false; response_revision:1658; number_of_response:1; }","duration":"1.067997515s","start":"2026-04-13T17:25:09.664456Z","end":"2026-04-13T17:25:10.732453Z","steps":["trace[1099693510] 'process raft request' (duration: 1.066734483s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:10.732828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:09.664444Z","time spent":"1.068220468s","remote":"127.0.0.1:58572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4247,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:25:11.232455Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198696049331638698,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T17:25:11.391538Z","caller":"traceutil/trace.go:171","msg":"trace[225880963] transaction","detail":"{read_only:false; response_revision:1659; number_of_response:1; }","duration":"831.318987ms","start":"2026-04-13T17:25:10.560188Z","end":"2026-04-13T17:25:11.391507Z","steps":["trace[225880963] 'process raft request' (duration: 804.794852ms)","trace[225880963] 'compare' (duration: 26.189852ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:25:11.391702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:10.560159Z","time spent":"831.444738ms","remote":"127.0.0.1:58142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":750,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T17:25:11.439142Z","caller":"traceutil/trace.go:171","msg":"trace[2090779161] linearizableReadLoop","detail":"{readStateIndex:1714; appliedIndex:1711; }","duration":"707.43365ms","start":"2026-04-13T17:25:10.731679Z","end":"2026-04-13T17:25:11.439113Z","steps":["trace[2090779161] 'read index received' (duration: 633.336526ms)","trace[2090779161] 'applied index is now lower than readState.Index' (duration: 74.096034ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:25:11.439209Z","caller":"traceutil/trace.go:171","msg":"trace[632235834] transaction","detail":"{read_only:false; response_revision:1661; number_of_response:1; }","duration":"698.791597ms","start":"2026-04-13T17:25:10.740395Z","end":"2026-04-13T17:25:11.439187Z","steps":["trace[632235834] 'process raft request' (duration: 698.629895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:11.439349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"960.041439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-13T17:25:11.439356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:10.740382Z","time spent":"698.877807ms","remote":"127.0.0.1:58556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4479,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:25:11.439396Z","caller":"traceutil/trace.go:171","msg":"trace[1015528589] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1661; }","duration":"960.131119ms","start":"2026-04-13T17:25:10.479255Z","end":"2026-04-13T17:25:11.439386Z","steps":["trace[1015528589] 'agreement among raft nodes before linearized reading' (duration: 960.011048ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:11.439406Z","caller":"traceutil/trace.go:171","msg":"trace[1704081494] transaction","detail":"{read_only:false; response_revision:1660; number_of_response:1; }","duration":"702.532363ms","start":"2026-04-13T17:25:10.736858Z","end":"2026-04-13T17:25:11.439391Z","steps":["trace[1704081494] 'process raft request' (duration: 702.036419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:11.439437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:10.479233Z","time spent":"960.19479ms","remote":"127.0.0.1:58348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":11,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-04-13T17:25:11.43948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:10.73683Z","time spent":"702.608834ms","remote":"127.0.0.1:58340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T17:25:11.439515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.642103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.224\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-13T17:25:11.439668Z","caller":"traceutil/trace.go:171","msg":"trace[1860314581] range","detail":"{range_begin:/registry/masterleases/199.19.213.224; range_end:; response_count:1; response_revision:1661; }","duration":"934.833195ms","start":"2026-04-13T17:25:10.504803Z","end":"2026-04-13T17:25:11.439636Z","steps":["trace[1860314581] 'agreement among raft nodes before linearized reading' (duration: 934.398161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:11.439747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:10.504789Z","time spent":"934.934585ms","remote":"127.0.0.1:58100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.224\" "} {"level":"warn","ts":"2026-04-13T17:25:11.43985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.618362476s","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-13T17:25:11.439914Z","caller":"traceutil/trace.go:171","msg":"trace[1210666533] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1661; }","duration":"1.618428287s","start":"2026-04-13T17:25:09.821471Z","end":"2026-04-13T17:25:11.4399Z","steps":["trace[1210666533] 'agreement among raft nodes before linearized reading' (duration: 1.618208684s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:11.439959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:09.821384Z","time spent":"1.618566568s","remote":"127.0.0.1:58340","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-13T17:25:11.439342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.162632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:25:11.440034Z","caller":"traceutil/trace.go:171","msg":"trace[1153017652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1661; }","duration":"895.95638ms","start":"2026-04-13T17:25:10.544064Z","end":"2026-04-13T17:25:11.44002Z","steps":["trace[1153017652] 'agreement among raft nodes before linearized reading' (duration: 895.187292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:11.440053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.358669ms","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":"warn","ts":"2026-04-13T17:25:11.44009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:10.544042Z","time spent":"896.036651ms","remote":"127.0.0.1:58070","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-13T17:25:11.440098Z","caller":"traceutil/trace.go:171","msg":"trace[272693972] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1661; }","duration":"313.45107ms","start":"2026-04-13T17:25:11.126634Z","end":"2026-04-13T17:25:11.440085Z","steps":["trace[272693972] 'agreement among raft nodes before linearized reading' (duration: 313.331719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:11.44015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:11.126606Z","time spent":"313.52871ms","remote":"127.0.0.1:58340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-13T17:25:25.76626Z","caller":"traceutil/trace.go:171","msg":"trace[1014593056] linearizableReadLoop","detail":"{readStateIndex:2044; appliedIndex:2043; }","duration":"221.627591ms","start":"2026-04-13T17:25:25.544602Z","end":"2026-04-13T17:25:25.76623Z","steps":["trace[1014593056] 'read index received' (duration: 221.5058ms)","trace[1014593056] 'applied index is now lower than readState.Index' (duration: 120.591µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:25:25.766389Z","caller":"traceutil/trace.go:171","msg":"trace[403131033] transaction","detail":"{read_only:false; response_revision:1964; number_of_response:1; }","duration":"222.292226ms","start":"2026-04-13T17:25:25.544085Z","end":"2026-04-13T17:25:25.766377Z","steps":["trace[403131033] 'process raft request' (duration: 221.905443ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:25.766521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.880113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:25:25.76668Z","caller":"traceutil/trace.go:171","msg":"trace[1358780331] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1964; }","duration":"222.064274ms","start":"2026-04-13T17:25:25.544583Z","end":"2026-04-13T17:25:25.766647Z","steps":["trace[1358780331] 'agreement among raft nodes before linearized reading' (duration: 221.816532ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:25.768988Z","caller":"traceutil/trace.go:171","msg":"trace[605384940] transaction","detail":"{read_only:false; response_revision:1965; number_of_response:1; }","duration":"221.585901ms","start":"2026-04-13T17:25:25.547378Z","end":"2026-04-13T17:25:25.768964Z","steps":["trace[605384940] 'process raft request' (duration: 221.406449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:25.769045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.382082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:25:25.769082Z","caller":"traceutil/trace.go:171","msg":"trace[1954681034] transaction","detail":"{read_only:false; response_revision:1966; number_of_response:1; }","duration":"184.34963ms","start":"2026-04-13T17:25:25.584709Z","end":"2026-04-13T17:25:25.769059Z","steps":["trace[1954681034] 'process raft request' (duration: 184.209049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:25.769176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.485151ms","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-13T17:25:25.769184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.391693ms","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-13T17:25:25.769097Z","caller":"traceutil/trace.go:171","msg":"trace[909375228] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1966; }","duration":"196.485555ms","start":"2026-04-13T17:25:25.572598Z","end":"2026-04-13T17:25:25.769084Z","steps":["trace[909375228] 'agreement among raft nodes before linearized reading' (duration: 196.352293ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:25.769249Z","caller":"traceutil/trace.go:171","msg":"trace[1485288182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1966; }","duration":"152.566822ms","start":"2026-04-13T17:25:25.616666Z","end":"2026-04-13T17:25:25.769233Z","steps":["trace[1485288182] 'agreement among raft nodes before linearized reading' (duration: 152.39825ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:25.769264Z","caller":"traceutil/trace.go:171","msg":"trace[1303940955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1966; }","duration":"147.524384ms","start":"2026-04-13T17:25:25.621723Z","end":"2026-04-13T17:25:25.769247Z","steps":["trace[1303940955] 'agreement among raft nodes before linearized reading' (duration: 147.289072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:25.769328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.032535ms","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-13T17:25:25.769397Z","caller":"traceutil/trace.go:171","msg":"trace[1669953770] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1966; }","duration":"172.104215ms","start":"2026-04-13T17:25:25.597273Z","end":"2026-04-13T17:25:25.769377Z","steps":["trace[1669953770] 'agreement among raft nodes before linearized reading' (duration: 171.849683ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:36.036603Z","caller":"traceutil/trace.go:171","msg":"trace[1622984118] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"202.788712ms","start":"2026-04-13T17:25:35.83348Z","end":"2026-04-13T17:25:36.036269Z","steps":["trace[1622984118] 'process raft request' (duration: 202.49694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:37.164674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.228232ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-13T17:25:37.164989Z","caller":"traceutil/trace.go:171","msg":"trace[893297737] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"1.179065911s","start":"2026-04-13T17:25:35.985895Z","end":"2026-04-13T17:25:37.164961Z","steps":["trace[893297737] 'process raft request' (duration: 1.17889401s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:37.165145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:35.985875Z","time spent":"1.179165452s","remote":"127.0.0.1:58340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:25:37.164985Z","caller":"traceutil/trace.go:171","msg":"trace[1814604544] linearizableReadLoop","detail":"{readStateIndex:2278; appliedIndex:2276; }","duration":"1.32095322s","start":"2026-04-13T17:25:35.84401Z","end":"2026-04-13T17:25:37.164963Z","steps":["trace[1814604544] 'read index received' (duration: 191.676528ms)","trace[1814604544] 'applied index is now lower than readState.Index' (duration: 1.129275922s)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:25:37.165342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.321313473s","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-13T17:25:37.165382Z","caller":"traceutil/trace.go:171","msg":"trace[1526280980] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2191; }","duration":"1.321396515s","start":"2026-04-13T17:25:35.843974Z","end":"2026-04-13T17:25:37.165371Z","steps":["trace[1526280980] 'agreement among raft nodes before linearized reading' (duration: 1.321041802s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:37.165416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:35.843953Z","time spent":"1.321455005s","remote":"127.0.0.1:58340","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-13T17:25:37.165488Z","caller":"traceutil/trace.go:171","msg":"trace[2098360322] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"1.052327121s","start":"2026-04-13T17:25:36.113132Z","end":"2026-04-13T17:25:37.165459Z","steps":["trace[2098360322] 'process raft request' (duration: 1.051786927s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:37.165676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:36.113104Z","time spent":"1.052459422s","remote":"127.0.0.1:58340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:25:37.166036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.320488756s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-13T17:25:37.16607Z","caller":"traceutil/trace.go:171","msg":"trace[348282061] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2193; }","duration":"1.320556838s","start":"2026-04-13T17:25:35.845503Z","end":"2026-04-13T17:25:37.16606Z","steps":["trace[348282061] 'agreement among raft nodes before linearized reading' (duration: 1.320414746s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:37.166096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:35.845491Z","time spent":"1.320600428s","remote":"127.0.0.1:58340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-13T17:25:37.166154Z","caller":"traceutil/trace.go:171","msg":"trace[537231783] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"489.662563ms","start":"2026-04-13T17:25:36.676459Z","end":"2026-04-13T17:25:37.166121Z","steps":["trace[537231783] 'process raft request' (duration: 489.409491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:37.166203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.218438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-04-13T17:25:37.166272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.209765404s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0520ec5e-9227-4ee4-9708-4ec29d364a85\" ","response":"range_response_count:1 size:3617"} {"level":"info","ts":"2026-04-13T17:25:37.166283Z","caller":"traceutil/trace.go:171","msg":"trace[1329793623] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2193; }","duration":"774.335818ms","start":"2026-04-13T17:25:36.391927Z","end":"2026-04-13T17:25:37.166262Z","steps":["trace[1329793623] 'agreement among raft nodes before linearized reading' (duration: 774.091116ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:37.1663Z","caller":"traceutil/trace.go:171","msg":"trace[467063145] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0520ec5e-9227-4ee4-9708-4ec29d364a85; range_end:; response_count:1; response_revision:2193; }","duration":"1.209830835s","start":"2026-04-13T17:25:35.956459Z","end":"2026-04-13T17:25:37.16629Z","steps":["trace[467063145] 'agreement among raft nodes before linearized reading' (duration: 1.209758704s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:37.166325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:35.956448Z","time spent":"1.209872485s","remote":"127.0.0.1:58240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3640,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0520ec5e-9227-4ee4-9708-4ec29d364a85\" "} {"level":"warn","ts":"2026-04-13T17:25:37.166337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:36.391907Z","time spent":"774.420389ms","remote":"127.0.0.1:58340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-04-13T17:25:37.16635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:36.676429Z","time spent":"489.823425ms","remote":"127.0.0.1:58340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:25:37.166642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.937062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:25:37.166697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.23961658s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:10 size:3673"} {"level":"info","ts":"2026-04-13T17:25:37.166728Z","caller":"traceutil/trace.go:171","msg":"trace[645393024] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:10; response_revision:2193; }","duration":"1.23968363s","start":"2026-04-13T17:25:35.927037Z","end":"2026-04-13T17:25:37.166721Z","steps":["trace[645393024] 'agreement among raft nodes before linearized reading' (duration: 1.239375368s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:37.166814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.611891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0520ec5e-9227-4ee4-9708-4ec29d364a85\" ","response":"range_response_count:1 size:3617"} {"level":"info","ts":"2026-04-13T17:25:37.166852Z","caller":"traceutil/trace.go:171","msg":"trace[815267075] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0520ec5e-9227-4ee4-9708-4ec29d364a85; range_end:; response_count:1; response_revision:2193; }","duration":"761.686022ms","start":"2026-04-13T17:25:36.405153Z","end":"2026-04-13T17:25:37.166839Z","steps":["trace[815267075] 'agreement among raft nodes before linearized reading' (duration: 761.567701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:37.166882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:36.405132Z","time spent":"761.743653ms","remote":"127.0.0.1:58240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3640,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0520ec5e-9227-4ee4-9708-4ec29d364a85\" "} {"level":"warn","ts":"2026-04-13T17:25:37.167773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.316865523s","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-13T17:25:37.167828Z","caller":"traceutil/trace.go:171","msg":"trace[1343197358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2193; }","duration":"1.316961705s","start":"2026-04-13T17:25:35.85085Z","end":"2026-04-13T17:25:37.167811Z","steps":["trace[1343197358] 'agreement among raft nodes before linearized reading' (duration: 1.316840904s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:37.167896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:35.850825Z","time spent":"1.317056085s","remote":"127.0.0.1:58340","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-13T17:25:37.167961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.295347255s","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-13T17:25:37.16802Z","caller":"traceutil/trace.go:171","msg":"trace[1831590807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2193; }","duration":"1.295427355s","start":"2026-04-13T17:25:35.872576Z","end":"2026-04-13T17:25:37.168003Z","steps":["trace[1831590807] 'agreement among raft nodes before linearized reading' (duration: 1.295234344s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:37.16803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:35.92702Z","time spent":"1.23972358s","remote":"127.0.0.1:58180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":10,"response size":3696,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 "} {"level":"warn","ts":"2026-04-13T17:25:37.168065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:35.872565Z","time spent":"1.295487716s","remote":"127.0.0.1:58340","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-13T17:25:37.168143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.301498611s","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-13T17:25:37.166697Z","caller":"traceutil/trace.go:171","msg":"trace[1890744155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2193; }","duration":"622.040344ms","start":"2026-04-13T17:25:36.544641Z","end":"2026-04-13T17:25:37.166682Z","steps":["trace[1890744155] 'agreement among raft nodes before linearized reading' (duration: 621.952283ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:37.168178Z","caller":"traceutil/trace.go:171","msg":"trace[614885426] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2193; }","duration":"1.301561482s","start":"2026-04-13T17:25:35.866606Z","end":"2026-04-13T17:25:37.168168Z","steps":["trace[614885426] 'agreement among raft nodes before linearized reading' (duration: 1.301462281s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:37.168208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:35.866592Z","time spent":"1.301608942s","remote":"127.0.0.1:58340","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-13T17:25:37.168215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:36.544608Z","time spent":"623.594758ms","remote":"127.0.0.1:58070","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-13T17:25:37.169302Z","caller":"traceutil/trace.go:171","msg":"trace[2025328683] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"801.293717ms","start":"2026-04-13T17:25:36.367982Z","end":"2026-04-13T17:25:37.169276Z","steps":["trace[2025328683] 'process raft request' (duration: 797.408391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:37.16942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:36.367954Z","time spent":"801.409578ms","remote":"127.0.0.1:58340","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-13T17:26:21.459146Z","caller":"traceutil/trace.go:171","msg":"trace[1235740196] transaction","detail":"{read_only:false; response_revision:2465; number_of_response:1; }","duration":"176.064237ms","start":"2026-04-13T17:26:21.283055Z","end":"2026-04-13T17:26:21.45912Z","steps":["trace[1235740196] 'process raft request' (duration: 175.866642ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:26:21.479627Z","caller":"traceutil/trace.go:171","msg":"trace[1173890865] transaction","detail":"{read_only:false; response_revision:2466; number_of_response:1; }","duration":"138.888605ms","start":"2026-04-13T17:26:21.340698Z","end":"2026-04-13T17:26:21.479587Z","steps":["trace[1173890865] 'process raft request' (duration: 138.639699ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:26:21.480121Z","caller":"traceutil/trace.go:171","msg":"trace[1310671365] transaction","detail":"{read_only:false; response_revision:2467; number_of_response:1; }","duration":"124.875668ms","start":"2026-04-13T17:26:21.355226Z","end":"2026-04-13T17:26:21.480102Z","steps":["trace[1310671365] 'process raft request' (duration: 124.294734ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:26:21.480219Z","caller":"traceutil/trace.go:171","msg":"trace[212596638] transaction","detail":"{read_only:false; response_revision:2468; number_of_response:1; }","duration":"124.49425ms","start":"2026-04-13T17:26:21.355691Z","end":"2026-04-13T17:26:21.480185Z","steps":["trace[212596638] 'process raft request' (duration: 124.268125ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:26:21.480274Z","caller":"traceutil/trace.go:171","msg":"trace[195514445] linearizableReadLoop","detail":"{readStateIndex:2567; appliedIndex:2563; }","duration":"114.34852ms","start":"2026-04-13T17:26:21.365877Z","end":"2026-04-13T17:26:21.480226Z","steps":["trace[195514445] 'read index received' (duration: 93.124029ms)","trace[195514445] 'applied index is now lower than readState.Index' (duration: 21.222121ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:26:21.480526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.637896ms","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-13T17:26:21.480769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.239505ms","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-13T17:26:21.480859Z","caller":"traceutil/trace.go:171","msg":"trace[1911652900] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2468; }","duration":"110.412569ms","start":"2026-04-13T17:26:21.370429Z","end":"2026-04-13T17:26:21.480842Z","steps":["trace[1911652900] 'agreement among raft nodes before linearized reading' (duration: 110.101282ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:26:21.480932Z","caller":"traceutil/trace.go:171","msg":"trace[956644405] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2468; }","duration":"114.771439ms","start":"2026-04-13T17:26:21.365803Z","end":"2026-04-13T17:26:21.480574Z","steps":["trace[956644405] 'agreement among raft nodes before linearized reading' (duration: 114.543734ms)"],"step_count":1}