{"level":"warn","ts":"2026-02-03T17:20:18.757429Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-03T17:20:18.757513Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.138: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.138:2380","--initial-cluster=instance=https://199.204.45.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.138: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-02-03T17:20:18.757598Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-03T17:20:18.757612Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-02-03T17:20:18.76393Z","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-02-03T17:20:18.765088Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"]} {"level":"info","ts":"2026-02-03T17:20:18.76521Z","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":2,"max-cpu-available":2,"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.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.138: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-02-03T17:20:18.772397Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.715136ms"} {"level":"info","ts":"2026-02-03T17:20:18.804235Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3d52791b64f7591","cluster-id":"90076b89649fe294"} {"level":"info","ts":"2026-02-03T17:20:18.804319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=()"} {"level":"info","ts":"2026-02-03T17:20:18.80435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 0"} {"level":"info","ts":"2026-02-03T17:20:18.804377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3d52791b64f7591 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-03T17:20:18.804384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 1"} {"level":"info","ts":"2026-02-03T17:20:18.804436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"warn","ts":"2026-02-03T17:20:18.820764Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-03T17:20:18.82585Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-03T17:20:18.829506Z","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-02-03T17:20:18.847983Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3d52791b64f7591","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-03T17:20:18.848627Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3d52791b64f7591","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-03T17:20:18.849039Z","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-02-03T17:20:18.849161Z","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-02-03T17:20:18.849168Z","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-02-03T17:20:18.849671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"info","ts":"2026-02-03T17:20:18.849741Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","added-peer-id":"f3d52791b64f7591","added-peer-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-02-03T17:20:18.86775Z","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-02-03T17:20:18.868499Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3d52791b64f7591","initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-03T17:20:18.868619Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-03T17:20:18.868721Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-02-03T17:20:18.868731Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-02-03T17:20:19.708023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 is starting a new election at term 1"} {"level":"info","ts":"2026-02-03T17:20:19.708065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-03T17:20:19.708086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgPreVoteResp from f3d52791b64f7591 at term 1"} {"level":"info","ts":"2026-02-03T17:20:19.708258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became candidate at term 2"} {"level":"info","ts":"2026-02-03T17:20:19.708269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgVoteResp from f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-02-03T17:20:19.708316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became leader at term 2"} {"level":"info","ts":"2026-02-03T17:20:19.708329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3d52791b64f7591 elected leader f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-02-03T17:20:19.713062Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3d52791b64f7591","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.138:2379]}","request-path":"/0/members/f3d52791b64f7591/attributes","cluster-id":"90076b89649fe294","publish-timeout":"7s"} {"level":"info","ts":"2026-02-03T17:20:19.71312Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T17:20:19.713377Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T17:20:19.713398Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T17:20:19.715325Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-03T17:20:19.715346Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-03T17:20:19.71806Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-03T17:20:19.718616Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.138:2379"} {"level":"info","ts":"2026-02-03T17:20:19.718691Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T17:20:19.718755Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T17:20:19.71879Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T17:20:23.52386Z","caller":"traceutil/trace.go:171","msg":"trace[2079426359] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"138.407537ms","start":"2026-02-03T17:20:23.385439Z","end":"2026-02-03T17:20:23.523847Z","steps":["trace[2079426359] 'process raft request' (duration: 137.974857ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:23.523617Z","caller":"traceutil/trace.go:171","msg":"trace[2031022648] linearizableReadLoop","detail":"{readStateIndex:254; appliedIndex:253; }","duration":"137.41921ms","start":"2026-02-03T17:20:23.386178Z","end":"2026-02-03T17:20:23.523597Z","steps":["trace[2031022648] 'read index received' (duration: 137.184716ms)","trace[2031022648] 'applied index is now lower than readState.Index' (duration: 233.444µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:20:23.52433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.130203ms","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-02-03T17:20:23.52488Z","caller":"traceutil/trace.go:171","msg":"trace[1445714410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:249; }","duration":"138.714379ms","start":"2026-02-03T17:20:23.386154Z","end":"2026-02-03T17:20:23.524868Z","steps":["trace[1445714410] 'agreement among raft nodes before linearized reading' (duration: 137.900739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:20:23.5248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.479388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3180"} {"level":"info","ts":"2026-02-03T17:20:23.525119Z","caller":"traceutil/trace.go:171","msg":"trace[1493456218] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:249; }","duration":"101.818049ms","start":"2026-02-03T17:20:23.423288Z","end":"2026-02-03T17:20:23.525106Z","steps":["trace[1493456218] 'agreement among raft nodes before linearized reading' (duration: 101.437179ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:24.187434Z","caller":"traceutil/trace.go:171","msg":"trace[150835385] linearizableReadLoop","detail":"{readStateIndex:273; appliedIndex:272; }","duration":"102.423629ms","start":"2026-02-03T17:20:24.084995Z","end":"2026-02-03T17:20:24.187418Z","steps":["trace[150835385] 'read index received' (duration: 99.691124ms)","trace[150835385] 'applied index is now lower than readState.Index' (duration: 2.731935ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:20:24.187693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.683443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-03T17:20:24.187812Z","caller":"traceutil/trace.go:171","msg":"trace[376816910] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:268; }","duration":"102.82585ms","start":"2026-02-03T17:20:24.084972Z","end":"2026-02-03T17:20:24.187798Z","steps":["trace[376816910] 'agreement among raft nodes before linearized reading' (duration: 102.683173ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:24.18772Z","caller":"traceutil/trace.go:171","msg":"trace[51575725] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"109.432933ms","start":"2026-02-03T17:20:24.078281Z","end":"2026-02-03T17:20:24.187714Z","steps":["trace[51575725] 'process raft request' (duration: 106.389855ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:24.963454Z","caller":"traceutil/trace.go:171","msg":"trace[208205501] transaction","detail":"{read_only:false; number_of_response:0; response_revision:287; }","duration":"137.240172ms","start":"2026-02-03T17:20:24.826185Z","end":"2026-02-03T17:20:24.963426Z","steps":["trace[208205501] 'process raft request' (duration: 109.329975ms)","trace[208205501] 'compare' (duration: 27.81699ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:20:24.963863Z","caller":"traceutil/trace.go:171","msg":"trace[491519296] linearizableReadLoop","detail":"{readStateIndex:294; appliedIndex:292; }","duration":"125.304886ms","start":"2026-02-03T17:20:24.838548Z","end":"2026-02-03T17:20:24.963853Z","steps":["trace[491519296] 'read index received' (duration: 96.976088ms)","trace[491519296] 'applied index is now lower than readState.Index' (duration: 28.327988ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:20:24.964008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.371423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:20:24.964038Z","caller":"traceutil/trace.go:171","msg":"trace[1999201962] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cronjob-controller; range_end:; response_count:0; response_revision:288; }","duration":"147.434501ms","start":"2026-02-03T17:20:24.816594Z","end":"2026-02-03T17:20:24.964029Z","steps":["trace[1999201962] 'agreement among raft nodes before linearized reading' (duration: 147.295474ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:24.964246Z","caller":"traceutil/trace.go:171","msg":"trace[1846081761] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"137.868238ms","start":"2026-02-03T17:20:24.826365Z","end":"2026-02-03T17:20:24.964233Z","steps":["trace[1846081761] 'process raft request' (duration: 137.011329ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:24.990064Z","caller":"traceutil/trace.go:171","msg":"trace[719008344] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"148.552334ms","start":"2026-02-03T17:20:24.841484Z","end":"2026-02-03T17:20:24.990037Z","steps":["trace[719008344] 'process raft request' (duration: 148.412638ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:24.990311Z","caller":"traceutil/trace.go:171","msg":"trace[677537866] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"148.201842ms","start":"2026-02-03T17:20:24.84209Z","end":"2026-02-03T17:20:24.990292Z","steps":["trace[677537866] 'process raft request' (duration: 147.894469ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:24.990532Z","caller":"traceutil/trace.go:171","msg":"trace[623402629] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"140.400188ms","start":"2026-02-03T17:20:24.85012Z","end":"2026-02-03T17:20:24.990495Z","steps":["trace[623402629] 'process raft request' (duration: 139.989627ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:24.990571Z","caller":"traceutil/trace.go:171","msg":"trace[1903845466] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"139.301533ms","start":"2026-02-03T17:20:24.851259Z","end":"2026-02-03T17:20:24.99056Z","steps":["trace[1903845466] 'process raft request' (duration: 138.887703ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:24.991243Z","caller":"traceutil/trace.go:171","msg":"trace[1354468792] transaction","detail":"{read_only:false; number_of_response:0; response_revision:291; }","duration":"143.382967ms","start":"2026-02-03T17:20:24.847851Z","end":"2026-02-03T17:20:24.991233Z","steps":["trace[1354468792] 'process raft request' (duration: 142.203085ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:24.991353Z","caller":"traceutil/trace.go:171","msg":"trace[1834006750] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"143.549374ms","start":"2026-02-03T17:20:24.847795Z","end":"2026-02-03T17:20:24.991344Z","steps":["trace[1834006750] 'process raft request' (duration: 142.226765ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:24.991566Z","caller":"traceutil/trace.go:171","msg":"trace[1859145853] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"142.645165ms","start":"2026-02-03T17:20:24.848912Z","end":"2026-02-03T17:20:24.991557Z","steps":["trace[1859145853] 'process raft request' (duration: 141.169399ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:25.226058Z","caller":"traceutil/trace.go:171","msg":"trace[950507708] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"219.770431ms","start":"2026-02-03T17:20:25.006265Z","end":"2026-02-03T17:20:25.226035Z","steps":["trace[950507708] 'process raft request' (duration: 219.729782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:20:25.226427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.107538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3444"} {"level":"info","ts":"2026-02-03T17:20:25.226472Z","caller":"traceutil/trace.go:171","msg":"trace[1935334895] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:298; }","duration":"224.172257ms","start":"2026-02-03T17:20:25.00229Z","end":"2026-02-03T17:20:25.226462Z","steps":["trace[1935334895] 'agreement among raft nodes before linearized reading' (duration: 224.107668ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:25.226825Z","caller":"traceutil/trace.go:171","msg":"trace[264061345] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"220.595942ms","start":"2026-02-03T17:20:25.006215Z","end":"2026-02-03T17:20:25.226811Z","steps":["trace[264061345] 'process raft request' (duration: 219.729451ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:25.226125Z","caller":"traceutil/trace.go:171","msg":"trace[567488182] linearizableReadLoop","detail":"{readStateIndex:303; appliedIndex:302; }","duration":"222.391399ms","start":"2026-02-03T17:20:25.003703Z","end":"2026-02-03T17:20:25.226095Z","steps":["trace[567488182] 'read index received' (duration: 138.728465ms)","trace[567488182] 'applied index is now lower than readState.Index' (duration: 83.659994ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:20:25.226335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.712294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3705"} {"level":"info","ts":"2026-02-03T17:20:25.227123Z","caller":"traceutil/trace.go:171","msg":"trace[2032352899] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:298; }","duration":"228.558813ms","start":"2026-02-03T17:20:24.998554Z","end":"2026-02-03T17:20:25.227113Z","steps":["trace[2032352899] 'agreement among raft nodes before linearized reading' (duration: 227.626776ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:25.226379Z","caller":"traceutil/trace.go:171","msg":"trace[1172321505] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"224.226365ms","start":"2026-02-03T17:20:25.00214Z","end":"2026-02-03T17:20:25.226366Z","steps":["trace[1172321505] 'process raft request' (duration: 140.279487ms)","trace[1172321505] 'compare' (duration: 83.297273ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:20:25.530358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.253328ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:20:25.531643Z","caller":"traceutil/trace.go:171","msg":"trace[951060029] linearizableReadLoop","detail":"{readStateIndex:307; appliedIndex:305; }","duration":"288.577027ms","start":"2026-02-03T17:20:25.243034Z","end":"2026-02-03T17:20:25.531611Z","steps":["trace[951060029] 'read index received' (duration: 141.44675ms)","trace[951060029] 'applied index is now lower than readState.Index' (duration: 147.129117ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:20:25.531783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.742463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:20:25.531813Z","caller":"traceutil/trace.go:171","msg":"trace[252051297] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:0; response_revision:300; }","duration":"288.789032ms","start":"2026-02-03T17:20:25.243015Z","end":"2026-02-03T17:20:25.531804Z","steps":["trace[252051297] 'agreement among raft nodes before linearized reading' (duration: 288.664795ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:25.532457Z","caller":"traceutil/trace.go:171","msg":"trace[566126545] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"292.036295ms","start":"2026-02-03T17:20:25.240407Z","end":"2026-02-03T17:20:25.532443Z","steps":["trace[566126545] 'process raft request' (duration: 144.068668ms)","trace[566126545] 'compare' (duration: 143.118471ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:20:25.533246Z","caller":"traceutil/trace.go:171","msg":"trace[716334581] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"291.931117ms","start":"2026-02-03T17:20:25.241287Z","end":"2026-02-03T17:20:25.533218Z","steps":["trace[716334581] 'process raft request' (duration: 289.154933ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:25.701916Z","caller":"traceutil/trace.go:171","msg":"trace[310582872] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"159.526404ms","start":"2026-02-03T17:20:25.542358Z","end":"2026-02-03T17:20:25.701884Z","steps":["trace[310582872] 'process raft request' (duration: 117.368489ms)","trace[310582872] 'compare' (duration: 41.801023ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:20:25.702317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.564682ms","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-02-03T17:20:25.702367Z","caller":"traceutil/trace.go:171","msg":"trace[1344621889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:302; }","duration":"159.649391ms","start":"2026-02-03T17:20:25.542704Z","end":"2026-02-03T17:20:25.702354Z","steps":["trace[1344621889] 'agreement among raft nodes before linearized reading' (duration: 159.565552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:20:26.159708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.646965ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:20:26.15989Z","caller":"traceutil/trace.go:171","msg":"trace[945256130] linearizableReadLoop","detail":"{readStateIndex:313; appliedIndex:311; }","duration":"449.940757ms","start":"2026-02-03T17:20:25.709932Z","end":"2026-02-03T17:20:26.159872Z","steps":["trace[945256130] 'read index received' (duration: 317.064667ms)","trace[945256130] 'applied index is now lower than readState.Index' (duration: 132.87489ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:20:26.16026Z","caller":"traceutil/trace.go:171","msg":"trace[1256473428] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"450.467074ms","start":"2026-02-03T17:20:25.709778Z","end":"2026-02-03T17:20:26.160245Z","steps":["trace[1256473428] 'process raft request' (duration: 317.196624ms)","trace[1256473428] 'compare' (duration: 131.372304ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:20:26.160328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:20:25.709765Z","time spent":"450.527123ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:20:26.160536Z","caller":"traceutil/trace.go:171","msg":"trace[1766763596] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"450.665249ms","start":"2026-02-03T17:20:25.709861Z","end":"2026-02-03T17:20:26.160526Z","steps":["trace[1766763596] 'process raft request' (duration: 449.955756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:20:26.16059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:20:25.709846Z","time spent":"450.723818ms","remote":"127.0.0.1:54906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2629,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-03T17:20:26.160716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.779077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:20:26.160752Z","caller":"traceutil/trace.go:171","msg":"trace[382504531] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-controller; range_end:; response_count:0; response_revision:306; }","duration":"450.829135ms","start":"2026-02-03T17:20:25.709913Z","end":"2026-02-03T17:20:26.160742Z","steps":["trace[382504531] 'agreement among raft nodes before linearized reading' (duration: 450.774476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:20:26.160781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:20:25.709907Z","time spent":"450.866664ms","remote":"127.0.0.1:54638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/service-controller\" "} {"level":"warn","ts":"2026-02-03T17:20:26.160914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.833689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:20:26.160947Z","caller":"traceutil/trace.go:171","msg":"trace[344472693] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:306; }","duration":"374.906967ms","start":"2026-02-03T17:20:25.786032Z","end":"2026-02-03T17:20:26.160939Z","steps":["trace[344472693] 'agreement among raft nodes before linearized reading' (duration: 374.863088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:20:26.160972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:20:25.786012Z","time spent":"374.954777ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-03T17:20:26.161155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.990452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-03T17:20:26.161189Z","caller":"traceutil/trace.go:171","msg":"trace[418343603] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:306; }","duration":"207.05879ms","start":"2026-02-03T17:20:25.954123Z","end":"2026-02-03T17:20:26.161182Z","steps":["trace[418343603] 'agreement among raft nodes before linearized reading' (duration: 207.003472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:20:26.161996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.433485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2898"} {"level":"info","ts":"2026-02-03T17:20:26.16225Z","caller":"traceutil/trace.go:171","msg":"trace[1205520561] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:306; }","duration":"375.710028ms","start":"2026-02-03T17:20:25.786521Z","end":"2026-02-03T17:20:26.162231Z","steps":["trace[1205520561] 'agreement among raft nodes before linearized reading' (duration: 375.389096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:20:26.162468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:20:25.786514Z","time spent":"375.939863ms","remote":"127.0.0.1:54632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":2922,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-02-03T17:20:26.408207Z","caller":"traceutil/trace.go:171","msg":"trace[966241710] linearizableReadLoop","detail":"{readStateIndex:315; appliedIndex:314; }","duration":"236.126414ms","start":"2026-02-03T17:20:26.172059Z","end":"2026-02-03T17:20:26.408185Z","steps":["trace[966241710] 'read index received' (duration: 26.110968ms)","trace[966241710] 'applied index is now lower than readState.Index' (duration: 210.014606ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:20:26.408189Z","caller":"traceutil/trace.go:171","msg":"trace[1953980479] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"236.137344ms","start":"2026-02-03T17:20:26.17201Z","end":"2026-02-03T17:20:26.408147Z","steps":["trace[1953980479] 'process raft request' (duration: 155.166506ms)","trace[1953980479] 'compare' (duration: 80.726764ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:20:26.408351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.266912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-03T17:20:26.408384Z","caller":"traceutil/trace.go:171","msg":"trace[2069203437] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:310; }","duration":"236.335279ms","start":"2026-02-03T17:20:26.172039Z","end":"2026-02-03T17:20:26.408375Z","steps":["trace[2069203437] 'agreement among raft nodes before linearized reading' (duration: 236.234702ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:26.408943Z","caller":"traceutil/trace.go:171","msg":"trace[2118678089] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"236.861767ms","start":"2026-02-03T17:20:26.172066Z","end":"2026-02-03T17:20:26.408927Z","steps":["trace[2118678089] 'process raft request' (duration: 236.019366ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:26.409217Z","caller":"traceutil/trace.go:171","msg":"trace[1905195657] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"226.283636ms","start":"2026-02-03T17:20:26.182921Z","end":"2026-02-03T17:20:26.409205Z","steps":["trace[1905195657] 'process raft request' (duration: 225.21319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:20:26.715414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.971789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:20:26.716192Z","caller":"traceutil/trace.go:171","msg":"trace[1375818806] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"218.880909ms","start":"2026-02-03T17:20:26.497293Z","end":"2026-02-03T17:20:26.716174Z","steps":["trace[1375818806] 'process raft request' (duration: 218.563386ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:26.716788Z","caller":"traceutil/trace.go:171","msg":"trace[1252337221] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"234.934191ms","start":"2026-02-03T17:20:26.481838Z","end":"2026-02-03T17:20:26.716772Z","steps":["trace[1252337221] 'process raft request' (duration: 92.523996ms)","trace[1252337221] 'compare' (duration: 140.814802ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:20:26.717261Z","caller":"traceutil/trace.go:171","msg":"trace[1429528748] linearizableReadLoop","detail":"{readStateIndex:323; appliedIndex:322; }","duration":"220.021042ms","start":"2026-02-03T17:20:26.497227Z","end":"2026-02-03T17:20:26.717248Z","steps":["trace[1429528748] 'read index received' (duration: 77.218116ms)","trace[1429528748] 'applied index is now lower than readState.Index' (duration: 142.801336ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:20:26.717684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.446612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-03T17:20:26.71794Z","caller":"traceutil/trace.go:171","msg":"trace[976448656] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:317; }","duration":"220.688027ms","start":"2026-02-03T17:20:26.4972Z","end":"2026-02-03T17:20:26.717888Z","steps":["trace[976448656] 'agreement among raft nodes before linearized reading' (duration: 220.310766ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:26.983602Z","caller":"traceutil/trace.go:171","msg":"trace[999550752] linearizableReadLoop","detail":"{readStateIndex:326; appliedIndex:325; }","duration":"190.201713ms","start":"2026-02-03T17:20:26.793381Z","end":"2026-02-03T17:20:26.983583Z","steps":["trace[999550752] 'read index received' (duration: 123.05073ms)","trace[999550752] 'applied index is now lower than readState.Index' (duration: 67.150073ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:20:26.984247Z","caller":"traceutil/trace.go:171","msg":"trace[776656589] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"192.113997ms","start":"2026-02-03T17:20:26.792117Z","end":"2026-02-03T17:20:26.984231Z","steps":["trace[776656589] 'process raft request' (duration: 124.318719ms)","trace[776656589] 'compare' (duration: 67.056326ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:20:26.983717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.31395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-03T17:20:26.98691Z","caller":"traceutil/trace.go:171","msg":"trace[1100752534] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:319; }","duration":"193.543114ms","start":"2026-02-03T17:20:26.793352Z","end":"2026-02-03T17:20:26.986895Z","steps":["trace[1100752534] 'agreement among raft nodes before linearized reading' (duration: 190.29773ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:20:29.004434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.102864ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:20:29.004539Z","caller":"traceutil/trace.go:171","msg":"trace[1291104242] linearizableReadLoop","detail":"{readStateIndex:355; appliedIndex:354; }","duration":"260.972221ms","start":"2026-02-03T17:20:28.743552Z","end":"2026-02-03T17:20:29.004524Z","steps":["trace[1291104242] 'read index received' (duration: 133.618213ms)","trace[1291104242] 'applied index is now lower than readState.Index' (duration: 127.353068ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:20:29.004613Z","caller":"traceutil/trace.go:171","msg":"trace[826388086] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"285.715895ms","start":"2026-02-03T17:20:28.718872Z","end":"2026-02-03T17:20:29.004588Z","steps":["trace[826388086] 'process raft request' (duration: 158.390937ms)","trace[826388086] 'compare' (duration: 127.002726ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:20:29.004663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.097628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-03T17:20:29.004709Z","caller":"traceutil/trace.go:171","msg":"trace[1619686360] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:348; }","duration":"261.176126ms","start":"2026-02-03T17:20:28.743522Z","end":"2026-02-03T17:20:29.004698Z","steps":["trace[1619686360] 'agreement among raft nodes before linearized reading' (duration: 261.090358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:20:29.005055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.338561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-03T17:20:29.005072Z","caller":"traceutil/trace.go:171","msg":"trace[1110342156] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:348; }","duration":"114.38271ms","start":"2026-02-03T17:20:28.890683Z","end":"2026-02-03T17:20:29.005066Z","steps":["trace[1110342156] 'agreement among raft nodes before linearized reading' (duration: 114.356311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:20:29.005243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.471749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-03T17:20:29.005257Z","caller":"traceutil/trace.go:171","msg":"trace[1846386838] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:348; }","duration":"163.509439ms","start":"2026-02-03T17:20:28.841743Z","end":"2026-02-03T17:20:29.005252Z","steps":["trace[1846386838] 'agreement among raft nodes before linearized reading' (duration: 163.486579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:20:29.005809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.626499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-03T17:20:29.005948Z","caller":"traceutil/trace.go:171","msg":"trace[1067290425] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:348; }","duration":"211.716917ms","start":"2026-02-03T17:20:28.794144Z","end":"2026-02-03T17:20:29.005861Z","steps":["trace[1067290425] 'agreement among raft nodes before linearized reading' (duration: 210.70771ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:31.033891Z","caller":"traceutil/trace.go:171","msg":"trace[1770458927] linearizableReadLoop","detail":"{readStateIndex:403; appliedIndex:402; }","duration":"119.339675ms","start":"2026-02-03T17:20:30.91453Z","end":"2026-02-03T17:20:31.03387Z","steps":["trace[1770458927] 'read index received' (duration: 118.749328ms)","trace[1770458927] 'applied index is now lower than readState.Index' (duration: 589.527µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:20:31.033991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.443602ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:20:31.034068Z","caller":"traceutil/trace.go:171","msg":"trace[912120574] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:395; }","duration":"119.53381ms","start":"2026-02-03T17:20:30.914525Z","end":"2026-02-03T17:20:31.034059Z","steps":["trace[912120574] 'agreement among raft nodes before linearized reading' (duration: 119.427583ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:31.034339Z","caller":"traceutil/trace.go:171","msg":"trace[1436582784] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"185.521059ms","start":"2026-02-03T17:20:30.848804Z","end":"2026-02-03T17:20:31.034325Z","steps":["trace[1436582784] 'process raft request' (duration: 184.535712ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:31.16944Z","caller":"traceutil/trace.go:171","msg":"trace[422975044] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"126.253754ms","start":"2026-02-03T17:20:31.043152Z","end":"2026-02-03T17:20:31.169405Z","steps":["trace[422975044] 'process raft request' (duration: 123.973637ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:34.67654Z","caller":"traceutil/trace.go:171","msg":"trace[1025598265] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"109.388893ms","start":"2026-02-03T17:20:34.567128Z","end":"2026-02-03T17:20:34.676517Z","steps":["trace[1025598265] 'process raft request' (duration: 109.182278ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:35.902217Z","caller":"traceutil/trace.go:171","msg":"trace[2121200484] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"110.542406ms","start":"2026-02-03T17:20:35.79165Z","end":"2026-02-03T17:20:35.902192Z","steps":["trace[2121200484] 'process raft request' (duration: 110.406997ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:20:36.777265Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724054723917699,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T17:20:37.27762Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724054723917699,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T17:20:37.77795Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724054723917699,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T17:20:38.278816Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724054723917699,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T17:20:38.643053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000122703s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-03T17:20:38.643154Z","caller":"traceutil/trace.go:171","msg":"trace[2035094504] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000281965s","start":"2026-02-03T17:20:36.642853Z","end":"2026-02-03T17:20:38.643135Z","steps":["trace[2035094504] 'agreement among raft nodes before linearized reading' (duration: 2.000139525s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:20:38.643232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:20:36.642841Z","time spent":"2.000362871s","remote":"127.0.0.1:54426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-03T17:20:38.779469Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724054723917699,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T17:20:39.280313Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724054723917699,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T17:20:39.781431Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724054723917699,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T17:20:40.23563Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.960787195s","expected-duration":"1s"} {"level":"info","ts":"2026-02-03T17:20:40.236057Z","caller":"traceutil/trace.go:171","msg":"trace[1834013973] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"3.961268079s","start":"2026-02-03T17:20:36.274772Z","end":"2026-02-03T17:20:40.23604Z","steps":["trace[1834013973] 'process raft request' (duration: 3.961071605s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:20:40.236225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:20:36.274756Z","time spent":"3.961385607s","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:20:41.649885Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.356732403s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-03T17:20:41.68022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999191683s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-03T17:20:41.680264Z","caller":"traceutil/trace.go:171","msg":"trace[575924034] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999281179s","start":"2026-02-03T17:20:36.680971Z","end":"2026-02-03T17:20:41.680252Z","steps":["trace[575924034] 'agreement among raft nodes before linearized reading' (duration: 4.999221775s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:20:41.680291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:20:36.680925Z","time spent":"4.999360815s","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-03T17:20:42.968118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.318014118s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-03T17:20:42.968577Z","caller":"traceutil/trace.go:171","msg":"trace[148187955] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"1.498502593s","start":"2026-02-03T17:20:41.470056Z","end":"2026-02-03T17:20:42.968559Z","steps":["trace[148187955] 'process raft request' (duration: 1.498419607s)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:42.968651Z","caller":"traceutil/trace.go:171","msg":"trace[336994387] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:421; }","duration":"6.691914691s","start":"2026-02-03T17:20:36.276719Z","end":"2026-02-03T17:20:42.968634Z","steps":["trace[336994387] 'read index received' (duration: 3.959857653s)","trace[336994387] 'applied index is now lower than readState.Index' (duration: 2.732052697s)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:20:42.968812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:20:41.470033Z","time spent":"1.498675044s","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:20:42.968915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.672045311s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"info","ts":"2026-02-03T17:20:42.968968Z","caller":"traceutil/trace.go:171","msg":"trace[1884272281] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:414; }","duration":"5.672122466s","start":"2026-02-03T17:20:37.296836Z","end":"2026-02-03T17:20:42.968958Z","steps":["trace[1884272281] 'agreement among raft nodes before linearized reading' (duration: 5.672050691s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:20:42.969002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:20:37.296822Z","time spent":"5.67217333s","remote":"127.0.0.1:54632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6922,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-02-03T17:20:42.969274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.509042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-03T17:20:42.969313Z","caller":"traceutil/trace.go:171","msg":"trace[831950723] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:414; }","duration":"182.583478ms","start":"2026-02-03T17:20:42.786719Z","end":"2026-02-03T17:20:42.969302Z","steps":["trace[831950723] 'agreement among raft nodes before linearized reading' (duration: 182.501532ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:42.968568Z","caller":"traceutil/trace.go:171","msg":"trace[52327888] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"4.315236703s","start":"2026-02-03T17:20:38.653309Z","end":"2026-02-03T17:20:42.968546Z","steps":["trace[52327888] 'process raft request' (duration: 4.314998797s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:20:42.969481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.69731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-03T17:20:42.969517Z","caller":"traceutil/trace.go:171","msg":"trace[245151616] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:414; }","duration":"729.782315ms","start":"2026-02-03T17:20:42.239726Z","end":"2026-02-03T17:20:42.969508Z","steps":["trace[245151616] 'agreement among raft nodes before linearized reading' (duration: 729.724412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:20:42.969473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:20:38.653287Z","time spent":"4.316100951s","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":757,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-03T17:20:42.969582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.062647442s","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-02-03T17:20:42.96961Z","caller":"traceutil/trace.go:171","msg":"trace[1309020590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:414; }","duration":"5.062709047s","start":"2026-02-03T17:20:37.906894Z","end":"2026-02-03T17:20:42.969603Z","steps":["trace[1309020590] 'agreement among raft nodes before linearized reading' (duration: 5.062667294s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:20:42.969633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:20:37.906877Z","time spent":"5.062751079s","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T17:20:42.968861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.692128815s","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-02-03T17:20:42.969904Z","caller":"traceutil/trace.go:171","msg":"trace[583212489] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:414; }","duration":"6.693184316s","start":"2026-02-03T17:20:36.276704Z","end":"2026-02-03T17:20:42.969889Z","steps":["trace[583212489] 'agreement among raft nodes before linearized reading' (duration: 6.692019228s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:20:42.970001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:20:36.276698Z","time spent":"6.693288602s","remote":"127.0.0.1:54708","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-02-03T17:20:42.969544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:20:42.239711Z","time spent":"729.826708ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-03T17:20:43.175284Z","caller":"traceutil/trace.go:171","msg":"trace[1661292529] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"194.651528ms","start":"2026-02-03T17:20:42.980603Z","end":"2026-02-03T17:20:43.175254Z","steps":["trace[1661292529] 'process raft request' (duration: 194.622106ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:43.176193Z","caller":"traceutil/trace.go:171","msg":"trace[2002220407] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"196.990152ms","start":"2026-02-03T17:20:42.979193Z","end":"2026-02-03T17:20:43.176183Z","steps":["trace[2002220407] 'process raft request' (duration: 195.858148ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:43.176304Z","caller":"traceutil/trace.go:171","msg":"trace[460025462] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"197.020304ms","start":"2026-02-03T17:20:42.979273Z","end":"2026-02-03T17:20:43.176294Z","steps":["trace[460025462] 'process raft request' (duration: 195.875319ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:43.176663Z","caller":"traceutil/trace.go:171","msg":"trace[987894519] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"196.225771ms","start":"2026-02-03T17:20:42.980407Z","end":"2026-02-03T17:20:43.176633Z","steps":["trace[987894519] 'process raft request' (duration: 194.780737ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:45.322425Z","caller":"traceutil/trace.go:171","msg":"trace[1399484766] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:434; }","duration":"132.056097ms","start":"2026-02-03T17:20:45.190342Z","end":"2026-02-03T17:20:45.322398Z","steps":["trace[1399484766] 'read index received' (duration: 22.880501ms)","trace[1399484766] 'applied index is now lower than readState.Index' (duration: 109.174756ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:20:45.322561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.188805ms","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-02-03T17:20:45.322555Z","caller":"traceutil/trace.go:171","msg":"trace[1502743985] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"133.162357ms","start":"2026-02-03T17:20:45.189374Z","end":"2026-02-03T17:20:45.322536Z","steps":["trace[1502743985] 'process raft request' (duration: 80.18373ms)","trace[1502743985] 'compare' (duration: 52.727381ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:20:45.322592Z","caller":"traceutil/trace.go:171","msg":"trace[1258963264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:424; }","duration":"132.25659ms","start":"2026-02-03T17:20:45.190325Z","end":"2026-02-03T17:20:45.322582Z","steps":["trace[1258963264] 'agreement among raft nodes before linearized reading' (duration: 132.168274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:20:46.447985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.488223ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:20:46.448092Z","caller":"traceutil/trace.go:171","msg":"trace[742503048] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:427; }","duration":"417.630842ms","start":"2026-02-03T17:20:46.030444Z","end":"2026-02-03T17:20:46.448075Z","steps":["trace[742503048] 'range keys from in-memory index tree' (duration: 417.475172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:20:46.448338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.030183ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:20:46.448379Z","caller":"traceutil/trace.go:171","msg":"trace[1490079936] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"757.676819ms","start":"2026-02-03T17:20:45.690693Z","end":"2026-02-03T17:20:46.44837Z","steps":["trace[1490079936] 'process raft request' (duration: 226.575931ms)","trace[1490079936] 'compare' (duration: 530.724484ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:20:46.448416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:20:45.690679Z","time spent":"757.718421ms","remote":"127.0.0.1:54922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3656,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-03T17:20:46.448808Z","caller":"traceutil/trace.go:171","msg":"trace[1238137679] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"756.068197ms","start":"2026-02-03T17:20:45.692727Z","end":"2026-02-03T17:20:46.448795Z","steps":["trace[1238137679] 'process raft request' (duration: 755.980411ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:20:46.448882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:20:45.692715Z","time spent":"756.126791ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:20:46.675585Z","caller":"traceutil/trace.go:171","msg":"trace[2059196012] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"217.823883ms","start":"2026-02-03T17:20:46.457745Z","end":"2026-02-03T17:20:46.675569Z","steps":["trace[2059196012] 'process raft request' (duration: 217.664683ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:46.675694Z","caller":"traceutil/trace.go:171","msg":"trace[1348927370] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"220.609805ms","start":"2026-02-03T17:20:46.455079Z","end":"2026-02-03T17:20:46.675689Z","steps":["trace[1348927370] 'process raft request' (duration: 132.128183ms)","trace[1348927370] 'compare' (duration: 88.105029ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:20:46.675738Z","caller":"traceutil/trace.go:171","msg":"trace[573727327] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"217.927309ms","start":"2026-02-03T17:20:46.4578Z","end":"2026-02-03T17:20:46.675727Z","steps":["trace[573727327] 'process raft request' (duration: 217.662702ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:46.863076Z","caller":"traceutil/trace.go:171","msg":"trace[1004746731] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"130.983462ms","start":"2026-02-03T17:20:46.73207Z","end":"2026-02-03T17:20:46.863054Z","steps":["trace[1004746731] 'process raft request' (duration: 130.884926ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:46.863097Z","caller":"traceutil/trace.go:171","msg":"trace[471904479] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"134.719572ms","start":"2026-02-03T17:20:46.72835Z","end":"2026-02-03T17:20:46.86307Z","steps":["trace[471904479] 'process raft request' (duration: 74.939355ms)","trace[471904479] 'compare' (duration: 59.445467ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:20:47.533633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.424395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-03T17:20:47.533842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.137692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-03T17:20:47.533931Z","caller":"traceutil/trace.go:171","msg":"trace[201598080] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:460; }","duration":"207.237868ms","start":"2026-02-03T17:20:47.326652Z","end":"2026-02-03T17:20:47.53389Z","steps":["trace[201598080] 'range keys from in-memory index tree' (duration: 207.005324ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:47.533881Z","caller":"traceutil/trace.go:171","msg":"trace[236473210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:460; }","duration":"148.732085ms","start":"2026-02-03T17:20:47.385134Z","end":"2026-02-03T17:20:47.533866Z","steps":["trace[236473210] 'range keys from in-memory index tree' (duration: 148.305969ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:48.566295Z","caller":"traceutil/trace.go:171","msg":"trace[85126568] linearizableReadLoop","detail":"{readStateIndex:477; appliedIndex:476; }","duration":"108.1396ms","start":"2026-02-03T17:20:48.458129Z","end":"2026-02-03T17:20:48.566268Z","steps":["trace[85126568] 'read index received' (duration: 107.919757ms)","trace[85126568] 'applied index is now lower than readState.Index' (duration: 218.903µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:20:48.566433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.277679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-03T17:20:48.566456Z","caller":"traceutil/trace.go:171","msg":"trace[1692282811] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:463; }","duration":"108.359973ms","start":"2026-02-03T17:20:48.458089Z","end":"2026-02-03T17:20:48.566449Z","steps":["trace[1692282811] 'agreement among raft nodes before linearized reading' (duration: 108.269728ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:48.566687Z","caller":"traceutil/trace.go:171","msg":"trace[832715864] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"133.315762ms","start":"2026-02-03T17:20:48.433362Z","end":"2026-02-03T17:20:48.566678Z","steps":["trace[832715864] 'process raft request' (duration: 132.757559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:20:49.463248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.406807ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:20:49.463322Z","caller":"traceutil/trace.go:171","msg":"trace[1805568795] linearizableReadLoop","detail":"{readStateIndex:478; appliedIndex:477; }","duration":"287.353281ms","start":"2026-02-03T17:20:49.175958Z","end":"2026-02-03T17:20:49.463311Z","steps":["trace[1805568795] 'read index received' (duration: 36.102µs)","trace[1805568795] 'applied index is now lower than readState.Index' (duration: 287.316519ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:20:49.463553Z","caller":"traceutil/trace.go:171","msg":"trace[264513388] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"890.329811ms","start":"2026-02-03T17:20:48.573207Z","end":"2026-02-03T17:20:49.463537Z","steps":["trace[264513388] 'process raft request' (duration: 342.571094ms)","trace[264513388] 'compare' (duration: 547.309311ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:20:49.463588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:20:48.573184Z","time spent":"890.386284ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:20:49.463699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.741204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:20:49.463713Z","caller":"traceutil/trace.go:171","msg":"trace[1736521373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:464; }","duration":"287.798878ms","start":"2026-02-03T17:20:49.175909Z","end":"2026-02-03T17:20:49.463708Z","steps":["trace[1736521373] 'agreement among raft nodes before linearized reading' (duration: 287.772616ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:20:49.463805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.514812ms","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-02-03T17:20:49.463821Z","caller":"traceutil/trace.go:171","msg":"trace[952052321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:464; }","duration":"209.556094ms","start":"2026-02-03T17:20:49.25426Z","end":"2026-02-03T17:20:49.463816Z","steps":["trace[952052321] 'agreement among raft nodes before linearized reading' (duration: 209.522632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:20:49.740589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.5318ms","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-02-03T17:20:49.740672Z","caller":"traceutil/trace.go:171","msg":"trace[1752750243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:465; }","duration":"169.657517ms","start":"2026-02-03T17:20:49.571001Z","end":"2026-02-03T17:20:49.740658Z","steps":["trace[1752750243] 'range keys from in-memory index tree' (duration: 169.448145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:20:49.74092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.189232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-03T17:20:49.740937Z","caller":"traceutil/trace.go:171","msg":"trace[2011023822] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:465; }","duration":"168.613076ms","start":"2026-02-03T17:20:49.572318Z","end":"2026-02-03T17:20:49.740931Z","steps":["trace[2011023822] 'range keys from in-memory index tree' (duration: 168.126428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:20:49.958851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.537207ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:20:49.958956Z","caller":"traceutil/trace.go:171","msg":"trace[269952252] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"214.579748ms","start":"2026-02-03T17:20:49.744357Z","end":"2026-02-03T17:20:49.958936Z","steps":["trace[269952252] 'process raft request' (duration: 112.863641ms)","trace[269952252] 'compare' (duration: 101.42943ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:20:51.745798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.690106ms","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-02-03T17:20:51.747388Z","caller":"traceutil/trace.go:171","msg":"trace[2099377680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:468; }","duration":"229.261955ms","start":"2026-02-03T17:20:51.518047Z","end":"2026-02-03T17:20:51.747309Z","steps":["trace[2099377680] 'range keys from in-memory index tree' (duration: 227.533758ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:55.641873Z","caller":"traceutil/trace.go:171","msg":"trace[1564396657] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"140.306939ms","start":"2026-02-03T17:20:55.50155Z","end":"2026-02-03T17:20:55.641857Z","steps":["trace[1564396657] 'process raft request' (duration: 140.212934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:20:56.186588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.339591ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:20:56.186731Z","caller":"traceutil/trace.go:171","msg":"trace[1964763713] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:480; }","duration":"157.503849ms","start":"2026-02-03T17:20:56.02921Z","end":"2026-02-03T17:20:56.186714Z","steps":["trace[1964763713] 'range keys from in-memory index tree' (duration: 157.32217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:20:56.186774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.730743ms","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-02-03T17:20:56.186819Z","caller":"traceutil/trace.go:171","msg":"trace[1108109942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:480; }","duration":"184.784566ms","start":"2026-02-03T17:20:56.002023Z","end":"2026-02-03T17:20:56.186807Z","steps":["trace[1108109942] 'range keys from in-memory index tree' (duration: 184.65553ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:20:56.186961Z","caller":"traceutil/trace.go:171","msg":"trace[992220652] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"167.353396ms","start":"2026-02-03T17:20:56.019593Z","end":"2026-02-03T17:20:56.186947Z","steps":["trace[992220652] 'process raft request' (duration: 71.128282ms)","trace[992220652] 'compare' (duration: 95.809883ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:21:00.152682Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724054723917906,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T17:21:00.639992Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.165840271s","expected-duration":"1s"} {"level":"info","ts":"2026-02-03T17:21:00.640346Z","caller":"traceutil/trace.go:171","msg":"trace[1270420619] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:536; }","duration":"988.515269ms","start":"2026-02-03T17:20:59.651814Z","end":"2026-02-03T17:21:00.640329Z","steps":["trace[1270420619] 'read index received' (duration: 988.276127ms)","trace[1270420619] 'applied index is now lower than readState.Index' (duration: 238.492µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:21:00.640607Z","caller":"traceutil/trace.go:171","msg":"trace[1398501322] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"1.166530004s","start":"2026-02-03T17:20:59.474066Z","end":"2026-02-03T17:21:00.640596Z","steps":["trace[1398501322] 'process raft request' (duration: 1.166065182s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:00.640721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:20:59.474035Z","time spent":"1.166623668s","remote":"127.0.0.1:54616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4211,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:21:00.640892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.083515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-03T17:21:00.640924Z","caller":"traceutil/trace.go:171","msg":"trace[710853658] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:521; }","duration":"989.147258ms","start":"2026-02-03T17:20:59.651767Z","end":"2026-02-03T17:21:00.640914Z","steps":["trace[710853658] 'agreement among raft nodes before linearized reading' (duration: 989.085896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:00.640944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:20:59.651748Z","time spent":"989.191011ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-03T17:21:00.641196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.366202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:21:00.641228Z","caller":"traceutil/trace.go:171","msg":"trace[26839282] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:521; }","duration":"466.397463ms","start":"2026-02-03T17:21:00.174823Z","end":"2026-02-03T17:21:00.64122Z","steps":["trace[26839282] 'agreement among raft nodes before linearized reading' (duration: 466.352601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:00.641249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:21:00.174782Z","time spent":"466.461716ms","remote":"127.0.0.1:54426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-03T17:21:00.641389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.91389ms","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-02-03T17:21:00.641531Z","caller":"traceutil/trace.go:171","msg":"trace[1038987578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:521; }","duration":"789.234956ms","start":"2026-02-03T17:20:59.852274Z","end":"2026-02-03T17:21:00.641509Z","steps":["trace[1038987578] 'agreement among raft nodes before linearized reading' (duration: 788.805784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:00.641656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:20:59.852259Z","time spent":"789.383433ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-03T17:21:00.642169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.971844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-03T17:21:00.642371Z","caller":"traceutil/trace.go:171","msg":"trace[79974939] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:521; }","duration":"651.205386ms","start":"2026-02-03T17:20:59.991152Z","end":"2026-02-03T17:21:00.642357Z","steps":["trace[79974939] 'agreement among raft nodes before linearized reading' (duration: 650.948444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:00.642535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:20:59.991136Z","time spent":"651.384594ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-03T17:21:00.643026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.144215ms","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-02-03T17:21:00.64316Z","caller":"traceutil/trace.go:171","msg":"trace[236089803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:521; }","duration":"388.317743ms","start":"2026-02-03T17:21:00.25483Z","end":"2026-02-03T17:21:00.643148Z","steps":["trace[236089803] 'agreement among raft nodes before linearized reading' (duration: 388.155556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:00.643293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:21:00.25481Z","time spent":"388.46512ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-03T17:21:06.492884Z","caller":"traceutil/trace.go:171","msg":"trace[746733511] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"284.153424ms","start":"2026-02-03T17:21:06.208715Z","end":"2026-02-03T17:21:06.492868Z","steps":["trace[746733511] 'process raft request' (duration: 284.05894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:07.905409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.796929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:21:07.905481Z","caller":"traceutil/trace.go:171","msg":"trace[487913961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:565; }","duration":"728.892544ms","start":"2026-02-03T17:21:07.176564Z","end":"2026-02-03T17:21:07.905457Z","steps":["trace[487913961] 'range keys from in-memory index tree' (duration: 728.697595ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:07.905509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:21:07.176548Z","time spent":"728.955446ms","remote":"127.0.0.1:54426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-03T17:21:08.678214Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724054723918001,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-03T17:21:08.811374Z","caller":"traceutil/trace.go:171","msg":"trace[1722021156] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"870.9546ms","start":"2026-02-03T17:21:07.940398Z","end":"2026-02-03T17:21:08.811353Z","steps":["trace[1722021156] 'process raft request' (duration: 870.816815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:08.811667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:21:07.940378Z","time spent":"871.213341ms","remote":"127.0.0.1:54632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:21:10.17733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000144019s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-03T17:21:10.177408Z","caller":"traceutil/trace.go:171","msg":"trace[340478409] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000270853s","start":"2026-02-03T17:21:08.177117Z","end":"2026-02-03T17:21:10.177388Z","steps":["trace[340478409] 'agreement among raft nodes before linearized reading' (duration: 2.000172299s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:10.177489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:21:08.177098Z","time spent":"2.000381448s","remote":"127.0.0.1:54426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-03T17:21:12.042367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.196655853s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-03T17:21:12.042473Z","caller":"traceutil/trace.go:171","msg":"trace[1381958506] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:583; }","duration":"3.865299484s","start":"2026-02-03T17:21:08.177157Z","end":"2026-02-03T17:21:12.042456Z","steps":["trace[1381958506] 'read index received' (duration: 634.321493ms)","trace[1381958506] 'applied index is now lower than readState.Index' (duration: 3.230976701s)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:21:12.042961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.36119568s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-03T17:21:12.042995Z","caller":"traceutil/trace.go:171","msg":"trace[772708328] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:566; }","duration":"3.361255993s","start":"2026-02-03T17:21:08.681728Z","end":"2026-02-03T17:21:12.042984Z","steps":["trace[772708328] 'agreement among raft nodes before linearized reading' (duration: 3.361110947s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:12.043026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:21:08.681716Z","time spent":"3.361303014s","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-03T17:21:12.043283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.865197945s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:21:12.043312Z","caller":"traceutil/trace.go:171","msg":"trace[1036050778] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:566; }","duration":"1.865259127s","start":"2026-02-03T17:21:10.178044Z","end":"2026-02-03T17:21:12.043303Z","steps":["trace[1036050778] 'agreement among raft nodes before linearized reading' (duration: 1.865209935s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:12.043357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:21:10.178032Z","time spent":"1.865317011s","remote":"127.0.0.1:54412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-03T17:21:12.043509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.330302152s","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-02-03T17:21:12.043538Z","caller":"traceutil/trace.go:171","msg":"trace[1930490244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:566; }","duration":"3.330339873s","start":"2026-02-03T17:21:08.713189Z","end":"2026-02-03T17:21:12.043529Z","steps":["trace[1930490244] 'agreement among raft nodes before linearized reading' (duration: 3.330289351s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:12.043556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:21:08.713184Z","time spent":"3.330369104s","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T17:21:12.04364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.339948648s","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-02-03T17:21:12.043652Z","caller":"traceutil/trace.go:171","msg":"trace[2027079906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:566; }","duration":"3.33998596s","start":"2026-02-03T17:21:08.703662Z","end":"2026-02-03T17:21:12.043648Z","steps":["trace[2027079906] 'agreement among raft nodes before linearized reading' (duration: 3.339946478s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:12.043664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:21:08.703651Z","time spent":"3.34000983s","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-03T17:21:12.04373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.361433829s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-03T17:21:12.043742Z","caller":"traceutil/trace.go:171","msg":"trace[2026495808] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:566; }","duration":"3.361456759s","start":"2026-02-03T17:21:08.682282Z","end":"2026-02-03T17:21:12.043739Z","steps":["trace[2026495808] 'agreement among raft nodes before linearized reading' (duration: 3.361433388s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:12.043753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:21:08.682278Z","time spent":"3.36147313s","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-03T17:21:13.105739Z","caller":"traceutil/trace.go:171","msg":"trace[55845096] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"142.390819ms","start":"2026-02-03T17:21:12.963332Z","end":"2026-02-03T17:21:13.105723Z","steps":["trace[55845096] 'process raft request' (duration: 121.268642ms)","trace[55845096] 'compare' (duration: 21.021623ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:21:17.290733Z","caller":"traceutil/trace.go:171","msg":"trace[1667528249] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"103.597351ms","start":"2026-02-03T17:21:17.187119Z","end":"2026-02-03T17:21:17.290716Z","steps":["trace[1667528249] 'process raft request' (duration: 103.508048ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:21:19.121966Z","caller":"traceutil/trace.go:171","msg":"trace[2131597020] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"144.699896ms","start":"2026-02-03T17:21:18.97725Z","end":"2026-02-03T17:21:19.12195Z","steps":["trace[2131597020] 'read index received' (duration: 45.933485ms)","trace[2131597020] 'applied index is now lower than readState.Index' (duration: 98.765951ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:21:19.122103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.8408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zz8mj\" ","response":"range_response_count:1 size:18075"} {"level":"info","ts":"2026-02-03T17:21:19.122133Z","caller":"traceutil/trace.go:171","msg":"trace[195739994] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zz8mj; range_end:; response_count:1; response_revision:652; }","duration":"144.872071ms","start":"2026-02-03T17:21:18.977242Z","end":"2026-02-03T17:21:19.122114Z","steps":["trace[195739994] 'agreement among raft nodes before linearized reading' (duration: 144.774078ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:21:19.122354Z","caller":"traceutil/trace.go:171","msg":"trace[416534311] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"149.902961ms","start":"2026-02-03T17:21:18.972444Z","end":"2026-02-03T17:21:19.122347Z","steps":["trace[416534311] 'process raft request' (duration: 149.330732ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:21:19.122489Z","caller":"traceutil/trace.go:171","msg":"trace[1202357776] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"107.128478ms","start":"2026-02-03T17:21:19.015356Z","end":"2026-02-03T17:21:19.122485Z","steps":["trace[1202357776] 'process raft request' (duration: 106.528328ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:21:19.320347Z","caller":"traceutil/trace.go:171","msg":"trace[1941920591] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"155.087221ms","start":"2026-02-03T17:21:19.165248Z","end":"2026-02-03T17:21:19.320335Z","steps":["trace[1941920591] 'process raft request' (duration: 155.009518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:19.590062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.961686ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:21:19.590314Z","caller":"traceutil/trace.go:171","msg":"trace[610804795] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"416.159002ms","start":"2026-02-03T17:21:19.174134Z","end":"2026-02-03T17:21:19.590293Z","steps":["trace[610804795] 'process raft request' (duration: 273.919376ms)","trace[610804795] 'compare' (duration: 141.857113ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:21:19.590393Z","caller":"traceutil/trace.go:171","msg":"trace[812841127] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"411.54643ms","start":"2026-02-03T17:21:19.178837Z","end":"2026-02-03T17:21:19.590384Z","steps":["trace[812841127] 'read index received' (duration: 141.861594ms)","trace[812841127] 'applied index is now lower than readState.Index' (duration: 269.684335ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:21:19.590411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:21:19.174123Z","time spent":"416.233925ms","remote":"127.0.0.1:54616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4086,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:21:19.590625Z","caller":"traceutil/trace.go:171","msg":"trace[1290249479] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"276.471099ms","start":"2026-02-03T17:21:19.314147Z","end":"2026-02-03T17:21:19.590618Z","steps":["trace[1290249479] 'process raft request' (duration: 276.074045ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:19.590694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.853059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:21:19.590714Z","caller":"traceutil/trace.go:171","msg":"trace[107200977] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:655; }","duration":"411.889031ms","start":"2026-02-03T17:21:19.178818Z","end":"2026-02-03T17:21:19.590708Z","steps":["trace[107200977] 'agreement among raft nodes before linearized reading' (duration: 411.8552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:19.59073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:21:19.178813Z","time spent":"411.913462ms","remote":"127.0.0.1:54426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-03T17:21:19.59371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.431623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-03T17:21:19.593921Z","caller":"traceutil/trace.go:171","msg":"trace[1736938676] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:656; }","duration":"400.668541ms","start":"2026-02-03T17:21:19.193241Z","end":"2026-02-03T17:21:19.59391Z","steps":["trace[1736938676] 'agreement among raft nodes before linearized reading' (duration: 400.377821ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:21:19.59396Z","caller":"traceutil/trace.go:171","msg":"trace[479573243] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"174.051647ms","start":"2026-02-03T17:21:19.419878Z","end":"2026-02-03T17:21:19.59393Z","steps":["trace[479573243] 'process raft request' (duration: 173.631214ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:19.594013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:21:19.19323Z","time spent":"400.773414ms","remote":"127.0.0.1:54458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24268,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"info","ts":"2026-02-03T17:21:19.835922Z","caller":"traceutil/trace.go:171","msg":"trace[2050736991] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:678; }","duration":"111.545813ms","start":"2026-02-03T17:21:19.724324Z","end":"2026-02-03T17:21:19.83587Z","steps":["trace[2050736991] 'read index received' (duration: 31.947795ms)","trace[2050736991] 'applied index is now lower than readState.Index' (duration: 79.597308ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:21:19.846841Z","caller":"traceutil/trace.go:171","msg":"trace[1089349626] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"228.180314ms","start":"2026-02-03T17:21:19.618647Z","end":"2026-02-03T17:21:19.846827Z","steps":["trace[1089349626] 'process raft request' (duration: 216.830809ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:21:19.848997Z","caller":"traceutil/trace.go:171","msg":"trace[839001973] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"230.423549ms","start":"2026-02-03T17:21:19.618564Z","end":"2026-02-03T17:21:19.848988Z","steps":["trace[839001973] 'process raft request' (duration: 137.699687ms)","trace[839001973] 'compare' (duration: 78.668498ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:21:19.849281Z","caller":"traceutil/trace.go:171","msg":"trace[869118826] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"216.352034ms","start":"2026-02-03T17:21:19.628638Z","end":"2026-02-03T17:21:19.84499Z","steps":["trace[869118826] 'process raft request' (duration: 206.927883ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:21:19.852568Z","caller":"traceutil/trace.go:171","msg":"trace[1388742203] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"233.883342ms","start":"2026-02-03T17:21:19.618673Z","end":"2026-02-03T17:21:19.852556Z","steps":["trace[1388742203] 'process raft request' (duration: 216.84507ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:21:19.853712Z","caller":"traceutil/trace.go:171","msg":"trace[1277372881] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"141.723389ms","start":"2026-02-03T17:21:19.703276Z","end":"2026-02-03T17:21:19.844999Z","steps":["trace[1277372881] 'process raft request' (duration: 132.318719ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:19.853883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.543738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-k9vqn\" ","response":"range_response_count:1 size:3849"} {"level":"info","ts":"2026-02-03T17:21:19.853902Z","caller":"traceutil/trace.go:171","msg":"trace[488573421] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-k9vqn; range_end:; response_count:1; response_revision:662; }","duration":"129.59875ms","start":"2026-02-03T17:21:19.724297Z","end":"2026-02-03T17:21:19.853896Z","steps":["trace[488573421] 'agreement among raft nodes before linearized reading' (duration: 120.705235ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:21:26.195834Z","caller":"traceutil/trace.go:171","msg":"trace[1570888550] linearizableReadLoop","detail":"{readStateIndex:753; appliedIndex:751; }","duration":"142.244267ms","start":"2026-02-03T17:21:26.053542Z","end":"2026-02-03T17:21:26.195786Z","steps":["trace[1570888550] 'read index received' (duration: 14.664858ms)","trace[1570888550] 'applied index is now lower than readState.Index' (duration: 127.578439ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:21:26.196061Z","caller":"traceutil/trace.go:171","msg":"trace[1091459535] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"142.567208ms","start":"2026-02-03T17:21:26.053453Z","end":"2026-02-03T17:21:26.196021Z","steps":["trace[1091459535] 'process raft request' (duration: 82.220661ms)","trace[1091459535] 'compare' (duration: 59.990836ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:21:26.197224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.62381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-k9vqn\" ","response":"range_response_count:1 size:5023"} {"level":"info","ts":"2026-02-03T17:21:26.19742Z","caller":"traceutil/trace.go:171","msg":"trace[720219433] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-k9vqn; range_end:; response_count:1; response_revision:729; }","duration":"143.744791ms","start":"2026-02-03T17:21:26.053521Z","end":"2026-02-03T17:21:26.197266Z","steps":["trace[720219433] 'agreement among raft nodes before linearized reading' (duration: 142.525587ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:21:42.297091Z","caller":"traceutil/trace.go:171","msg":"trace[1145090726] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"166.19686ms","start":"2026-02-03T17:21:42.130882Z","end":"2026-02-03T17:21:42.297079Z","steps":["trace[1145090726] 'process raft request' (duration: 165.882264ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:21:52.545515Z","caller":"traceutil/trace.go:171","msg":"trace[1136532117] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"169.662232ms","start":"2026-02-03T17:21:52.375846Z","end":"2026-02-03T17:21:52.545508Z","steps":["trace[1136532117] 'process raft request' (duration: 169.302607ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:21:52.545324Z","caller":"traceutil/trace.go:171","msg":"trace[579120976] linearizableReadLoop","detail":"{readStateIndex:865; appliedIndex:864; }","duration":"165.978244ms","start":"2026-02-03T17:21:52.379325Z","end":"2026-02-03T17:21:52.545303Z","steps":["trace[579120976] 'read index received' (duration: 165.772121ms)","trace[579120976] 'applied index is now lower than readState.Index' (duration: 205.523µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:21:52.546029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.696856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-03T17:21:52.547256Z","caller":"traceutil/trace.go:171","msg":"trace[1906658394] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:835; }","duration":"166.747007ms","start":"2026-02-03T17:21:52.379301Z","end":"2026-02-03T17:21:52.546048Z","steps":["trace[1906658394] 'agreement among raft nodes before linearized reading' (duration: 166.431882ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:21:56.752634Z","caller":"traceutil/trace.go:171","msg":"trace[123295576] linearizableReadLoop","detail":"{readStateIndex:878; appliedIndex:877; }","duration":"182.341744ms","start":"2026-02-03T17:21:56.570276Z","end":"2026-02-03T17:21:56.752618Z","steps":["trace[123295576] 'read index received' (duration: 182.179511ms)","trace[123295576] 'applied index is now lower than readState.Index' (duration: 161.413µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:21:56.752802Z","caller":"traceutil/trace.go:171","msg":"trace[1723827037] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"190.4402ms","start":"2026-02-03T17:21:56.562354Z","end":"2026-02-03T17:21:56.752794Z","steps":["trace[1723827037] 'process raft request' (duration: 190.155966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:56.752983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.693229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-03T17:21:56.753005Z","caller":"traceutil/trace.go:171","msg":"trace[495990387] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:847; }","duration":"182.74638ms","start":"2026-02-03T17:21:56.570253Z","end":"2026-02-03T17:21:56.752999Z","steps":["trace[495990387] 'agreement among raft nodes before linearized reading' (duration: 182.657119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:56.75316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.442767ms","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-02-03T17:21:56.753189Z","caller":"traceutil/trace.go:171","msg":"trace[882909117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:847; }","duration":"157.520608ms","start":"2026-02-03T17:21:56.59566Z","end":"2026-02-03T17:21:56.753181Z","steps":["trace[882909117] 'agreement among raft nodes before linearized reading' (duration: 157.427226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:21:56.753395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.988061ms","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-02-03T17:21:56.75342Z","caller":"traceutil/trace.go:171","msg":"trace[194537891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:847; }","duration":"157.025922ms","start":"2026-02-03T17:21:56.596387Z","end":"2026-02-03T17:21:56.753413Z","steps":["trace[194537891] 'agreement among raft nodes before linearized reading' (duration: 156.962041ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:02.992411Z","caller":"traceutil/trace.go:171","msg":"trace[655053846] linearizableReadLoop","detail":"{readStateIndex:898; appliedIndex:897; }","duration":"156.896106ms","start":"2026-02-03T17:22:02.835498Z","end":"2026-02-03T17:22:02.992394Z","steps":["trace[655053846] 'read index received' (duration: 76.706936ms)","trace[655053846] 'applied index is now lower than readState.Index' (duration: 80.18855ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:22:02.99265Z","caller":"traceutil/trace.go:171","msg":"trace[1028986106] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"182.250275ms","start":"2026-02-03T17:22:02.810392Z","end":"2026-02-03T17:22:02.992643Z","steps":["trace[1028986106] 'process raft request' (duration: 101.851463ms)","trace[1028986106] 'compare' (duration: 80.082998ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:22:02.992776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.26563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-03T17:22:02.992793Z","caller":"traceutil/trace.go:171","msg":"trace[1395717009] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:865; }","duration":"157.31062ms","start":"2026-02-03T17:22:02.835476Z","end":"2026-02-03T17:22:02.992787Z","steps":["trace[1395717009] 'agreement among raft nodes before linearized reading' (duration: 157.26702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:22:02.992923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.748047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-03T17:22:02.992936Z","caller":"traceutil/trace.go:171","msg":"trace[181146404] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:865; }","duration":"130.796747ms","start":"2026-02-03T17:22:02.862136Z","end":"2026-02-03T17:22:02.992932Z","steps":["trace[181146404] 'agreement among raft nodes before linearized reading' (duration: 130.726956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:22:02.993081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.841349ms","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-02-03T17:22:02.993095Z","caller":"traceutil/trace.go:171","msg":"trace[703582184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:865; }","duration":"117.885359ms","start":"2026-02-03T17:22:02.875205Z","end":"2026-02-03T17:22:02.99309Z","steps":["trace[703582184] 'agreement among raft nodes before linearized reading' (duration: 117.848448ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:03.166088Z","caller":"traceutil/trace.go:171","msg":"trace[1891657216] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"165.832201ms","start":"2026-02-03T17:22:03.000233Z","end":"2026-02-03T17:22:03.166066Z","steps":["trace[1891657216] 'process raft request' (duration: 165.78988ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:03.166579Z","caller":"traceutil/trace.go:171","msg":"trace[185303630] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"166.840773ms","start":"2026-02-03T17:22:02.999724Z","end":"2026-02-03T17:22:03.166565Z","steps":["trace[185303630] 'process raft request' (duration: 109.586562ms)","trace[185303630] 'compare' (duration: 56.593113ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:22:03.400313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.845436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4912"} {"level":"info","ts":"2026-02-03T17:22:03.400389Z","caller":"traceutil/trace.go:171","msg":"trace[102905132] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:868; }","duration":"110.957656ms","start":"2026-02-03T17:22:03.289414Z","end":"2026-02-03T17:22:03.400371Z","steps":["trace[102905132] 'range keys from in-memory index tree' (duration: 110.693153ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:05.157344Z","caller":"traceutil/trace.go:171","msg":"trace[761829855] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"132.985661ms","start":"2026-02-03T17:22:05.024336Z","end":"2026-02-03T17:22:05.157322Z","steps":["trace[761829855] 'process raft request' (duration: 132.903191ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:05.157706Z","caller":"traceutil/trace.go:171","msg":"trace[819939808] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"134.490838ms","start":"2026-02-03T17:22:05.023203Z","end":"2026-02-03T17:22:05.157694Z","steps":["trace[819939808] 'process raft request' (duration: 82.748008ms)","trace[819939808] 'compare' (duration: 50.804539ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:22:09.363141Z","caller":"traceutil/trace.go:171","msg":"trace[1608305263] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"181.829222ms","start":"2026-02-03T17:22:09.181291Z","end":"2026-02-03T17:22:09.36312Z","steps":["trace[1608305263] 'process raft request' (duration: 181.694021ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:09.37724Z","caller":"traceutil/trace.go:171","msg":"trace[1906683668] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:916; }","duration":"194.009423ms","start":"2026-02-03T17:22:09.183212Z","end":"2026-02-03T17:22:09.377222Z","steps":["trace[1906683668] 'read index received' (duration: 179.960213ms)","trace[1906683668] 'applied index is now lower than readState.Index' (duration: 14.04858ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:22:09.377438Z","caller":"traceutil/trace.go:171","msg":"trace[1343849729] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"195.798111ms","start":"2026-02-03T17:22:09.181633Z","end":"2026-02-03T17:22:09.377431Z","steps":["trace[1343849729] 'process raft request' (duration: 195.510718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:22:09.377628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.399436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:22:09.377657Z","caller":"traceutil/trace.go:171","msg":"trace[883661054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:883; }","duration":"194.452847ms","start":"2026-02-03T17:22:09.183198Z","end":"2026-02-03T17:22:09.37765Z","steps":["trace[883661054] 'agreement among raft nodes before linearized reading' (duration: 194.400126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:22:09.37782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.722021ms","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-02-03T17:22:09.377843Z","caller":"traceutil/trace.go:171","msg":"trace[1538235139] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:883; }","duration":"156.771211ms","start":"2026-02-03T17:22:09.221066Z","end":"2026-02-03T17:22:09.377838Z","steps":["trace[1538235139] 'agreement among raft nodes before linearized reading' (duration: 156.679181ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:22:09.378002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.074455ms","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-02-03T17:22:09.378026Z","caller":"traceutil/trace.go:171","msg":"trace[593122672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:883; }","duration":"156.127445ms","start":"2026-02-03T17:22:09.221894Z","end":"2026-02-03T17:22:09.378021Z","steps":["trace[593122672] 'agreement among raft nodes before linearized reading' (duration: 156.066025ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:41.059342Z","caller":"traceutil/trace.go:171","msg":"trace[1599896517] linearizableReadLoop","detail":"{readStateIndex:1049; appliedIndex:1048; }","duration":"107.108221ms","start":"2026-02-03T17:22:40.952206Z","end":"2026-02-03T17:22:41.059314Z","steps":["trace[1599896517] 'read index received' (duration: 106.930829ms)","trace[1599896517] 'applied index is now lower than readState.Index' (duration: 176.092µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:22:41.059567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.340951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:22:41.059608Z","caller":"traceutil/trace.go:171","msg":"trace[427062024] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"164.424865ms","start":"2026-02-03T17:22:40.895164Z","end":"2026-02-03T17:22:41.059589Z","steps":["trace[427062024] 'process raft request' (duration: 163.986503ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:41.059696Z","caller":"traceutil/trace.go:171","msg":"trace[1162279894] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1008; }","duration":"107.463471ms","start":"2026-02-03T17:22:40.952177Z","end":"2026-02-03T17:22:41.05964Z","steps":["trace[1162279894] 'agreement among raft nodes before linearized reading' (duration: 107.335091ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:59.928347Z","caller":"traceutil/trace.go:171","msg":"trace[1909431276] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"249.672608ms","start":"2026-02-03T17:22:59.678654Z","end":"2026-02-03T17:22:59.928326Z","steps":["trace[1909431276] 'process raft request' (duration: 249.546738ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:22:59.928699Z","caller":"traceutil/trace.go:171","msg":"trace[156844396] linearizableReadLoop","detail":"{readStateIndex:1110; appliedIndex:1109; }","duration":"228.23063ms","start":"2026-02-03T17:22:59.700444Z","end":"2026-02-03T17:22:59.928675Z","steps":["trace[156844396] 'read index received' (duration: 227.65407ms)","trace[156844396] 'applied index is now lower than readState.Index' (duration: 574.25µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:22:59.929055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.591529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-node-lease/\" range_end:\"/registry/roles/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:22:59.92915Z","caller":"traceutil/trace.go:171","msg":"trace[1261692139] range","detail":"{range_begin:/registry/roles/kube-node-lease/; range_end:/registry/roles/kube-node-lease0; response_count:0; response_revision:1065; }","duration":"228.737379ms","start":"2026-02-03T17:22:59.700396Z","end":"2026-02-03T17:22:59.929134Z","steps":["trace[1261692139] 'agreement among raft nodes before linearized reading' (duration: 228.515999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:22:59.929516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.252765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-node-lease/\" range_end:\"/registry/secrets/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:22:59.930643Z","caller":"traceutil/trace.go:171","msg":"trace[1736179226] range","detail":"{range_begin:/registry/secrets/kube-node-lease/; range_end:/registry/secrets/kube-node-lease0; response_count:0; response_revision:1065; }","duration":"200.394844ms","start":"2026-02-03T17:22:59.730223Z","end":"2026-02-03T17:22:59.930618Z","steps":["trace[1736179226] 'agreement among raft nodes before linearized reading' (duration: 199.262335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:22:59.929631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.300124ms","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-02-03T17:22:59.931168Z","caller":"traceutil/trace.go:171","msg":"trace[1627779611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1065; }","duration":"107.845013ms","start":"2026-02-03T17:22:59.82331Z","end":"2026-02-03T17:22:59.931155Z","steps":["trace[1627779611] 'agreement among raft nodes before linearized reading' (duration: 106.229104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:22:59.929695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.402104ms","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-02-03T17:22:59.932016Z","caller":"traceutil/trace.go:171","msg":"trace[530992325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1065; }","duration":"108.751873ms","start":"2026-02-03T17:22:59.823251Z","end":"2026-02-03T17:22:59.932003Z","steps":["trace[530992325] 'agreement among raft nodes before linearized reading' (duration: 106.394414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:22:59.929949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.830592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-03T17:22:59.932599Z","caller":"traceutil/trace.go:171","msg":"trace[1090335217] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1065; }","duration":"112.56361ms","start":"2026-02-03T17:22:59.820023Z","end":"2026-02-03T17:22:59.932586Z","steps":["trace[1090335217] 'agreement among raft nodes before linearized reading' (duration: 109.686012ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:23:00.082448Z","caller":"traceutil/trace.go:171","msg":"trace[456774460] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"142.096474ms","start":"2026-02-03T17:22:59.940327Z","end":"2026-02-03T17:23:00.082424Z","steps":["trace[456774460] 'process raft request' (duration: 139.808496ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:23:00.083338Z","caller":"traceutil/trace.go:171","msg":"trace[1325416312] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"139.604736ms","start":"2026-02-03T17:22:59.943721Z","end":"2026-02-03T17:23:00.083326Z","steps":["trace[1325416312] 'process raft request' (duration: 139.499936ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:23:00.084673Z","caller":"traceutil/trace.go:171","msg":"trace[1745801217] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"139.930235ms","start":"2026-02-03T17:22:59.944726Z","end":"2026-02-03T17:23:00.084656Z","steps":["trace[1745801217] 'process raft request' (duration: 138.567097ms)"],"step_count":1}