{"level":"warn","ts":"2026-04-11T21:39:16.496209Z","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-11T21:39:16.496294Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.19:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.19:2380","--initial-cluster=instance=https://199.204.45.19:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.19:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.19: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-11T21:39:16.496357Z","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-11T21:39:16.496373Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-04-11T21:39:16.496391Z","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-11T21:39:16.497001Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"]} {"level":"info","ts":"2026-04-11T21:39:16.497074Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.19: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-11T21:39:16.500353Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.077675ms"} {"level":"info","ts":"2026-04-11T21:39:16.511228Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a847c2e9c7010db6","cluster-id":"2b11a68e27507b05"} {"level":"info","ts":"2026-04-11T21:39:16.511284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=()"} {"level":"info","ts":"2026-04-11T21:39:16.511306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 0"} {"level":"info","ts":"2026-04-11T21:39:16.51132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a847c2e9c7010db6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-11T21:39:16.511327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 1"} {"level":"info","ts":"2026-04-11T21:39:16.511357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"warn","ts":"2026-04-11T21:39:16.518645Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-11T21:39:16.52004Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-11T21:39:16.521467Z","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-11T21:39:16.523704Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a847c2e9c7010db6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-11T21:39:16.523861Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a847c2e9c7010db6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-11T21:39:16.523956Z","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-11T21:39:16.523999Z","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-11T21:39:16.52401Z","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-11T21:39:16.524952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"info","ts":"2026-04-11T21:39:16.525143Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","added-peer-id":"a847c2e9c7010db6","added-peer-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-04-11T21:39:16.529921Z","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-11T21:39:16.531164Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-04-11T21:39:16.531217Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-04-11T21:39:16.531199Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a847c2e9c7010db6","initial-advertise-peer-urls":["https://199.204.45.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-11T21:39:16.531353Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-11T21:39:17.511813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 is starting a new election at term 1"} {"level":"info","ts":"2026-04-11T21:39:17.511871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-11T21:39:17.511908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgPreVoteResp from a847c2e9c7010db6 at term 1"} {"level":"info","ts":"2026-04-11T21:39:17.511927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became candidate at term 2"} {"level":"info","ts":"2026-04-11T21:39:17.512054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgVoteResp from a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-04-11T21:39:17.512081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became leader at term 2"} {"level":"info","ts":"2026-04-11T21:39:17.512096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a847c2e9c7010db6 elected leader a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-04-11T21:39:17.515306Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T21:39:17.516893Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T21:39:17.5169Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a847c2e9c7010db6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.19:2379]}","request-path":"/0/members/a847c2e9c7010db6/attributes","cluster-id":"2b11a68e27507b05","publish-timeout":"7s"} {"level":"info","ts":"2026-04-11T21:39:17.516956Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T21:39:17.51731Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-11T21:39:17.517368Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-11T21:39:17.517558Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T21:39:17.517709Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T21:39:17.517748Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T21:39:17.520165Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-11T21:39:17.520641Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.19:2379"} {"level":"warn","ts":"2026-04-11T21:39:17.851214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.311418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-11T21:39:17.851319Z","caller":"traceutil/trace.go:171","msg":"trace[864449052] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"127.492952ms","start":"2026-04-11T21:39:17.723809Z","end":"2026-04-11T21:39:17.851302Z","steps":["trace[864449052] 'range keys from in-memory index tree' (duration: 127.240986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:17.851537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.874359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-11T21:39:17.851573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.454121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-11T21:39:17.851628Z","caller":"traceutil/trace.go:171","msg":"trace[2127673708] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"181.993121ms","start":"2026-04-11T21:39:17.669613Z","end":"2026-04-11T21:39:17.851606Z","steps":["trace[2127673708] 'count revisions from in-memory index tree' (duration: 181.754226ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:39:17.851649Z","caller":"traceutil/trace.go:171","msg":"trace[682104302] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"127.606294ms","start":"2026-04-11T21:39:17.724024Z","end":"2026-04-11T21:39:17.851631Z","steps":["trace[682104302] 'count revisions from in-memory index tree' (duration: 127.304818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:17.851658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.45121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-11T21:39:17.851761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.525532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-11T21:39:17.851756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.892235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-11T21:39:17.851805Z","caller":"traceutil/trace.go:171","msg":"trace[989278624] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"158.615643ms","start":"2026-04-11T21:39:17.693178Z","end":"2026-04-11T21:39:17.851793Z","steps":["trace[989278624] 'count revisions from in-memory index tree' (duration: 158.417279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:17.851787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.370467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-11T21:39:17.851824Z","caller":"traceutil/trace.go:171","msg":"trace[121688469] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"209.985847ms","start":"2026-04-11T21:39:17.641823Z","end":"2026-04-11T21:39:17.851809Z","steps":["trace[121688469] 'count revisions from in-memory index tree' (duration: 209.812074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:17.85185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.970414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-11T21:39:17.851868Z","caller":"traceutil/trace.go:171","msg":"trace[1696421577] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"196.518471ms","start":"2026-04-11T21:39:17.655332Z","end":"2026-04-11T21:39:17.85185Z","steps":["trace[1696421577] 'count revisions from in-memory index tree' (duration: 196.243565ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:39:17.851879Z","caller":"traceutil/trace.go:171","msg":"trace[1676299919] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"169.012785ms","start":"2026-04-11T21:39:17.682858Z","end":"2026-04-11T21:39:17.85187Z","steps":["trace[1676299919] 'count revisions from in-memory index tree' (duration: 168.930103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:17.851936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.426469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-11T21:39:17.851974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.927002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-11T21:39:17.851981Z","caller":"traceutil/trace.go:171","msg":"trace[1801107317] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"196.522681ms","start":"2026-04-11T21:39:17.655448Z","end":"2026-04-11T21:39:17.85197Z","steps":["trace[1801107317] 'range keys from in-memory index tree' (duration: 196.351827ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:39:17.852007Z","caller":"traceutil/trace.go:171","msg":"trace[1413581111] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"143.980643ms","start":"2026-04-11T21:39:17.708017Z","end":"2026-04-11T21:39:17.851998Z","steps":["trace[1413581111] 'range keys from in-memory index tree' (duration: 143.839409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:17.852022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.016336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-11T21:39:17.852033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.274654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-11T21:39:17.852054Z","caller":"traceutil/trace.go:171","msg":"trace[521782546] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"210.332316ms","start":"2026-04-11T21:39:17.641715Z","end":"2026-04-11T21:39:17.852048Z","steps":["trace[521782546] 'range keys from in-memory index tree' (duration: 210.133331ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:39:17.852051Z","caller":"traceutil/trace.go:171","msg":"trace[548488693] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"169.062698ms","start":"2026-04-11T21:39:17.682979Z","end":"2026-04-11T21:39:17.852041Z","steps":["trace[548488693] 'range keys from in-memory index tree' (duration: 168.955704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:17.852059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.750839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-11T21:39:17.851817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.206327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-11T21:39:17.852109Z","caller":"traceutil/trace.go:171","msg":"trace[2076200746] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"182.527754ms","start":"2026-04-11T21:39:17.669572Z","end":"2026-04-11T21:39:17.8521Z","steps":["trace[2076200746] 'range keys from in-memory index tree' (duration: 182.048903ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:39:17.852106Z","caller":"traceutil/trace.go:171","msg":"trace[635895878] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"222.814661ms","start":"2026-04-11T21:39:17.629275Z","end":"2026-04-11T21:39:17.85209Z","steps":["trace[635895878] 'range keys from in-memory index tree' (duration: 222.699269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:17.852119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.735944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-11T21:39:17.852138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.790939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-11T21:39:17.852168Z","caller":"traceutil/trace.go:171","msg":"trace[98418575] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"143.830021ms","start":"2026-04-11T21:39:17.708329Z","end":"2026-04-11T21:39:17.852159Z","steps":["trace[98418575] 'count revisions from in-memory index tree' (duration: 143.762499ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:39:17.85217Z","caller":"traceutil/trace.go:171","msg":"trace[1964544562] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1; }","duration":"116.792895ms","start":"2026-04-11T21:39:17.735362Z","end":"2026-04-11T21:39:17.852155Z","steps":["trace[1964544562] 'range keys from in-memory index tree' (duration: 116.613831ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:39:17.851766Z","caller":"traceutil/trace.go:171","msg":"trace[286905045] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"158.618504ms","start":"2026-04-11T21:39:17.693118Z","end":"2026-04-11T21:39:17.851736Z","steps":["trace[286905045] 'range keys from in-memory index tree' (duration: 158.352968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:17.852361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.591441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-11T21:39:17.852383Z","caller":"traceutil/trace.go:171","msg":"trace[750609384] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1; }","duration":"116.642952ms","start":"2026-04-11T21:39:17.735733Z","end":"2026-04-11T21:39:17.852376Z","steps":["trace[750609384] 'count revisions from in-memory index tree' (duration: 116.528929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:17.852416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.243009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-11T21:39:17.852452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.059006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-11T21:39:17.852461Z","caller":"traceutil/trace.go:171","msg":"trace[923610135] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"223.325501ms","start":"2026-04-11T21:39:17.629127Z","end":"2026-04-11T21:39:17.852453Z","steps":["trace[923610135] 'count revisions from in-memory index tree' (duration: 222.924563ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:39:17.85247Z","caller":"traceutil/trace.go:171","msg":"trace[1146723582] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1; }","duration":"106.118527ms","start":"2026-04-11T21:39:17.746346Z","end":"2026-04-11T21:39:17.852465Z","steps":["trace[1146723582] 'range keys from in-memory index tree' (duration: 105.918953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:17.852429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.138639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-11T21:39:17.852594Z","caller":"traceutil/trace.go:171","msg":"trace[656882499] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1; }","duration":"106.285582ms","start":"2026-04-11T21:39:17.746285Z","end":"2026-04-11T21:39:17.85257Z","steps":["trace[656882499] 'count revisions from in-memory index tree' (duration: 106.102387ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:39:19.310758Z","caller":"traceutil/trace.go:171","msg":"trace[346162559] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"480.438848ms","start":"2026-04-11T21:39:18.830282Z","end":"2026-04-11T21:39:19.310721Z","steps":["trace[346162559] 'process raft request' (duration: 480.183472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:19.311532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:18.83027Z","time spent":"480.605862ms","remote":"127.0.0.1:49734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T21:39:20.090229Z","caller":"traceutil/trace.go:171","msg":"trace[871798629] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"1.259852492s","start":"2026-04-11T21:39:18.830341Z","end":"2026-04-11T21:39:20.090194Z","steps":["trace[871798629] 'process raft request' (duration: 1.259653008s)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:39:20.090312Z","caller":"traceutil/trace.go:171","msg":"trace[2029552323] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"1.255164543s","start":"2026-04-11T21:39:18.835133Z","end":"2026-04-11T21:39:20.090298Z","steps":["trace[2029552323] 'read index received' (duration: 475.459303ms)","trace[2029552323] 'applied index is now lower than readState.Index' (duration: 779.70456ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T21:39:20.09035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:18.830324Z","time spent":"1.259968284s","remote":"127.0.0.1:49748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T21:39:20.09043Z","caller":"traceutil/trace.go:171","msg":"trace[2091616982] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"1.210178147s","start":"2026-04-11T21:39:18.880193Z","end":"2026-04-11T21:39:20.090371Z","steps":["trace[2091616982] 'process raft request' (duration: 1.210020463s)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:39:20.09043Z","caller":"traceutil/trace.go:171","msg":"trace[2131287132] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"1.210437783s","start":"2026-04-11T21:39:18.879953Z","end":"2026-04-11T21:39:20.090391Z","steps":["trace[2131287132] 'process raft request' (duration: 1.210167237s)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:39:20.090474Z","caller":"traceutil/trace.go:171","msg":"trace[471931248] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"1.210164917s","start":"2026-04-11T21:39:18.880153Z","end":"2026-04-11T21:39:20.090318Z","steps":["trace[471931248] 'process raft request' (duration: 1.210018713s)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:39:20.090515Z","caller":"traceutil/trace.go:171","msg":"trace[1589607377] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"1.210530334s","start":"2026-04-11T21:39:18.879965Z","end":"2026-04-11T21:39:20.090495Z","steps":["trace[1589607377] 'process raft request' (duration: 1.210172696s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:20.091444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:18.880145Z","time spent":"1.210398571s","remote":"127.0.0.1:50182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-11T21:39:20.091667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:18.87995Z","time spent":"1.211674579s","remote":"127.0.0.1:50182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-11T21:39:20.092176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:18.879936Z","time spent":"1.210573956s","remote":"127.0.0.1:50182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T21:39:20.092261Z","caller":"traceutil/trace.go:171","msg":"trace[1424996724] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"1.203388122s","start":"2026-04-11T21:39:18.888292Z","end":"2026-04-11T21:39:20.09168Z","steps":["trace[1424996724] 'process raft request' (duration: 1.201947331s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:20.092472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:18.888228Z","time spent":"1.204133298s","remote":"127.0.0.1:49858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T21:39:20.092328Z","caller":"traceutil/trace.go:171","msg":"trace[1582872720] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"1.212019275s","start":"2026-04-11T21:39:18.880146Z","end":"2026-04-11T21:39:20.092165Z","steps":["trace[1582872720] 'process raft request' (duration: 1.210009702s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:20.092823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:18.880137Z","time spent":"1.212550926s","remote":"127.0.0.1:50182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T21:39:20.09243Z","caller":"traceutil/trace.go:171","msg":"trace[112613803] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"1.211856453s","start":"2026-04-11T21:39:18.878788Z","end":"2026-04-11T21:39:20.090644Z","steps":["trace[112613803] 'process raft request' (duration: 1.21130257s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:20.093278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:18.878768Z","time spent":"1.214476118s","remote":"127.0.0.1:50108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-11T21:39:20.094421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.25921721s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-11T21:39:20.094484Z","caller":"traceutil/trace.go:171","msg":"trace[92397403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:11; }","duration":"1.259290631s","start":"2026-04-11T21:39:18.835162Z","end":"2026-04-11T21:39:20.094453Z","steps":["trace[92397403] 'agreement among raft nodes before linearized reading' (duration: 1.257445412s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:20.094509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:18.835158Z","time spent":"1.259345553s","remote":"127.0.0.1:49950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-11T21:39:20.09464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.213445976s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-11T21:39:20.09468Z","caller":"traceutil/trace.go:171","msg":"trace[2008261220] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"1.213498887s","start":"2026-04-11T21:39:18.881161Z","end":"2026-04-11T21:39:20.09466Z","steps":["trace[2008261220] 'agreement among raft nodes before linearized reading' (duration: 1.213460267s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:20.094704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:18.881151Z","time spent":"1.213548738s","remote":"127.0.0.1:49762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-04-11T21:39:20.090499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:18.880036Z","time spent":"1.210426973s","remote":"127.0.0.1:50182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-11T21:39:20.096636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.219538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-11T21:39:20.096654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.188764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-11T21:39:20.096675Z","caller":"traceutil/trace.go:171","msg":"trace[1227565008] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:11; }","duration":"319.2992ms","start":"2026-04-11T21:39:19.777365Z","end":"2026-04-11T21:39:20.096665Z","steps":["trace[1227565008] 'agreement among raft nodes before linearized reading' (duration: 317.524282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:20.096691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.218276318s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-11T21:39:20.096701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:19.777326Z","time spent":"319.369771ms","remote":"127.0.0.1:50054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"info","ts":"2026-04-11T21:39:20.096703Z","caller":"traceutil/trace.go:171","msg":"trace[1628602470] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:11; }","duration":"318.086545ms","start":"2026-04-11T21:39:19.778602Z","end":"2026-04-11T21:39:20.096688Z","steps":["trace[1628602470] 'agreement among raft nodes before linearized reading' (duration: 315.098801ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:39:20.096724Z","caller":"traceutil/trace.go:171","msg":"trace[313193749] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.218315139s","start":"2026-04-11T21:39:18.878399Z","end":"2026-04-11T21:39:20.096714Z","steps":["trace[313193749] 'agreement among raft nodes before linearized reading' (duration: 1.216419659s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:20.096739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:19.778582Z","time spent":"318.146596ms","remote":"127.0.0.1:50032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-04-11T21:39:20.096745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:18.878367Z","time spent":"1.218372851s","remote":"127.0.0.1:49794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-11T21:39:20.096865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.217008082s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-11T21:39:20.096888Z","caller":"traceutil/trace.go:171","msg":"trace[1606760767] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.217071574s","start":"2026-04-11T21:39:18.87981Z","end":"2026-04-11T21:39:20.096882Z","steps":["trace[1606760767] 'agreement among raft nodes before linearized reading' (duration: 1.21500728s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:20.096906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:18.879793Z","time spent":"1.217109514s","remote":"127.0.0.1:49794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-11T21:39:20.096868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.166817664s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-11T21:39:20.096998Z","caller":"traceutil/trace.go:171","msg":"trace[1770298013] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.166979888s","start":"2026-04-11T21:39:18.930013Z","end":"2026-04-11T21:39:20.096993Z","steps":["trace[1770298013] 'agreement among raft nodes before linearized reading' (duration: 1.163464644s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:20.097018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:18.929996Z","time spent":"1.167017088s","remote":"127.0.0.1:49794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-11T21:39:20.097241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.254583561s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-11T21:39:20.097269Z","caller":"traceutil/trace.go:171","msg":"trace[144436408] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"1.254640252s","start":"2026-04-11T21:39:18.842622Z","end":"2026-04-11T21:39:20.097262Z","steps":["trace[144436408] 'agreement among raft nodes before linearized reading' (duration: 1.25220696s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:20.097286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:18.842607Z","time spent":"1.254675023s","remote":"127.0.0.1:50078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-11T21:39:20.097386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.262248114s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-11T21:39:20.09742Z","caller":"traceutil/trace.go:171","msg":"trace[246096506] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:11; }","duration":"1.262303716s","start":"2026-04-11T21:39:18.835108Z","end":"2026-04-11T21:39:20.097412Z","steps":["trace[246096506] 'agreement among raft nodes before linearized reading' (duration: 1.258409234s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:20.097441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:18.835095Z","time spent":"1.262340766s","remote":"127.0.0.1:49950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-11T21:39:20.097495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.220546217s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.19\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-11T21:39:20.097525Z","caller":"traceutil/trace.go:171","msg":"trace[100983301] range","detail":"{range_begin:/registry/masterleases/199.204.45.19; range_end:; response_count:0; response_revision:11; }","duration":"1.220577208s","start":"2026-04-11T21:39:18.876941Z","end":"2026-04-11T21:39:20.097518Z","steps":["trace[100983301] 'agreement among raft nodes before linearized reading' (duration: 1.217874501s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:20.097544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:18.876928Z","time spent":"1.220611368s","remote":"127.0.0.1:49716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.19\" "} {"level":"info","ts":"2026-04-11T21:39:20.097575Z","caller":"traceutil/trace.go:171","msg":"trace[726932161] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"1.197104309s","start":"2026-04-11T21:39:18.897707Z","end":"2026-04-11T21:39:20.094811Z","steps":["trace[726932161] 'process raft request' (duration: 1.192559862s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:20.097642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:18.897696Z","time spent":"1.199914118s","remote":"127.0.0.1:49938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T21:39:21.761013Z","caller":"traceutil/trace.go:171","msg":"trace[1391913149] transaction","detail":"{read_only:false; response_revision:172; number_of_response:1; }","duration":"177.889881ms","start":"2026-04-11T21:39:21.58309Z","end":"2026-04-11T21:39:21.76098Z","steps":["trace[1391913149] 'process raft request' (duration: 125.509268ms)","trace[1391913149] 'compare' (duration: 52.237931ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:39:22.327289Z","caller":"traceutil/trace.go:171","msg":"trace[1062743434] linearizableReadLoop","detail":"{readStateIndex:191; appliedIndex:190; }","duration":"125.041767ms","start":"2026-04-11T21:39:22.202215Z","end":"2026-04-11T21:39:22.327257Z","steps":["trace[1062743434] 'read index received' (duration: 29.680731ms)","trace[1062743434] 'applied index is now lower than readState.Index' (duration: 95.359516ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:39:22.327344Z","caller":"traceutil/trace.go:171","msg":"trace[842578152] transaction","detail":"{read_only:false; response_revision:187; number_of_response:1; }","duration":"146.149866ms","start":"2026-04-11T21:39:22.181154Z","end":"2026-04-11T21:39:22.327304Z","steps":["trace[842578152] 'process raft request' (duration: 50.714738ms)","trace[842578152] 'compare' (duration: 95.195683ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T21:39:22.327661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.408135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T21:39:22.327837Z","caller":"traceutil/trace.go:171","msg":"trace[825954624] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:187; }","duration":"125.62036ms","start":"2026-04-11T21:39:22.202154Z","end":"2026-04-11T21:39:22.327774Z","steps":["trace[825954624] 'agreement among raft nodes before linearized reading' (duration: 125.239422ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:39:23.577106Z","caller":"traceutil/trace.go:171","msg":"trace[1985508103] linearizableReadLoop","detail":"{readStateIndex:228; appliedIndex:227; }","duration":"104.163274ms","start":"2026-04-11T21:39:23.472921Z","end":"2026-04-11T21:39:23.577084Z","steps":["trace[1985508103] 'read index received' (duration: 51.861593ms)","trace[1985508103] 'applied index is now lower than readState.Index' (duration: 52.300191ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:39:23.577359Z","caller":"traceutil/trace.go:171","msg":"trace[328946169] transaction","detail":"{read_only:false; response_revision:222; number_of_response:1; }","duration":"178.032822ms","start":"2026-04-11T21:39:23.399311Z","end":"2026-04-11T21:39:23.577343Z","steps":["trace[328946169] 'process raft request' (duration: 125.583798ms)","trace[328946169] 'compare' (duration: 51.986614ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T21:39:23.577581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.650453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-04-11T21:39:23.577627Z","caller":"traceutil/trace.go:171","msg":"trace[1131686024] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:222; }","duration":"104.747976ms","start":"2026-04-11T21:39:23.472865Z","end":"2026-04-11T21:39:23.577613Z","steps":["trace[1131686024] 'agreement among raft nodes before linearized reading' (duration: 104.659374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:23.849923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.440525ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-11T21:39:23.850068Z","caller":"traceutil/trace.go:171","msg":"trace[1513032681] linearizableReadLoop","detail":"{readStateIndex:230; appliedIndex:229; }","duration":"174.797664ms","start":"2026-04-11T21:39:23.675246Z","end":"2026-04-11T21:39:23.850044Z","steps":["trace[1513032681] 'read index received' (duration: 16.0212ms)","trace[1513032681] 'applied index is now lower than readState.Index' (duration: 158.774504ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:39:23.850158Z","caller":"traceutil/trace.go:171","msg":"trace[2085119382] transaction","detail":"{read_only:false; response_revision:224; number_of_response:1; }","duration":"177.080963ms","start":"2026-04-11T21:39:23.672992Z","end":"2026-04-11T21:39:23.850073Z","steps":["trace[2085119382] 'process raft request' (duration: 18.252638ms)","trace[2085119382] 'compare' (duration: 158.284012ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T21:39:23.850317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.050319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T21:39:23.850516Z","caller":"traceutil/trace.go:171","msg":"trace[1781506488] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/statefulset-controller; range_end:; response_count:0; response_revision:224; }","duration":"175.282404ms","start":"2026-04-11T21:39:23.675214Z","end":"2026-04-11T21:39:23.850496Z","steps":["trace[1781506488] 'agreement among raft nodes before linearized reading' (duration: 174.890096ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:39:24.049188Z","caller":"traceutil/trace.go:171","msg":"trace[244851352] transaction","detail":"{read_only:false; response_revision:225; number_of_response:1; }","duration":"191.303935ms","start":"2026-04-11T21:39:23.85785Z","end":"2026-04-11T21:39:24.049154Z","steps":["trace[244851352] 'process raft request' (duration: 127.55294ms)","trace[244851352] 'compare' (duration: 63.597832ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:39:24.051425Z","caller":"traceutil/trace.go:171","msg":"trace[937914430] transaction","detail":"{read_only:false; response_revision:226; number_of_response:1; }","duration":"193.095812ms","start":"2026-04-11T21:39:23.858301Z","end":"2026-04-11T21:39:24.051397Z","steps":["trace[937914430] 'process raft request' (duration: 192.839806ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:39:24.45595Z","caller":"traceutil/trace.go:171","msg":"trace[1447294261] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"108.349271ms","start":"2026-04-11T21:39:24.347556Z","end":"2026-04-11T21:39:24.455905Z","steps":["trace[1447294261] 'process raft request' (duration: 66.672226ms)","trace[1447294261] 'compare' (duration: 41.452271ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T21:39:24.812729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.00558ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-11T21:39:24.812955Z","caller":"traceutil/trace.go:171","msg":"trace[989502973] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"154.353259ms","start":"2026-04-11T21:39:24.65858Z","end":"2026-04-11T21:39:24.812933Z","steps":["trace[989502973] 'process raft request' (duration: 154.295708ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:39:24.813044Z","caller":"traceutil/trace.go:171","msg":"trace[1970774052] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"249.592532ms","start":"2026-04-11T21:39:24.563409Z","end":"2026-04-11T21:39:24.813001Z","steps":["trace[1970774052] 'process raft request' (duration: 64.241805ms)","trace[1970774052] 'compare' (duration: 184.854217ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:39:24.813127Z","caller":"traceutil/trace.go:171","msg":"trace[1365329810] linearizableReadLoop","detail":"{readStateIndex:245; appliedIndex:244; }","duration":"231.129229ms","start":"2026-04-11T21:39:24.581964Z","end":"2026-04-11T21:39:24.813093Z","steps":["trace[1365329810] 'read index received' (duration: 45.718011ms)","trace[1365329810] 'applied index is now lower than readState.Index' (duration: 185.404568ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T21:39:24.813269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.303453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3708"} {"level":"info","ts":"2026-04-11T21:39:24.813323Z","caller":"traceutil/trace.go:171","msg":"trace[1388561750] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:240; }","duration":"231.398575ms","start":"2026-04-11T21:39:24.581902Z","end":"2026-04-11T21:39:24.813301Z","steps":["trace[1388561750] 'agreement among raft nodes before linearized reading' (duration: 231.291623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:25.318093Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150334828159296,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T21:39:27.273866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.708249485s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-11T21:39:27.273959Z","caller":"traceutil/trace.go:171","msg":"trace[1455817060] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"2.456458579s","start":"2026-04-11T21:39:24.817487Z","end":"2026-04-11T21:39:27.273945Z","steps":["trace[1455817060] 'process raft request' (duration: 748.05346ms)","trace[1455817060] 'compare' (duration: 1.708134863s)"],"step_count":2} {"level":"warn","ts":"2026-04-11T21:39:27.273993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:24.817468Z","time spent":"2.456511719s","remote":"127.0.0.1:49750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-11T21:39:27.801301Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.235729703s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-11T21:39:29.40443Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.20193218s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-11T21:39:29.404774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.602836641s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T21:39:29.405004Z","caller":"traceutil/trace.go:171","msg":"trace[1981567313] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"4.584318426s","start":"2026-04-11T21:39:24.820649Z","end":"2026-04-11T21:39:29.404967Z","steps":["trace[1981567313] 'process raft request' (duration: 2.981195468s)","trace[1981567313] 'compare' (duration: 1.601995414s)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:39:29.405055Z","caller":"traceutil/trace.go:171","msg":"trace[451560136] linearizableReadLoop","detail":"{readStateIndex:248; appliedIndex:246; }","duration":"4.587265978s","start":"2026-04-11T21:39:24.817707Z","end":"2026-04-11T21:39:29.404973Z","steps":["trace[451560136] 'read index received' (duration: 747.844516ms)","trace[451560136] 'applied index is now lower than readState.Index' (duration: 3.839412552s)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:39:29.4051Z","caller":"traceutil/trace.go:171","msg":"trace[299954438] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"2.126672813s","start":"2026-04-11T21:39:27.278415Z","end":"2026-04-11T21:39:29.405088Z","steps":["trace[299954438] 'process raft request' (duration: 2.126465508s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:29.405287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.587560835s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T21:39:29.405331Z","caller":"traceutil/trace.go:171","msg":"trace[419663835] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:0; response_revision:244; }","duration":"4.587650156s","start":"2026-04-11T21:39:24.817668Z","end":"2026-04-11T21:39:29.405319Z","steps":["trace[419663835] 'agreement among raft nodes before linearized reading' (duration: 4.587516934s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:29.405329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:27.278393Z","time spent":"2.126888847s","remote":"127.0.0.1:49750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-11T21:39:29.405375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:24.817656Z","time spent":"4.587710608s","remote":"127.0.0.1:49892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-04-11T21:39:29.405248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:24.820632Z","time spent":"4.584472979s","remote":"127.0.0.1:49858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4002,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T21:39:29.405043Z","caller":"traceutil/trace.go:171","msg":"trace[1608471540] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"1.59386993s","start":"2026-04-11T21:39:27.811148Z","end":"2026-04-11T21:39:29.405018Z","steps":["trace[1608471540] 'process raft request' (duration: 1.593804869s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:29.406913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:27.811122Z","time spent":"1.595661038s","remote":"127.0.0.1:49864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T21:39:29.45548Z","caller":"traceutil/trace.go:171","msg":"trace[1636933468] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"820.913443ms","start":"2026-04-11T21:39:28.634525Z","end":"2026-04-11T21:39:29.455439Z","steps":["trace[1636933468] 'process raft request' (duration: 820.79685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:29.455639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.116384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-11T21:39:29.455709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:28.634508Z","time spent":"821.126378ms","remote":"127.0.0.1:49864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-11T21:39:29.455862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.749334439s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5892"} {"level":"info","ts":"2026-04-11T21:39:29.455915Z","caller":"traceutil/trace.go:171","msg":"trace[757991007] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:245; }","duration":"3.749430971s","start":"2026-04-11T21:39:25.70647Z","end":"2026-04-11T21:39:29.455901Z","steps":["trace[757991007] 'agreement among raft nodes before linearized reading' (duration: 3.749314609s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:29.455865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.66922001s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-11T21:39:29.455934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.275457083s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-11T21:39:29.455974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:25.706445Z","time spent":"3.749515352s","remote":"127.0.0.1:49864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5915,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-04-11T21:39:29.455984Z","caller":"traceutil/trace.go:171","msg":"trace[363811650] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:245; }","duration":"1.669363483s","start":"2026-04-11T21:39:27.786603Z","end":"2026-04-11T21:39:29.455966Z","steps":["trace[363811650] 'agreement among raft nodes before linearized reading' (duration: 1.669192459s)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:39:29.456013Z","caller":"traceutil/trace.go:171","msg":"trace[7764547] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:245; }","duration":"4.275556225s","start":"2026-04-11T21:39:25.180434Z","end":"2026-04-11T21:39:29.45599Z","steps":["trace[7764547] 'agreement among raft nodes before linearized reading' (duration: 4.275406172s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:29.456067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:25.180422Z","time spent":"4.275632506s","remote":"127.0.0.1:49950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-11T21:39:29.455751Z","caller":"traceutil/trace.go:171","msg":"trace[1622169] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:245; }","duration":"418.288408ms","start":"2026-04-11T21:39:29.037442Z","end":"2026-04-11T21:39:29.45573Z","steps":["trace[1622169] 'agreement among raft nodes before linearized reading' (duration: 418.074483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:29.456209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:29.037424Z","time spent":"418.770958ms","remote":"127.0.0.1:49950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-04-11T21:39:29.456273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.993813185s","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-11T21:39:29.456318Z","caller":"traceutil/trace.go:171","msg":"trace[1546574749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:245; }","duration":"2.993903347s","start":"2026-04-11T21:39:26.462403Z","end":"2026-04-11T21:39:29.456306Z","steps":["trace[1546574749] 'agreement among raft nodes before linearized reading' (duration: 2.993819845s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:29.456357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:26.462378Z","time spent":"2.993970499s","remote":"127.0.0.1:49950","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-11T21:39:29.456368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.368698985s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-11T21:39:29.456527Z","caller":"traceutil/trace.go:171","msg":"trace[238004002] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:245; }","duration":"4.36892207s","start":"2026-04-11T21:39:25.087573Z","end":"2026-04-11T21:39:29.456495Z","steps":["trace[238004002] 'agreement among raft nodes before linearized reading' (duration: 4.368586373s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:29.456595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:25.087552Z","time spent":"4.369032072s","remote":"127.0.0.1:49950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-11T21:39:29.456659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.094279119s","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-11T21:39:29.456713Z","caller":"traceutil/trace.go:171","msg":"trace[2108747472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:245; }","duration":"3.094373951s","start":"2026-04-11T21:39:26.362325Z","end":"2026-04-11T21:39:29.456699Z","steps":["trace[2108747472] 'agreement among raft nodes before linearized reading' (duration: 3.094247008s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:29.456747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:26.362303Z","time spent":"3.094437472s","remote":"127.0.0.1:49950","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-11T21:39:29.656804Z","caller":"traceutil/trace.go:171","msg":"trace[952727686] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"192.426163ms","start":"2026-04-11T21:39:29.464317Z","end":"2026-04-11T21:39:29.656743Z","steps":["trace[952727686] 'process raft request' (duration: 168.802402ms)","trace[952727686] 'compare' (duration: 23.297204ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:39:29.656879Z","caller":"traceutil/trace.go:171","msg":"trace[1448554248] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"192.383433ms","start":"2026-04-11T21:39:29.464447Z","end":"2026-04-11T21:39:29.65683Z","steps":["trace[1448554248] 'process raft request' (duration: 192.192829ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:39:29.656922Z","caller":"traceutil/trace.go:171","msg":"trace[423803353] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"191.652167ms","start":"2026-04-11T21:39:29.465229Z","end":"2026-04-11T21:39:29.656881Z","steps":["trace[423803353] 'process raft request' (duration: 191.571195ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:39:29.656944Z","caller":"traceutil/trace.go:171","msg":"trace[103380377] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"191.762199ms","start":"2026-04-11T21:39:29.465144Z","end":"2026-04-11T21:39:29.656906Z","steps":["trace[103380377] 'process raft request' (duration: 191.612576ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:39:29.657129Z","caller":"traceutil/trace.go:171","msg":"trace[1334950632] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"192.24776ms","start":"2026-04-11T21:39:29.464858Z","end":"2026-04-11T21:39:29.657106Z","steps":["trace[1334950632] 'process raft request' (duration: 191.862911ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:39:29.65771Z","caller":"traceutil/trace.go:171","msg":"trace[101832564] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"183.25036ms","start":"2026-04-11T21:39:29.474447Z","end":"2026-04-11T21:39:29.657697Z","steps":["trace[101832564] 'process raft request' (duration: 183.164558ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:39:29.658629Z","caller":"traceutil/trace.go:171","msg":"trace[1128286607] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"190.640766ms","start":"2026-04-11T21:39:29.467947Z","end":"2026-04-11T21:39:29.658588Z","steps":["trace[1128286607] 'process raft request' (duration: 189.529743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:36.052931Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150334828159607,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-11T21:39:36.174562Z","caller":"traceutil/trace.go:171","msg":"trace[1263123871] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:421; }","duration":"622.526489ms","start":"2026-04-11T21:39:35.552013Z","end":"2026-04-11T21:39:36.174539Z","steps":["trace[1263123871] 'read index received' (duration: 622.338955ms)","trace[1263123871] 'applied index is now lower than readState.Index' (duration: 186.644µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:39:36.174601Z","caller":"traceutil/trace.go:171","msg":"trace[2015134532] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"627.592507ms","start":"2026-04-11T21:39:35.546992Z","end":"2026-04-11T21:39:36.174584Z","steps":["trace[2015134532] 'process raft request' (duration: 627.423163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:36.174706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.678452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-11T21:39:36.174715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:35.546972Z","time spent":"627.675198ms","remote":"127.0.0.1:49950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T21:39:36.17475Z","caller":"traceutil/trace.go:171","msg":"trace[117847823] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:405; }","duration":"622.752604ms","start":"2026-04-11T21:39:35.551982Z","end":"2026-04-11T21:39:36.174734Z","steps":["trace[117847823] 'agreement among raft nodes before linearized reading' (duration: 622.676802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:36.174798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:35.551966Z","time spent":"622.822425ms","remote":"127.0.0.1:49950","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-11T21:39:36.174897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.519344ms","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-11T21:39:36.174926Z","caller":"traceutil/trace.go:171","msg":"trace[1146667621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:405; }","duration":"402.589067ms","start":"2026-04-11T21:39:35.77233Z","end":"2026-04-11T21:39:36.174919Z","steps":["trace[1146667621] 'agreement among raft nodes before linearized reading' (duration: 402.546386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:36.174946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:35.772309Z","time spent":"402.631817ms","remote":"127.0.0.1:49950","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-11T21:39:36.174839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.745824ms","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-11T21:39:36.175006Z","caller":"traceutil/trace.go:171","msg":"trace[888456848] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:405; }","duration":"622.948797ms","start":"2026-04-11T21:39:35.552051Z","end":"2026-04-11T21:39:36.175Z","steps":["trace[888456848] 'agreement among raft nodes before linearized reading' (duration: 622.764654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:36.175031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:35.552032Z","time spent":"622.993769ms","remote":"127.0.0.1:49950","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-11T21:39:37.271594Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.092250074s","expected-duration":"1s"} {"level":"info","ts":"2026-04-11T21:39:37.272085Z","caller":"traceutil/trace.go:171","msg":"trace[797278498] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"1.092795926s","start":"2026-04-11T21:39:36.179259Z","end":"2026-04-11T21:39:37.272054Z","steps":["trace[797278498] 'process raft request' (duration: 1.092598492s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:37.272277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:36.179242Z","time spent":"1.092936658s","remote":"127.0.0.1:49950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T21:39:37.282629Z","caller":"traceutil/trace.go:171","msg":"trace[1529913334] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"1.103146825s","start":"2026-04-11T21:39:36.179459Z","end":"2026-04-11T21:39:37.282606Z","steps":["trace[1529913334] 'process raft request' (duration: 1.103005773s)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:39:37.282759Z","caller":"traceutil/trace.go:171","msg":"trace[2027645636] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"1.103232777s","start":"2026-04-11T21:39:36.179511Z","end":"2026-04-11T21:39:37.282744Z","steps":["trace[2027645636] 'process raft request' (duration: 1.103059394s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:37.282762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:36.17945Z","time spent":"1.103239187s","remote":"127.0.0.1:49950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T21:39:37.282839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:36.179485Z","time spent":"1.103313348s","remote":"127.0.0.1:49950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T21:39:49.496775Z","caller":"traceutil/trace.go:171","msg":"trace[1574013490] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"118.784675ms","start":"2026-04-11T21:39:49.377966Z","end":"2026-04-11T21:39:49.496751Z","steps":["trace[1574013490] 'process raft request' (duration: 52.694496ms)","trace[1574013490] 'compare' (duration: 65.987177ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:39:57.538526Z","caller":"traceutil/trace.go:171","msg":"trace[1229091976] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"527.877511ms","start":"2026-04-11T21:39:57.010624Z","end":"2026-04-11T21:39:57.538501Z","steps":["trace[1229091976] 'process raft request' (duration: 527.748048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:57.538672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:57.010603Z","time spent":"528.007645ms","remote":"127.0.0.1:49722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T21:39:57.546363Z","caller":"traceutil/trace.go:171","msg":"trace[493384005] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"530.853662ms","start":"2026-04-11T21:39:57.01549Z","end":"2026-04-11T21:39:57.546344Z","steps":["trace[493384005] 'process raft request' (duration: 530.711208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:57.546454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:57.015472Z","time spent":"530.938644ms","remote":"127.0.0.1:49722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T21:39:57.546609Z","caller":"traceutil/trace.go:171","msg":"trace[114077792] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"528.053726ms","start":"2026-04-11T21:39:57.018547Z","end":"2026-04-11T21:39:57.546601Z","steps":["trace[114077792] 'process raft request' (duration: 527.742938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:57.54665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:57.018527Z","time spent":"528.103137ms","remote":"127.0.0.1:49722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T21:39:57.546869Z","caller":"traceutil/trace.go:171","msg":"trace[359219237] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"521.136939ms","start":"2026-04-11T21:39:57.025674Z","end":"2026-04-11T21:39:57.546811Z","steps":["trace[359219237] 'process raft request' (duration: 520.866481ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:39:57.546965Z","caller":"traceutil/trace.go:171","msg":"trace[1468021348] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"514.426637ms","start":"2026-04-11T21:39:57.032511Z","end":"2026-04-11T21:39:57.546938Z","steps":["trace[1468021348] 'process raft request' (duration: 514.281343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:57.546996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:57.025659Z","time spent":"521.274403ms","remote":"127.0.0.1:49722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-11T21:39:57.547115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:57.032502Z","time spent":"514.54349ms","remote":"127.0.0.1:49722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T21:39:57.547182Z","caller":"traceutil/trace.go:171","msg":"trace[400225605] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"518.522348ms","start":"2026-04-11T21:39:57.02865Z","end":"2026-04-11T21:39:57.547173Z","steps":["trace[400225605] 'process raft request' (duration: 518.079817ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:39:57.547192Z","caller":"traceutil/trace.go:171","msg":"trace[1550148039] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"509.134663ms","start":"2026-04-11T21:39:57.038042Z","end":"2026-04-11T21:39:57.547176Z","steps":["trace[1550148039] 'process raft request' (duration: 508.868536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:57.547226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:57.028637Z","time spent":"518.571829ms","remote":"127.0.0.1:49722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-11T21:39:57.547241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:57.038034Z","time spent":"509.183785ms","remote":"127.0.0.1:49722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T21:39:57.547268Z","caller":"traceutil/trace.go:171","msg":"trace[78797483] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"486.970092ms","start":"2026-04-11T21:39:57.060287Z","end":"2026-04-11T21:39:57.547257Z","steps":["trace[78797483] 'process raft request' (duration: 486.651733ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:39:57.547286Z","caller":"traceutil/trace.go:171","msg":"trace[378040212] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"482.633345ms","start":"2026-04-11T21:39:57.064647Z","end":"2026-04-11T21:39:57.54728Z","steps":["trace[378040212] 'process raft request' (duration: 482.325477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:57.547313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:57.060271Z","time spent":"487.021054ms","remote":"127.0.0.1:49722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-11T21:39:57.547312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:57.064628Z","time spent":"482.672216ms","remote":"127.0.0.1:49722","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-11T21:39:57.547339Z","caller":"traceutil/trace.go:171","msg":"trace[1582191653] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"511.19432ms","start":"2026-04-11T21:39:57.036136Z","end":"2026-04-11T21:39:57.54733Z","steps":["trace[1582191653] 'process raft request' (duration: 510.746478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:57.547383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:57.036125Z","time spent":"511.23831ms","remote":"127.0.0.1:49722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T21:39:57.547459Z","caller":"traceutil/trace.go:171","msg":"trace[1002465449] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"462.15333ms","start":"2026-04-11T21:39:57.085299Z","end":"2026-04-11T21:39:57.547452Z","steps":["trace[1002465449] 'process raft request' (duration: 461.713867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:57.547516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:57.085281Z","time spent":"462.198212ms","remote":"127.0.0.1:49722","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-11T21:39:57.547756Z","caller":"traceutil/trace.go:171","msg":"trace[1166353523] linearizableReadLoop","detail":"{readStateIndex:555; appliedIndex:544; }","duration":"178.645444ms","start":"2026-04-11T21:39:57.3691Z","end":"2026-04-11T21:39:57.547746Z","steps":["trace[1166353523] 'read index received' (duration: 169.390082ms)","trace[1166353523] 'applied index is now lower than readState.Index' (duration: 9.254662ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:39:57.547803Z","caller":"traceutil/trace.go:171","msg":"trace[1495366387] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"380.768144ms","start":"2026-04-11T21:39:57.167028Z","end":"2026-04-11T21:39:57.547796Z","steps":["trace[1495366387] 'process raft request' (duration: 380.380193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:57.547888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:57.166995Z","time spent":"380.872226ms","remote":"127.0.0.1:49722","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-11T21:39:57.549272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.164545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T21:39:57.549342Z","caller":"traceutil/trace.go:171","msg":"trace[237516712] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"180.277418ms","start":"2026-04-11T21:39:57.369046Z","end":"2026-04-11T21:39:57.549324Z","steps":["trace[237516712] 'agreement among raft nodes before linearized reading' (duration: 179.873487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:57.555779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.357885ms","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-11T21:39:57.555843Z","caller":"traceutil/trace.go:171","msg":"trace[1060673580] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:535; }","duration":"147.465297ms","start":"2026-04-11T21:39:57.40836Z","end":"2026-04-11T21:39:57.555825Z","steps":["trace[1060673580] 'agreement among raft nodes before linearized reading' (duration: 147.377185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:57.557801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.435574ms","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-11T21:39:57.557936Z","caller":"traceutil/trace.go:171","msg":"trace[227452726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:535; }","duration":"103.584048ms","start":"2026-04-11T21:39:57.454324Z","end":"2026-04-11T21:39:57.557908Z","steps":["trace[227452726] 'agreement among raft nodes before linearized reading' (duration: 101.06086ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:39:59.766496Z","caller":"traceutil/trace.go:171","msg":"trace[486784699] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"183.714971ms","start":"2026-04-11T21:39:59.582752Z","end":"2026-04-11T21:39:59.766467Z","steps":["trace[486784699] 'read index received' (duration: 183.553597ms)","trace[486784699] 'applied index is now lower than readState.Index' (duration: 160.604µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:39:59.76655Z","caller":"traceutil/trace.go:171","msg":"trace[597290950] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"187.243426ms","start":"2026-04-11T21:39:59.579292Z","end":"2026-04-11T21:39:59.766536Z","steps":["trace[597290950] 'process raft request' (duration: 186.956008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:39:59.766807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.022949ms","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-11T21:39:59.76692Z","caller":"traceutil/trace.go:171","msg":"trace[1146664981] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:601; }","duration":"184.169413ms","start":"2026-04-11T21:39:59.582731Z","end":"2026-04-11T21:39:59.7669Z","steps":["trace[1146664981] 'agreement among raft nodes before linearized reading' (duration: 183.839534ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:40:00.045804Z","caller":"traceutil/trace.go:171","msg":"trace[832480107] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"460.415483ms","start":"2026-04-11T21:39:59.585344Z","end":"2026-04-11T21:40:00.045759Z","steps":["trace[832480107] 'process raft request' (duration: 420.055099ms)","trace[832480107] 'compare' (duration: 39.80094ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:40:00.04597Z","caller":"traceutil/trace.go:171","msg":"trace[1704916258] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"275.607903ms","start":"2026-04-11T21:39:59.770289Z","end":"2026-04-11T21:40:00.045897Z","steps":["trace[1704916258] 'process raft request' (duration: 275.374477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:40:00.046042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:39:59.585334Z","time spent":"460.602918ms","remote":"127.0.0.1:49950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T21:40:04.211043Z","caller":"traceutil/trace.go:171","msg":"trace[1953403679] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"422.786186ms","start":"2026-04-11T21:40:03.78823Z","end":"2026-04-11T21:40:04.211016Z","steps":["trace[1953403679] 'process raft request' (duration: 422.55237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:40:04.21121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:40:03.788201Z","time spent":"422.92225ms","remote":"127.0.0.1:49950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T21:40:05.099301Z","caller":"traceutil/trace.go:171","msg":"trace[1591977862] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:655; }","duration":"1.042772801s","start":"2026-04-11T21:40:04.056501Z","end":"2026-04-11T21:40:05.099274Z","steps":["trace[1591977862] 'read index received' (duration: 154.282918ms)","trace[1591977862] 'applied index is now lower than readState.Index' (duration: 888.488833ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T21:40:05.099687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.043174231s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-11T21:40:05.099767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.930375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-11T21:40:05.0998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.780599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-11T21:40:05.099818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.04237328s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-11T21:40:05.099867Z","caller":"traceutil/trace.go:171","msg":"trace[1737907454] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"732.036128ms","start":"2026-04-11T21:40:04.367802Z","end":"2026-04-11T21:40:05.099838Z","steps":["trace[1737907454] 'agreement among raft nodes before linearized reading' (duration: 731.880314ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:40:05.099872Z","caller":"traceutil/trace.go:171","msg":"trace[761983787] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:628; }","duration":"772.891562ms","start":"2026-04-11T21:40:04.326956Z","end":"2026-04-11T21:40:05.099848Z","steps":["trace[761983787] 'agreement among raft nodes before linearized reading' (duration: 772.727608ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:40:05.099879Z","caller":"traceutil/trace.go:171","msg":"trace[841427491] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:628; }","duration":"1.042474933s","start":"2026-04-11T21:40:04.057382Z","end":"2026-04-11T21:40:05.099857Z","steps":["trace[841427491] 'agreement among raft nodes before linearized reading' (duration: 1.042275218s)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:40:05.099822Z","caller":"traceutil/trace.go:171","msg":"trace[1865314154] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"1.043340745s","start":"2026-04-11T21:40:04.056453Z","end":"2026-04-11T21:40:05.099794Z","steps":["trace[1865314154] 'agreement among raft nodes before linearized reading' (duration: 1.043025347s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:40:05.09993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:40:04.326927Z","time spent":"772.990575ms","remote":"127.0.0.1:49950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-11T21:40:05.100016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:40:04.05643Z","time spent":"1.043544831s","remote":"127.0.0.1:49950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-11T21:40:05.099921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:40:04.367762Z","time spent":"732.151521ms","remote":"127.0.0.1:49692","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-11T21:40:05.099886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.157748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6qxgr\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-11T21:40:05.100194Z","caller":"traceutil/trace.go:171","msg":"trace[1973457660] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6qxgr; range_end:; response_count:1; response_revision:628; }","duration":"753.524089ms","start":"2026-04-11T21:40:04.346658Z","end":"2026-04-11T21:40:05.100182Z","steps":["trace[1973457660] 'agreement among raft nodes before linearized reading' (duration: 753.019554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:40:05.10023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:40:04.346632Z","time spent":"753.58655ms","remote":"127.0.0.1:49864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-6qxgr\" "} {"level":"warn","ts":"2026-04-11T21:40:05.099927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:40:04.057364Z","time spent":"1.042555075s","remote":"127.0.0.1:49950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-11T21:40:05.100203Z","caller":"traceutil/trace.go:171","msg":"trace[1081612134] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"1.24970788s","start":"2026-04-11T21:40:03.850294Z","end":"2026-04-11T21:40:05.100002Z","steps":["trace[1081612134] 'process raft request' (duration: 1.21912797s)","trace[1081612134] 'compare' (duration: 29.48799ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T21:40:05.100507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:40:03.85025Z","time spent":"1.250170882s","remote":"127.0.0.1:49778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T21:40:06.715005Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150334828160092,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T21:40:07.091154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.352994443s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T21:40:07.091558Z","caller":"traceutil/trace.go:171","msg":"trace[1688717047] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"1.981216313s","start":"2026-04-11T21:40:05.110305Z","end":"2026-04-11T21:40:07.091522Z","steps":["trace[1688717047] 'process raft request' (duration: 627.681086ms)","trace[1688717047] 'compare' (duration: 1.352665785s)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:40:07.091611Z","caller":"traceutil/trace.go:171","msg":"trace[1978600362] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"1.972246786s","start":"2026-04-11T21:40:05.119309Z","end":"2026-04-11T21:40:07.091556Z","steps":["trace[1978600362] 'process raft request' (duration: 1.972126993s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:40:07.09173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:40:05.119299Z","time spent":"1.972375279s","remote":"127.0.0.1:49864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T21:40:07.091768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:40:05.110296Z","time spent":"1.981351867s","remote":"127.0.0.1:49950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T21:40:07.091612Z","caller":"traceutil/trace.go:171","msg":"trace[1176862328] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"1.979753704s","start":"2026-04-11T21:40:05.111832Z","end":"2026-04-11T21:40:07.091586Z","steps":["trace[1176862328] 'process raft request' (duration: 1.979476257s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:40:07.092002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:40:05.111811Z","time spent":"1.980112645s","remote":"127.0.0.1:49950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T21:40:07.136046Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.398029079s","expected-duration":"1s"} {"level":"info","ts":"2026-04-11T21:40:07.136323Z","caller":"traceutil/trace.go:171","msg":"trace[354584944] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"921.39022ms","start":"2026-04-11T21:40:06.214913Z","end":"2026-04-11T21:40:07.136303Z","steps":["trace[354584944] 'read index received' (duration: 921.251687ms)","trace[354584944] 'applied index is now lower than readState.Index' (duration: 137.523µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:40:07.136402Z","caller":"traceutil/trace.go:171","msg":"trace[119743571] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"1.981563506s","start":"2026-04-11T21:40:05.154803Z","end":"2026-04-11T21:40:07.136367Z","steps":["trace[119743571] 'process raft request' (duration: 1.981409262s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:40:07.136472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.306426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T21:40:07.136505Z","caller":"traceutil/trace.go:171","msg":"trace[477515765] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"768.383268ms","start":"2026-04-11T21:40:06.368107Z","end":"2026-04-11T21:40:07.136491Z","steps":["trace[477515765] 'agreement among raft nodes before linearized reading' (duration: 768.293146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:40:07.136532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:40:06.368089Z","time spent":"768.437349ms","remote":"127.0.0.1:49692","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-11T21:40:07.136587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:40:05.154781Z","time spent":"1.98171298s","remote":"127.0.0.1:50048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-11T21:40:07.136674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.73826ms","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-11T21:40:07.136774Z","caller":"traceutil/trace.go:171","msg":"trace[782120641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:633; }","duration":"921.914955ms","start":"2026-04-11T21:40:06.214836Z","end":"2026-04-11T21:40:07.136751Z","steps":["trace[782120641] 'agreement among raft nodes before linearized reading' (duration: 921.561945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:40:07.136838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:40:06.214816Z","time spent":"922.008337ms","remote":"127.0.0.1:49950","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-11T21:40:08.817484Z","caller":"traceutil/trace.go:171","msg":"trace[2141383718] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"338.33454ms","start":"2026-04-11T21:40:08.479095Z","end":"2026-04-11T21:40:08.81743Z","steps":["trace[2141383718] 'process raft request' (duration: 330.110794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:40:08.817805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:40:08.479073Z","time spent":"338.630427ms","remote":"127.0.0.1:49722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T21:40:08.817543Z","caller":"traceutil/trace.go:171","msg":"trace[333748555] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"335.430853ms","start":"2026-04-11T21:40:08.482087Z","end":"2026-04-11T21:40:08.817518Z","steps":["trace[333748555] 'process raft request' (duration: 335.389112ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:40:08.817594Z","caller":"traceutil/trace.go:171","msg":"trace[1138207259] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"337.268882ms","start":"2026-04-11T21:40:08.48032Z","end":"2026-04-11T21:40:08.817589Z","steps":["trace[1138207259] 'process raft request' (duration: 337.031525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:40:08.81829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:40:08.48031Z","time spent":"337.908258ms","remote":"127.0.0.1:49722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-11T21:40:08.818195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:40:08.482078Z","time spent":"336.025859ms","remote":"127.0.0.1:49722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T21:40:08.819287Z","caller":"traceutil/trace.go:171","msg":"trace[1512519891] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"143.354756ms","start":"2026-04-11T21:40:08.675911Z","end":"2026-04-11T21:40:08.819266Z","steps":["trace[1512519891] 'process raft request' (duration: 143.306165ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:40:08.819401Z","caller":"traceutil/trace.go:171","msg":"trace[1497542085] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"331.247153ms","start":"2026-04-11T21:40:08.48809Z","end":"2026-04-11T21:40:08.819337Z","steps":["trace[1497542085] 'process raft request' (duration: 330.947275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:40:08.8195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:40:08.488071Z","time spent":"331.376206ms","remote":"127.0.0.1:49722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T21:40:08.819678Z","caller":"traceutil/trace.go:171","msg":"trace[1873652018] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"331.482408ms","start":"2026-04-11T21:40:08.488175Z","end":"2026-04-11T21:40:08.819657Z","steps":["trace[1873652018] 'process raft request' (duration: 330.997746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:40:08.819802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:40:08.488168Z","time spent":"331.564361ms","remote":"127.0.0.1:50182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":932,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T21:40:19.342751Z","caller":"traceutil/trace.go:171","msg":"trace[1469051109] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"189.927446ms","start":"2026-04-11T21:40:19.152753Z","end":"2026-04-11T21:40:19.342681Z","steps":["trace[1469051109] 'process raft request' (duration: 189.614609ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:40:19.344433Z","caller":"traceutil/trace.go:171","msg":"trace[629050231] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:808; }","duration":"140.888422ms","start":"2026-04-11T21:40:19.203522Z","end":"2026-04-11T21:40:19.34441Z","steps":["trace[629050231] 'read index received' (duration: 138.83157ms)","trace[629050231] 'applied index is now lower than readState.Index' (duration: 2.055982ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:40:19.344541Z","caller":"traceutil/trace.go:171","msg":"trace[846559510] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"190.47093ms","start":"2026-04-11T21:40:19.154019Z","end":"2026-04-11T21:40:19.34449Z","steps":["trace[846559510] 'process raft request' (duration: 190.265315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:40:19.344691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.055933ms","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-11T21:40:19.344725Z","caller":"traceutil/trace.go:171","msg":"trace[1580577696] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:776; }","duration":"135.123415ms","start":"2026-04-11T21:40:19.209592Z","end":"2026-04-11T21:40:19.344715Z","steps":["trace[1580577696] 'agreement among raft nodes before linearized reading' (duration: 134.979521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:40:19.344839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.22331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-11T21:40:19.344902Z","caller":"traceutil/trace.go:171","msg":"trace[1534254850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:776; }","duration":"141.395745ms","start":"2026-04-11T21:40:19.203492Z","end":"2026-04-11T21:40:19.344888Z","steps":["trace[1534254850] 'agreement among raft nodes before linearized reading' (duration: 141.022946ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:40:21.761736Z","caller":"traceutil/trace.go:171","msg":"trace[1677300388] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"326.215943ms","start":"2026-04-11T21:40:21.4355Z","end":"2026-04-11T21:40:21.761716Z","steps":["trace[1677300388] 'process raft request' (duration: 326.0737ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:40:21.761797Z","caller":"traceutil/trace.go:171","msg":"trace[521427750] linearizableReadLoop","detail":"{readStateIndex:826; appliedIndex:826; }","duration":"299.402641ms","start":"2026-04-11T21:40:21.46235Z","end":"2026-04-11T21:40:21.761753Z","steps":["trace[521427750] 'read index received' (duration: 299.384891ms)","trace[521427750] 'applied index is now lower than readState.Index' (duration: 14.85µs)"],"step_count":2} {"level":"warn","ts":"2026-04-11T21:40:21.761974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.627907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-nbzxp\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-04-11T21:40:21.762011Z","caller":"traceutil/trace.go:171","msg":"trace[255815872] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-nbzxp; range_end:; response_count:1; response_revision:792; }","duration":"299.719709ms","start":"2026-04-11T21:40:21.462283Z","end":"2026-04-11T21:40:21.762002Z","steps":["trace[255815872] 'agreement among raft nodes before linearized reading' (duration: 299.597136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:40:21.7624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:40:21.435477Z","time spent":"326.349896ms","remote":"127.0.0.1:49950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T21:40:22.171245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.105299ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T21:40:22.171387Z","caller":"traceutil/trace.go:171","msg":"trace[1490308560] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"581.00098ms","start":"2026-04-11T21:40:21.590363Z","end":"2026-04-11T21:40:22.171364Z","steps":["trace[1490308560] 'process raft request' (duration: 475.680335ms)","trace[1490308560] 'compare' (duration: 104.864223ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T21:40:22.17146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:40:21.590337Z","time spent":"581.087792ms","remote":"127.0.0.1:49950","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-11T21:40:22.172968Z","caller":"traceutil/trace.go:171","msg":"trace[642844517] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:826; }","duration":"279.787013ms","start":"2026-04-11T21:40:21.893144Z","end":"2026-04-11T21:40:22.172931Z","steps":["trace[642844517] 'read index received' (duration: 172.848797ms)","trace[642844517] 'applied index is now lower than readState.Index' (duration: 106.937116ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:40:22.173003Z","caller":"traceutil/trace.go:171","msg":"trace[1622305797] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"402.776164ms","start":"2026-04-11T21:40:21.770193Z","end":"2026-04-11T21:40:22.172969Z","steps":["trace[1622305797] 'process raft request' (duration: 402.603069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:40:22.173158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.007819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-11T21:40:22.173184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:40:21.770171Z","time spent":"402.916937ms","remote":"127.0.0.1:49864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T21:40:22.173197Z","caller":"traceutil/trace.go:171","msg":"trace[416392268] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:794; }","duration":"280.108781ms","start":"2026-04-11T21:40:21.893077Z","end":"2026-04-11T21:40:22.173186Z","steps":["trace[416392268] 'agreement among raft nodes before linearized reading' (duration: 279.967188ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:40:23.617858Z","caller":"traceutil/trace.go:171","msg":"trace[213939455] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"111.950747ms","start":"2026-04-11T21:40:23.505885Z","end":"2026-04-11T21:40:23.617835Z","steps":["trace[213939455] 'process raft request' (duration: 111.768033ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:40:23.618051Z","caller":"traceutil/trace.go:171","msg":"trace[186989791] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"110.277596ms","start":"2026-04-11T21:40:23.50774Z","end":"2026-04-11T21:40:23.618018Z","steps":["trace[186989791] 'process raft request' (duration: 110.04362ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:41:13.763812Z","caller":"traceutil/trace.go:171","msg":"trace[1280959167] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"138.978197ms","start":"2026-04-11T21:41:13.624811Z","end":"2026-04-11T21:41:13.763789Z","steps":["trace[1280959167] 'process raft request' (duration: 138.831763ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:41:13.812616Z","caller":"traceutil/trace.go:171","msg":"trace[368985808] linearizableReadLoop","detail":"{readStateIndex:1148; appliedIndex:1146; }","duration":"151.897961ms","start":"2026-04-11T21:41:13.6607Z","end":"2026-04-11T21:41:13.812598Z","steps":["trace[368985808] 'read index received' (duration: 102.944293ms)","trace[368985808] 'applied index is now lower than readState.Index' (duration: 48.952758ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:41:13.812749Z","caller":"traceutil/trace.go:171","msg":"trace[554030965] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"187.71588ms","start":"2026-04-11T21:41:13.624996Z","end":"2026-04-11T21:41:13.812712Z","steps":["trace[554030965] 'process raft request' (duration: 175.455942ms)","trace[554030965] 'compare' (duration: 12.058563ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T21:41:13.812964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.126735ms","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-11T21:41:13.812995Z","caller":"traceutil/trace.go:171","msg":"trace[1062291416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1100; }","duration":"117.210317ms","start":"2026-04-11T21:41:13.695776Z","end":"2026-04-11T21:41:13.812986Z","steps":["trace[1062291416] 'agreement among raft nodes before linearized reading' (duration: 117.080044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:41:13.813059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.345271ms","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-11T21:41:13.813154Z","caller":"traceutil/trace.go:171","msg":"trace[1972308904] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1100; }","duration":"152.490355ms","start":"2026-04-11T21:41:13.660647Z","end":"2026-04-11T21:41:13.813138Z","steps":["trace[1972308904] 'agreement among raft nodes before linearized reading' (duration: 152.150297ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:42:20.621988Z","caller":"traceutil/trace.go:171","msg":"trace[1071141532] linearizableReadLoop","detail":"{readStateIndex:1420; appliedIndex:1419; }","duration":"242.691835ms","start":"2026-04-11T21:42:20.37927Z","end":"2026-04-11T21:42:20.621961Z","steps":["trace[1071141532] 'read index received' (duration: 242.514401ms)","trace[1071141532] 'applied index is now lower than readState.Index' (duration: 176.504µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:42:20.622167Z","caller":"traceutil/trace.go:171","msg":"trace[473928763] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"243.830142ms","start":"2026-04-11T21:42:20.378216Z","end":"2026-04-11T21:42:20.622047Z","steps":["trace[473928763] 'process raft request' (duration: 243.591997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:42:20.62227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.971522ms","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-11T21:42:20.622345Z","caller":"traceutil/trace.go:171","msg":"trace[397154447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1357; }","duration":"243.075065ms","start":"2026-04-11T21:42:20.379254Z","end":"2026-04-11T21:42:20.622329Z","steps":["trace[397154447] 'agreement among raft nodes before linearized reading' (duration: 242.836259ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:42:20.625806Z","caller":"traceutil/trace.go:171","msg":"trace[1489034192] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"243.815502ms","start":"2026-04-11T21:42:20.381968Z","end":"2026-04-11T21:42:20.625783Z","steps":["trace[1489034192] 'process raft request' (duration: 243.771321ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:42:20.625887Z","caller":"traceutil/trace.go:171","msg":"trace[502130132] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"244.160089ms","start":"2026-04-11T21:42:20.381698Z","end":"2026-04-11T21:42:20.625858Z","steps":["trace[502130132] 'process raft request' (duration: 243.921774ms)"],"step_count":1}