{"level":"warn","ts":"2026-03-25T21:01:40.053419Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-25T21:01:40.053539Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.209: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.209:2380","--initial-cluster=instance=https://199.204.45.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.209: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-03-25T21:01:40.053628Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-25T21:01:40.053641Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.209:2380"]} {"level":"info","ts":"2026-03-25T21:01:40.05366Z","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-03-25T21:01:40.054561Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"]} {"level":"info","ts":"2026-03-25T21:01:40.054665Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.209:2380"],"listen-peer-urls":["https://199.204.45.209:2380"],"advertise-client-urls":["https://199.204.45.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.209: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-03-25T21:01:40.060797Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.867209ms"} {"level":"info","ts":"2026-03-25T21:01:40.066596Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"adfb641115bf6cbd","cluster-id":"3da6b677d4b80c6"} {"level":"info","ts":"2026-03-25T21:01:40.066664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=()"} {"level":"info","ts":"2026-03-25T21:01:40.066686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became follower at term 0"} {"level":"info","ts":"2026-03-25T21:01:40.066696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft adfb641115bf6cbd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-25T21:01:40.066705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became follower at term 1"} {"level":"info","ts":"2026-03-25T21:01:40.066738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=(12536724012257995965)"} {"level":"warn","ts":"2026-03-25T21:01:40.074356Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-25T21:01:40.076025Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-25T21:01:40.077604Z","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-03-25T21:01:40.079468Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"adfb641115bf6cbd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-25T21:01:40.081367Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"adfb641115bf6cbd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-25T21:01:40.081463Z","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-03-25T21:01:40.081646Z","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-03-25T21:01:40.081668Z","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-03-25T21:01:40.081891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=(12536724012257995965)"} {"level":"info","ts":"2026-03-25T21:01:40.082051Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3da6b677d4b80c6","local-member-id":"adfb641115bf6cbd","added-peer-id":"adfb641115bf6cbd","added-peer-peer-urls":["https://199.204.45.209:2380"]} {"level":"info","ts":"2026-03-25T21:01:40.087556Z","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-03-25T21:01:40.087809Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"adfb641115bf6cbd","initial-advertise-peer-urls":["https://199.204.45.209:2380"],"listen-peer-urls":["https://199.204.45.209:2380"],"advertise-client-urls":["https://199.204.45.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-25T21:01:40.087843Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-25T21:01:40.087969Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.209:2380"} {"level":"info","ts":"2026-03-25T21:01:40.087991Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.209:2380"} {"level":"info","ts":"2026-03-25T21:01:40.967848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd is starting a new election at term 1"} {"level":"info","ts":"2026-03-25T21:01:40.967923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became pre-candidate at term 1"} {"level":"info","ts":"2026-03-25T21:01:40.967971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd received MsgPreVoteResp from adfb641115bf6cbd at term 1"} {"level":"info","ts":"2026-03-25T21:01:40.967985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became candidate at term 2"} {"level":"info","ts":"2026-03-25T21:01:40.967992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd received MsgVoteResp from adfb641115bf6cbd at term 2"} {"level":"info","ts":"2026-03-25T21:01:40.968003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became leader at term 2"} {"level":"info","ts":"2026-03-25T21:01:40.968091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: adfb641115bf6cbd elected leader adfb641115bf6cbd at term 2"} {"level":"info","ts":"2026-03-25T21:01:40.98115Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T21:01:40.988557Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"adfb641115bf6cbd","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.209:2379]}","request-path":"/0/members/adfb641115bf6cbd/attributes","cluster-id":"3da6b677d4b80c6","publish-timeout":"7s"} {"level":"info","ts":"2026-03-25T21:01:40.988627Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T21:01:40.988583Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T21:01:40.988986Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-25T21:01:40.989066Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-25T21:01:40.990472Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3da6b677d4b80c6","local-member-id":"adfb641115bf6cbd","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T21:01:40.990783Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T21:01:40.99083Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T21:01:40.992414Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-25T21:01:40.992534Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.209:2379"} {"level":"warn","ts":"2026-03-25T21:01:42.511317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.741725ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-25T21:01:42.511535Z","caller":"traceutil/trace.go:171","msg":"trace[1595101341] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"254.367803ms","start":"2026-03-25T21:01:42.257115Z","end":"2026-03-25T21:01:42.511483Z","steps":["trace[1595101341] 'process raft request' (duration: 142.876063ms)","trace[1595101341] 'compare' (duration: 110.556361ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:01:42.511629Z","caller":"traceutil/trace.go:171","msg":"trace[2061159763] linearizableReadLoop","detail":"{readStateIndex:7; appliedIndex:6; }","duration":"254.117188ms","start":"2026-03-25T21:01:42.257471Z","end":"2026-03-25T21:01:42.511588Z","steps":["trace[2061159763] 'read index received' (duration: 142.497885ms)","trace[2061159763] 'applied index is now lower than readState.Index' (duration: 111.616473ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:01:42.511967Z","caller":"traceutil/trace.go:171","msg":"trace[1013772977] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"251.046365ms","start":"2026-03-25T21:01:42.260908Z","end":"2026-03-25T21:01:42.511955Z","steps":["trace[1013772977] 'process raft request' (duration: 250.530655ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:01:42.511947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.441964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T21:01:42.512048Z","caller":"traceutil/trace.go:171","msg":"trace[438851588] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:5; }","duration":"254.576168ms","start":"2026-03-25T21:01:42.257444Z","end":"2026-03-25T21:01:42.51202Z","steps":["trace[438851588] 'agreement among raft nodes before linearized reading' (duration: 254.280382ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:42.512095Z","caller":"traceutil/trace.go:171","msg":"trace[2139548679] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"251.160398ms","start":"2026-03-25T21:01:42.260923Z","end":"2026-03-25T21:01:42.512084Z","steps":["trace[2139548679] 'process raft request' (duration: 250.77928ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:42.512191Z","caller":"traceutil/trace.go:171","msg":"trace[1719259318] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"251.144128ms","start":"2026-03-25T21:01:42.261039Z","end":"2026-03-25T21:01:42.512183Z","steps":["trace[1719259318] 'process raft request' (duration: 250.932114ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:42.512359Z","caller":"traceutil/trace.go:171","msg":"trace[2127859636] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"251.309171ms","start":"2026-03-25T21:01:42.261036Z","end":"2026-03-25T21:01:42.512345Z","steps":["trace[2127859636] 'process raft request' (duration: 250.979105ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:42.51241Z","caller":"traceutil/trace.go:171","msg":"trace[2124469021] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"251.002374ms","start":"2026-03-25T21:01:42.261397Z","end":"2026-03-25T21:01:42.5124Z","steps":["trace[2124469021] 'process raft request' (duration: 250.824181ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:42.512546Z","caller":"traceutil/trace.go:171","msg":"trace[2065934323] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"245.360831ms","start":"2026-03-25T21:01:42.267176Z","end":"2026-03-25T21:01:42.512537Z","steps":["trace[2065934323] 'process raft request' (duration: 245.152187ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:42.512794Z","caller":"traceutil/trace.go:171","msg":"trace[816431168] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"242.359491ms","start":"2026-03-25T21:01:42.270408Z","end":"2026-03-25T21:01:42.512767Z","steps":["trace[816431168] 'process raft request' (duration: 241.979793ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:01:42.513055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.588595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T21:01:42.513122Z","caller":"traceutil/trace.go:171","msg":"trace[339371439] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"239.700868ms","start":"2026-03-25T21:01:42.273404Z","end":"2026-03-25T21:01:42.513105Z","steps":["trace[339371439] 'agreement among raft nodes before linearized reading' (duration: 239.383341ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:01:42.513133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.757871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T21:01:42.513386Z","caller":"traceutil/trace.go:171","msg":"trace[1346140116] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"205.017477ms","start":"2026-03-25T21:01:42.308341Z","end":"2026-03-25T21:01:42.513358Z","steps":["trace[1346140116] 'agreement among raft nodes before linearized reading' (duration: 204.716081ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:42.715152Z","caller":"traceutil/trace.go:171","msg":"trace[1677614841] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"199.21835ms","start":"2026-03-25T21:01:42.515904Z","end":"2026-03-25T21:01:42.715123Z","steps":["trace[1677614841] 'process raft request' (duration: 157.426797ms)","trace[1677614841] 'compare' (duration: 41.64849ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:01:42.763933Z","caller":"traceutil/trace.go:171","msg":"trace[2093144494] linearizableReadLoop","detail":"{readStateIndex:16; appliedIndex:14; }","duration":"247.730379ms","start":"2026-03-25T21:01:42.516167Z","end":"2026-03-25T21:01:42.763897Z","steps":["trace[2093144494] 'read index received' (duration: 157.177472ms)","trace[2093144494] 'applied index is now lower than readState.Index' (duration: 90.550767ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:01:42.763976Z","caller":"traceutil/trace.go:171","msg":"trace[513875585] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"245.785229ms","start":"2026-03-25T21:01:42.518175Z","end":"2026-03-25T21:01:42.763961Z","steps":["trace[513875585] 'process raft request' (duration: 245.455153ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:42.764053Z","caller":"traceutil/trace.go:171","msg":"trace[2104988394] transaction","detail":"{read_only:false; number_of_response:0; response_revision:12; }","duration":"248.018205ms","start":"2026-03-25T21:01:42.51602Z","end":"2026-03-25T21:01:42.764038Z","steps":["trace[2104988394] 'process raft request' (duration: 247.383292ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:42.764088Z","caller":"traceutil/trace.go:171","msg":"trace[1528578951] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"245.316601ms","start":"2026-03-25T21:01:42.518757Z","end":"2026-03-25T21:01:42.764073Z","steps":["trace[1528578951] 'process raft request' (duration: 244.941223ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:42.763935Z","caller":"traceutil/trace.go:171","msg":"trace[1550752075] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"245.788349ms","start":"2026-03-25T21:01:42.51812Z","end":"2026-03-25T21:01:42.763908Z","steps":["trace[1550752075] 'process raft request' (duration: 245.386141ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:42.763904Z","caller":"traceutil/trace.go:171","msg":"trace[1323781964] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"245.272179ms","start":"2026-03-25T21:01:42.518544Z","end":"2026-03-25T21:01:42.763816Z","steps":["trace[1323781964] 'process raft request' (duration: 245.128567ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:42.7646Z","caller":"traceutil/trace.go:171","msg":"trace[288691920] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"241.084745ms","start":"2026-03-25T21:01:42.523487Z","end":"2026-03-25T21:01:42.764572Z","steps":["trace[288691920] 'process raft request' (duration: 240.33572ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:42.764747Z","caller":"traceutil/trace.go:171","msg":"trace[1757137765] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"245.731049ms","start":"2026-03-25T21:01:42.518999Z","end":"2026-03-25T21:01:42.76473Z","steps":["trace[1757137765] 'process raft request' (duration: 244.77901ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:42.764761Z","caller":"traceutil/trace.go:171","msg":"trace[425608291] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"245.77348ms","start":"2026-03-25T21:01:42.518904Z","end":"2026-03-25T21:01:42.764678Z","steps":["trace[425608291] 'process raft request' (duration: 244.828171ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:01:42.764913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.671488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-vwn86\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-03-25T21:01:42.765016Z","caller":"traceutil/trace.go:171","msg":"trace[1195819800] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-vwn86; range_end:; response_count:1; response_revision:19; }","duration":"248.852511ms","start":"2026-03-25T21:01:42.516141Z","end":"2026-03-25T21:01:42.764993Z","steps":["trace[1195819800] 'agreement among raft nodes before linearized reading' (duration: 248.436013ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:01:42.7651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.83205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-03-25T21:01:42.765108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.730999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-03-25T21:01:42.765184Z","caller":"traceutil/trace.go:171","msg":"trace[1179534433] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:19; }","duration":"195.840442ms","start":"2026-03-25T21:01:42.56933Z","end":"2026-03-25T21:01:42.76517Z","steps":["trace[1179534433] 'agreement among raft nodes before linearized reading' (duration: 195.704939ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:42.765231Z","caller":"traceutil/trace.go:171","msg":"trace[1914573543] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:19; }","duration":"189.970404ms","start":"2026-03-25T21:01:42.575187Z","end":"2026-03-25T21:01:42.765157Z","steps":["trace[1914573543] 'agreement among raft nodes before linearized reading' (duration: 189.766419ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:01:42.764913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.134637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"info","ts":"2026-03-25T21:01:42.765351Z","caller":"traceutil/trace.go:171","msg":"trace[910602874] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:19; }","duration":"247.625177ms","start":"2026-03-25T21:01:42.517688Z","end":"2026-03-25T21:01:42.765313Z","steps":["trace[910602874] 'agreement among raft nodes before linearized reading' (duration: 247.142277ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:42.93278Z","caller":"traceutil/trace.go:171","msg":"trace[2115663122] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"160.934537ms","start":"2026-03-25T21:01:42.771798Z","end":"2026-03-25T21:01:42.932733Z","steps":["trace[2115663122] 'process raft request' (duration: 110.956958ms)","trace[2115663122] 'compare' (duration: 49.697023ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:01:42.932893Z","caller":"traceutil/trace.go:171","msg":"trace[2053546619] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"160.995529ms","start":"2026-03-25T21:01:42.771875Z","end":"2026-03-25T21:01:42.93287Z","steps":["trace[2053546619] 'process raft request' (duration: 160.722743ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:42.933055Z","caller":"traceutil/trace.go:171","msg":"trace[1216736287] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"160.854936ms","start":"2026-03-25T21:01:42.772175Z","end":"2026-03-25T21:01:42.93303Z","steps":["trace[1216736287] 'process raft request' (duration: 160.494009ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:42.93306Z","caller":"traceutil/trace.go:171","msg":"trace[1302161184] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"160.980038ms","start":"2026-03-25T21:01:42.772056Z","end":"2026-03-25T21:01:42.933036Z","steps":["trace[1302161184] 'process raft request' (duration: 160.58383ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:42.933189Z","caller":"traceutil/trace.go:171","msg":"trace[838352928] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"160.704493ms","start":"2026-03-25T21:01:42.772453Z","end":"2026-03-25T21:01:42.933157Z","steps":["trace[838352928] 'process raft request' (duration: 160.288704ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:42.933295Z","caller":"traceutil/trace.go:171","msg":"trace[494451615] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"160.976108ms","start":"2026-03-25T21:01:42.772251Z","end":"2026-03-25T21:01:42.933227Z","steps":["trace[494451615] 'process raft request' (duration: 160.457868ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:42.933804Z","caller":"traceutil/trace.go:171","msg":"trace[1852126934] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"161.090041ms","start":"2026-03-25T21:01:42.772702Z","end":"2026-03-25T21:01:42.933792Z","steps":["trace[1852126934] 'process raft request' (duration: 160.108051ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:42.933844Z","caller":"traceutil/trace.go:171","msg":"trace[36381265] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"139.634117ms","start":"2026-03-25T21:01:42.794198Z","end":"2026-03-25T21:01:42.933832Z","steps":["trace[36381265] 'process raft request' (duration: 139.566926ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:42.933852Z","caller":"traceutil/trace.go:171","msg":"trace[1861421497] linearizableReadLoop","detail":"{readStateIndex:33; appliedIndex:23; }","duration":"159.763923ms","start":"2026-03-25T21:01:42.774068Z","end":"2026-03-25T21:01:42.933832Z","steps":["trace[1861421497] 'read index received' (duration: 16.173176ms)","trace[1861421497] 'applied index is now lower than readState.Index' (duration: 143.588257ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:01:42.934032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.955398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T21:01:42.934076Z","caller":"traceutil/trace.go:171","msg":"trace[1999224366] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:30; }","duration":"160.033299ms","start":"2026-03-25T21:01:42.77403Z","end":"2026-03-25T21:01:42.934063Z","steps":["trace[1999224366] 'agreement among raft nodes before linearized reading' (duration: 159.912897ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:01:42.934327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.608418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T21:01:42.934407Z","caller":"traceutil/trace.go:171","msg":"trace[12311934] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:30; }","duration":"139.726269ms","start":"2026-03-25T21:01:42.794653Z","end":"2026-03-25T21:01:42.934379Z","steps":["trace[12311934] 'agreement among raft nodes before linearized reading' (duration: 139.580616ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:01:43.148887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.861123ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-25T21:01:43.149125Z","caller":"traceutil/trace.go:171","msg":"trace[1549254737] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"212.275716ms","start":"2026-03-25T21:01:42.936821Z","end":"2026-03-25T21:01:43.149097Z","steps":["trace[1549254737] 'process raft request' (duration: 96.094577ms)","trace[1549254737] 'compare' (duration: 115.7469ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:01:43.149322Z","caller":"traceutil/trace.go:171","msg":"trace[19396433] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"212.004851ms","start":"2026-03-25T21:01:42.937299Z","end":"2026-03-25T21:01:43.149304Z","steps":["trace[19396433] 'process raft request' (duration: 211.729106ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:43.149588Z","caller":"traceutil/trace.go:171","msg":"trace[1223850245] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"211.869938ms","start":"2026-03-25T21:01:42.93768Z","end":"2026-03-25T21:01:43.14955Z","steps":["trace[1223850245] 'process raft request' (duration: 211.394089ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:43.149791Z","caller":"traceutil/trace.go:171","msg":"trace[862592044] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"211.889069ms","start":"2026-03-25T21:01:42.937887Z","end":"2026-03-25T21:01:43.149776Z","steps":["trace[862592044] 'process raft request' (duration: 211.389999ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:43.150181Z","caller":"traceutil/trace.go:171","msg":"trace[2091929595] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"179.034206ms","start":"2026-03-25T21:01:42.971135Z","end":"2026-03-25T21:01:43.150169Z","steps":["trace[2091929595] 'process raft request' (duration: 178.987145ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:43.15049Z","caller":"traceutil/trace.go:171","msg":"trace[194992780] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"212.584413ms","start":"2026-03-25T21:01:42.93789Z","end":"2026-03-25T21:01:43.150475Z","steps":["trace[194992780] 'process raft request' (duration: 211.800677ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:43.150663Z","caller":"traceutil/trace.go:171","msg":"trace[1972682583] transaction","detail":"{read_only:false; response_revision:39; number_of_response:1; }","duration":"210.567342ms","start":"2026-03-25T21:01:42.939975Z","end":"2026-03-25T21:01:43.150543Z","steps":["trace[1972682583] 'process raft request' (duration: 210.080842ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:43.150696Z","caller":"traceutil/trace.go:171","msg":"trace[1261156687] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"209.866308ms","start":"2026-03-25T21:01:42.940808Z","end":"2026-03-25T21:01:43.150674Z","steps":["trace[1261156687] 'process raft request' (duration: 209.279956ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:43.15081Z","caller":"traceutil/trace.go:171","msg":"trace[1065403422] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"212.541082ms","start":"2026-03-25T21:01:42.938252Z","end":"2026-03-25T21:01:43.150793Z","steps":["trace[1065403422] 'process raft request' (duration: 211.722886ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:43.150986Z","caller":"traceutil/trace.go:171","msg":"trace[801712099] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"212.122724ms","start":"2026-03-25T21:01:42.93885Z","end":"2026-03-25T21:01:43.150973Z","steps":["trace[801712099] 'process raft request' (duration: 211.185925ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:43.426727Z","caller":"traceutil/trace.go:171","msg":"trace[1127410083] transaction","detail":"{read_only:false; response_revision:45; number_of_response:1; }","duration":"224.414829ms","start":"2026-03-25T21:01:43.202272Z","end":"2026-03-25T21:01:43.426687Z","steps":["trace[1127410083] 'process raft request' (duration: 224.253826ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:43.42681Z","caller":"traceutil/trace.go:171","msg":"trace[1124157095] transaction","detail":"{read_only:false; response_revision:46; number_of_response:1; }","duration":"223.749155ms","start":"2026-03-25T21:01:43.203031Z","end":"2026-03-25T21:01:43.426781Z","steps":["trace[1124157095] 'process raft request' (duration: 223.563161ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:43.426853Z","caller":"traceutil/trace.go:171","msg":"trace[1909581642] transaction","detail":"{read_only:false; response_revision:47; number_of_response:1; }","duration":"223.456989ms","start":"2026-03-25T21:01:43.203377Z","end":"2026-03-25T21:01:43.426834Z","steps":["trace[1909581642] 'process raft request' (duration: 223.277745ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:43.426636Z","caller":"traceutil/trace.go:171","msg":"trace[1937695643] transaction","detail":"{read_only:false; response_revision:44; number_of_response:1; }","duration":"225.284306ms","start":"2026-03-25T21:01:43.201316Z","end":"2026-03-25T21:01:43.426601Z","steps":["trace[1937695643] 'process raft request' (duration: 192.482956ms)","trace[1937695643] 'compare' (duration: 32.558875ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:01:43.426716Z","caller":"traceutil/trace.go:171","msg":"trace[1834100188] linearizableReadLoop","detail":"{readStateIndex:51; appliedIndex:47; }","duration":"179.672458ms","start":"2026-03-25T21:01:43.247021Z","end":"2026-03-25T21:01:43.426693Z","steps":["trace[1834100188] 'read index received' (duration: 146.696114ms)","trace[1834100188] 'applied index is now lower than readState.Index' (duration: 32.975264ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:01:43.427044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.013405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T21:01:43.427092Z","caller":"traceutil/trace.go:171","msg":"trace[1459416225] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:47; }","duration":"180.074156ms","start":"2026-03-25T21:01:43.247004Z","end":"2026-03-25T21:01:43.427078Z","steps":["trace[1459416225] 'agreement among raft nodes before linearized reading' (duration: 179.962494ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:01:43.427122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.064963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T21:01:43.427198Z","caller":"traceutil/trace.go:171","msg":"trace[1548796408] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:47; }","duration":"167.140245ms","start":"2026-03-25T21:01:43.26001Z","end":"2026-03-25T21:01:43.42715Z","steps":["trace[1548796408] 'agreement among raft nodes before linearized reading' (duration: 167.069074ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:01:44.843701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.599602ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:01:44.844018Z","caller":"traceutil/trace.go:171","msg":"trace[134219215] transaction","detail":"{read_only:false; response_revision:157; number_of_response:1; }","duration":"153.663637ms","start":"2026-03-25T21:01:44.690236Z","end":"2026-03-25T21:01:44.8439Z","steps":["trace[134219215] 'process raft request' (duration: 42.728328ms)","trace[134219215] 'compare' (duration: 110.467129ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:01:45.879739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.444847ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:01:45.879915Z","caller":"traceutil/trace.go:171","msg":"trace[1542822043] transaction","detail":"{read_only:false; response_revision:186; number_of_response:1; }","duration":"214.9842ms","start":"2026-03-25T21:01:45.664904Z","end":"2026-03-25T21:01:45.879888Z","steps":["trace[1542822043] 'process raft request' (duration: 53.305199ms)","trace[1542822043] 'compare' (duration: 161.282463ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:01:46.432158Z","caller":"traceutil/trace.go:171","msg":"trace[227814978] transaction","detail":"{read_only:false; response_revision:195; number_of_response:1; }","duration":"162.391989ms","start":"2026-03-25T21:01:46.269742Z","end":"2026-03-25T21:01:46.432134Z","steps":["trace[227814978] 'process raft request' (duration: 162.337648ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:46.432792Z","caller":"traceutil/trace.go:171","msg":"trace[342792794] transaction","detail":"{read_only:false; response_revision:194; number_of_response:1; }","duration":"165.433719ms","start":"2026-03-25T21:01:46.267332Z","end":"2026-03-25T21:01:46.432765Z","steps":["trace[342792794] 'process raft request' (duration: 142.624313ms)","trace[342792794] 'compare' (duration: 21.963679ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:01:47.716594Z","caller":"traceutil/trace.go:171","msg":"trace[316754911] linearizableReadLoop","detail":"{readStateIndex:236; appliedIndex:235; }","duration":"174.28557ms","start":"2026-03-25T21:01:47.542284Z","end":"2026-03-25T21:01:47.716569Z","steps":["trace[316754911] 'read index received' (duration: 119.134168ms)","trace[316754911] 'applied index is now lower than readState.Index' (duration: 55.150342ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:01:47.716726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.430083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-25T21:01:47.716761Z","caller":"traceutil/trace.go:171","msg":"trace[1855335175] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:230; }","duration":"174.522894ms","start":"2026-03-25T21:01:47.542226Z","end":"2026-03-25T21:01:47.716749Z","steps":["trace[1855335175] 'agreement among raft nodes before linearized reading' (duration: 174.445873ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:47.716893Z","caller":"traceutil/trace.go:171","msg":"trace[147966213] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"199.592435ms","start":"2026-03-25T21:01:47.517276Z","end":"2026-03-25T21:01:47.716868Z","steps":["trace[147966213] 'process raft request' (duration: 144.27435ms)","trace[147966213] 'compare' (duration: 54.852345ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:01:48.752261Z","caller":"traceutil/trace.go:171","msg":"trace[892998945] linearizableReadLoop","detail":"{readStateIndex:269; appliedIndex:268; }","duration":"152.967408ms","start":"2026-03-25T21:01:48.599272Z","end":"2026-03-25T21:01:48.752239Z","steps":["trace[892998945] 'read index received' (duration: 62.088757ms)","trace[892998945] 'applied index is now lower than readState.Index' (duration: 90.877671ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:01:48.752354Z","caller":"traceutil/trace.go:171","msg":"trace[1852820895] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"157.417587ms","start":"2026-03-25T21:01:48.594925Z","end":"2026-03-25T21:01:48.752342Z","steps":["trace[1852820895] 'process raft request' (duration: 66.479725ms)","trace[1852820895] 'compare' (duration: 90.723978ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:01:48.752544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.251255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18a03155f36d68f4\" ","response":"range_response_count:1 size:651"} {"level":"info","ts":"2026-03-25T21:01:48.75266Z","caller":"traceutil/trace.go:171","msg":"trace[954570594] range","detail":"{range_begin:/registry/events/default/instance.18a03155f36d68f4; range_end:; response_count:1; response_revision:263; }","duration":"153.400457ms","start":"2026-03-25T21:01:48.599238Z","end":"2026-03-25T21:01:48.752639Z","steps":["trace[954570594] 'agreement among raft nodes before linearized reading' (duration: 153.182443ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:01:48.800936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.720514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"warn","ts":"2026-03-25T21:01:48.800945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.896218ms","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-03-25T21:01:48.800984Z","caller":"traceutil/trace.go:171","msg":"trace[766025684] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:264; }","duration":"126.796476ms","start":"2026-03-25T21:01:48.674176Z","end":"2026-03-25T21:01:48.800972Z","steps":["trace[766025684] 'agreement among raft nodes before linearized reading' (duration: 126.675004ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:48.80106Z","caller":"traceutil/trace.go:171","msg":"trace[230507803] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:264; }","duration":"125.99514ms","start":"2026-03-25T21:01:48.674995Z","end":"2026-03-25T21:01:48.800991Z","steps":["trace[230507803] 'agreement among raft nodes before linearized reading' (duration: 125.861937ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:01:48.801267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.717722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-25T21:01:48.801709Z","caller":"traceutil/trace.go:171","msg":"trace[549568353] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:264; }","duration":"159.373476ms","start":"2026-03-25T21:01:48.642215Z","end":"2026-03-25T21:01:48.801589Z","steps":["trace[549568353] 'agreement among raft nodes before linearized reading' (duration: 158.629821ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:48.934078Z","caller":"traceutil/trace.go:171","msg":"trace[950123304] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"177.71628ms","start":"2026-03-25T21:01:48.756339Z","end":"2026-03-25T21:01:48.934055Z","steps":["trace[950123304] 'process raft request' (duration: 177.663359ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:48.934126Z","caller":"traceutil/trace.go:171","msg":"trace[1956752603] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"179.374923ms","start":"2026-03-25T21:01:48.754726Z","end":"2026-03-25T21:01:48.934101Z","steps":["trace[1956752603] 'process raft request' (duration: 148.527118ms)","trace[1956752603] 'compare' (duration: 30.636251ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:01:48.958109Z","caller":"traceutil/trace.go:171","msg":"trace[1924080173] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"153.002969ms","start":"2026-03-25T21:01:48.805087Z","end":"2026-03-25T21:01:48.95809Z","steps":["trace[1924080173] 'process raft request' (duration: 152.821275ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:48.958373Z","caller":"traceutil/trace.go:171","msg":"trace[1941536302] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"137.97565ms","start":"2026-03-25T21:01:48.820368Z","end":"2026-03-25T21:01:48.958343Z","steps":["trace[1941536302] 'process raft request' (duration: 137.813767ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:48.958542Z","caller":"traceutil/trace.go:171","msg":"trace[987259863] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"152.755114ms","start":"2026-03-25T21:01:48.805773Z","end":"2026-03-25T21:01:48.958529Z","steps":["trace[987259863] 'process raft request' (duration: 152.246674ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:01:53.110814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.804973ms","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-03-25T21:01:53.110906Z","caller":"traceutil/trace.go:171","msg":"trace[1160706086] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:391; }","duration":"137.948126ms","start":"2026-03-25T21:01:52.972935Z","end":"2026-03-25T21:01:53.110884Z","steps":["trace[1160706086] 'range keys from in-memory index tree' (duration: 137.65244ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:53.283105Z","caller":"traceutil/trace.go:171","msg":"trace[1541114373] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"168.116741ms","start":"2026-03-25T21:01:53.114965Z","end":"2026-03-25T21:01:53.283082Z","steps":["trace[1541114373] 'process raft request' (duration: 167.978768ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:01:53.683906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.397436ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:01:53.684053Z","caller":"traceutil/trace.go:171","msg":"trace[614254648] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"386.80682ms","start":"2026-03-25T21:01:53.297223Z","end":"2026-03-25T21:01:53.684029Z","steps":["trace[614254648] 'process raft request' (duration: 175.042018ms)","trace[614254648] 'compare' (duration: 211.248492ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:01:53.684178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:01:53.297209Z","time spent":"386.901482ms","remote":"127.0.0.1:49906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7288,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:01:53.68921Z","caller":"traceutil/trace.go:171","msg":"trace[866527955] linearizableReadLoop","detail":"{readStateIndex:407; appliedIndex:406; }","duration":"189.187287ms","start":"2026-03-25T21:01:53.500008Z","end":"2026-03-25T21:01:53.689195Z","steps":["trace[866527955] 'read index received' (duration: 189.036674ms)","trace[866527955] 'applied index is now lower than readState.Index' (duration: 150.213µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:01:53.689275Z","caller":"traceutil/trace.go:171","msg":"trace[2036017471] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"210.61428ms","start":"2026-03-25T21:01:53.478634Z","end":"2026-03-25T21:01:53.689248Z","steps":["trace[2036017471] 'process raft request' (duration: 210.496478ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:01:53.68938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.161789ms","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-03-25T21:01:53.689423Z","caller":"traceutil/trace.go:171","msg":"trace[49556672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:395; }","duration":"180.225149ms","start":"2026-03-25T21:01:53.509186Z","end":"2026-03-25T21:01:53.689412Z","steps":["trace[49556672] 'agreement among raft nodes before linearized reading' (duration: 180.125677ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:01:53.689333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.32284ms","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-03-25T21:01:53.689606Z","caller":"traceutil/trace.go:171","msg":"trace[1362322717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:395; }","duration":"189.625026ms","start":"2026-03-25T21:01:53.499964Z","end":"2026-03-25T21:01:53.689589Z","steps":["trace[1362322717] 'agreement among raft nodes before linearized reading' (duration: 189.30701ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:53.871858Z","caller":"traceutil/trace.go:171","msg":"trace[231882433] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"156.335308ms","start":"2026-03-25T21:01:53.7155Z","end":"2026-03-25T21:01:53.871835Z","steps":["trace[231882433] 'process raft request' (duration: 86.884096ms)","trace[231882433] 'compare' (duration: 69.122445ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:01:54.75381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.669537ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-03-25T21:01:54.754127Z","caller":"traceutil/trace.go:171","msg":"trace[785532982] linearizableReadLoop","detail":"{readStateIndex:413; appliedIndex:411; }","duration":"351.785297ms","start":"2026-03-25T21:01:54.402285Z","end":"2026-03-25T21:01:54.754071Z","steps":["trace[785532982] 'read index received' (duration: 147.861356ms)","trace[785532982] 'applied index is now lower than readState.Index' (duration: 203.923121ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:01:54.75414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:01:54.171293Z","time spent":"582.839134ms","remote":"127.0.0.1:50240","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-25T21:01:54.754269Z","caller":"traceutil/trace.go:171","msg":"trace[1283078689] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"440.381824ms","start":"2026-03-25T21:01:54.313877Z","end":"2026-03-25T21:01:54.754259Z","steps":["trace[1283078689] 'process raft request' (duration: 440.090008ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:01:54.754389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.746037ms","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-03-25T21:01:54.754451Z","caller":"traceutil/trace.go:171","msg":"trace[734726657] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:400; }","duration":"205.86078ms","start":"2026-03-25T21:01:54.548574Z","end":"2026-03-25T21:01:54.754435Z","steps":["trace[734726657] 'agreement among raft nodes before linearized reading' (duration: 205.712527ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:01:54.754436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.132744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-nbkft\" ","response":"range_response_count:1 size:4466"} {"level":"info","ts":"2026-03-25T21:01:54.754515Z","caller":"traceutil/trace.go:171","msg":"trace[943218219] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-nbkft; range_end:; response_count:1; response_revision:400; }","duration":"352.246686ms","start":"2026-03-25T21:01:54.402255Z","end":"2026-03-25T21:01:54.754502Z","steps":["trace[943218219] 'agreement among raft nodes before linearized reading' (duration: 352.081653ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:01:54.754538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:01:54.402241Z","time spent":"352.292708ms","remote":"127.0.0.1:49906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":4489,"request content":"key:\"/registry/pods/kube-system/kube-proxy-nbkft\" "} {"level":"warn","ts":"2026-03-25T21:01:54.754703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:01:54.313858Z","time spent":"440.467496ms","remote":"127.0.0.1:49830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T21:01:55.060856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.435367ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:01:55.061023Z","caller":"traceutil/trace.go:171","msg":"trace[1498391637] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"305.564213ms","start":"2026-03-25T21:01:54.755398Z","end":"2026-03-25T21:01:55.060963Z","steps":["trace[1498391637] 'process raft request' (duration: 198.960713ms)","trace[1498391637] 'compare' (duration: 106.290554ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:01:55.061306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:01:54.755376Z","time spent":"305.748717ms","remote":"127.0.0.1:50240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-25T21:01:55.068364Z","caller":"traceutil/trace.go:171","msg":"trace[1295953369] linearizableReadLoop","detail":"{readStateIndex:416; appliedIndex:413; }","duration":"213.808904ms","start":"2026-03-25T21:01:54.854545Z","end":"2026-03-25T21:01:55.068354Z","steps":["trace[1295953369] 'read index received' (duration: 99.796217ms)","trace[1295953369] 'applied index is now lower than readState.Index' (duration: 114.012027ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:01:55.068424Z","caller":"traceutil/trace.go:171","msg":"trace[1938814725] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"309.589863ms","start":"2026-03-25T21:01:54.7588Z","end":"2026-03-25T21:01:55.06839Z","steps":["trace[1938814725] 'process raft request' (duration: 309.45208ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:01:55.068482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.937077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-25T21:01:55.068606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:01:54.758775Z","time spent":"309.729096ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:01:55.068699Z","caller":"traceutil/trace.go:171","msg":"trace[1176748435] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"306.328479ms","start":"2026-03-25T21:01:54.762324Z","end":"2026-03-25T21:01:55.068652Z","steps":["trace[1176748435] 'process raft request' (duration: 305.999373ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:01:55.068875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:01:54.762305Z","time spent":"306.482352ms","remote":"127.0.0.1:49906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4467,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:01:55.068595Z","caller":"traceutil/trace.go:171","msg":"trace[1582743452] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:403; }","duration":"214.08713ms","start":"2026-03-25T21:01:54.8545Z","end":"2026-03-25T21:01:55.068587Z","steps":["trace[1582743452] 'agreement among raft nodes before linearized reading' (duration: 213.893506ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:56.53198Z","caller":"traceutil/trace.go:171","msg":"trace[430778891] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"117.239584ms","start":"2026-03-25T21:01:56.414718Z","end":"2026-03-25T21:01:56.531957Z","steps":["trace[430778891] 'process raft request' (duration: 117.110642ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:01:56.859454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.685466ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:01:56.859857Z","caller":"traceutil/trace.go:171","msg":"trace[354028914] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"288.114944ms","start":"2026-03-25T21:01:56.57171Z","end":"2026-03-25T21:01:56.859825Z","steps":["trace[354028914] 'process raft request' (duration: 117.939179ms)","trace[354028914] 'compare' (duration: 169.574183ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:01:59.448353Z","caller":"traceutil/trace.go:171","msg":"trace[328058350] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"125.971316ms","start":"2026-03-25T21:01:59.322315Z","end":"2026-03-25T21:01:59.448287Z","steps":["trace[328058350] 'process raft request' (duration: 125.65122ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:01:59.921359Z","caller":"traceutil/trace.go:171","msg":"trace[1281444246] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"118.181584ms","start":"2026-03-25T21:01:59.803143Z","end":"2026-03-25T21:01:59.921324Z","steps":["trace[1281444246] 'process raft request' (duration: 88.296938ms)","trace[1281444246] 'compare' (duration: 29.694522ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:02:05.972484Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835591716677838317,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T21:02:06.473023Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835591716677838317,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T21:02:06.82756Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.667555468s","expected-duration":"1s"} {"level":"info","ts":"2026-03-25T21:02:06.827942Z","caller":"traceutil/trace.go:171","msg":"trace[1723200782] linearizableReadLoop","detail":"{readStateIndex:476; appliedIndex:475; }","duration":"1.355935394s","start":"2026-03-25T21:02:05.471985Z","end":"2026-03-25T21:02:06.827921Z","steps":["trace[1723200782] 'read index received' (duration: 1.35572297s)","trace[1723200782] 'applied index is now lower than readState.Index' (duration: 211.574µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:02:06.828059Z","caller":"traceutil/trace.go:171","msg":"trace[1179585152] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"1.668118479s","start":"2026-03-25T21:02:05.159905Z","end":"2026-03-25T21:02:06.828024Z","steps":["trace[1179585152] 'process raft request' (duration: 1.667855314s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:02:06.828159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.356160048s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-25T21:02:06.828184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:02:05.159878Z","time spent":"1.668238392s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:02:06.828206Z","caller":"traceutil/trace.go:171","msg":"trace[2113905998] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:461; }","duration":"1.356256191s","start":"2026-03-25T21:02:05.471938Z","end":"2026-03-25T21:02:06.828194Z","steps":["trace[2113905998] 'agreement among raft nodes before linearized reading' (duration: 1.356073857s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:02:06.828246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:02:05.471918Z","time spent":"1.356319871s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-25T21:02:06.828254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.268377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.209\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-25T21:02:06.828179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.042289921s","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-03-25T21:02:06.828507Z","caller":"traceutil/trace.go:171","msg":"trace[1053323786] range","detail":"{range_begin:/registry/masterleases/199.204.45.209; range_end:; response_count:1; response_revision:461; }","duration":"265.518231ms","start":"2026-03-25T21:02:06.562956Z","end":"2026-03-25T21:02:06.828474Z","steps":["trace[1053323786] 'agreement among raft nodes before linearized reading' (duration: 265.273936ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:02:06.828611Z","caller":"traceutil/trace.go:171","msg":"trace[794023241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:461; }","duration":"1.042706749s","start":"2026-03-25T21:02:05.78583Z","end":"2026-03-25T21:02:06.828537Z","steps":["trace[794023241] 'agreement among raft nodes before linearized reading' (duration: 1.04223881s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:02:06.828797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:02:05.785817Z","time spent":"1.042914373s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-25T21:02:06.829459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.940501ms","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-03-25T21:02:06.829544Z","caller":"traceutil/trace.go:171","msg":"trace[665843078] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:461; }","duration":"838.056243ms","start":"2026-03-25T21:02:05.991477Z","end":"2026-03-25T21:02:06.829533Z","steps":["trace[665843078] 'agreement among raft nodes before linearized reading' (duration: 836.794638ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:02:06.829568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:02:05.991459Z","time spent":"838.103344ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-25T21:02:07.08862Z","caller":"traceutil/trace.go:171","msg":"trace[1864303532] linearizableReadLoop","detail":"{readStateIndex:480; appliedIndex:476; }","duration":"234.976917ms","start":"2026-03-25T21:02:06.85361Z","end":"2026-03-25T21:02:07.088587Z","steps":["trace[1864303532] 'read index received' (duration: 178.401091ms)","trace[1864303532] 'applied index is now lower than readState.Index' (duration: 56.574906ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:02:07.088783Z","caller":"traceutil/trace.go:171","msg":"trace[766969928] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"256.2576ms","start":"2026-03-25T21:02:06.832491Z","end":"2026-03-25T21:02:07.088748Z","steps":["trace[766969928] 'process raft request' (duration: 255.69822ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:02:07.088835Z","caller":"traceutil/trace.go:171","msg":"trace[756361341] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"254.947214ms","start":"2026-03-25T21:02:06.833853Z","end":"2026-03-25T21:02:07.0888Z","steps":["trace[756361341] 'process raft request' (duration: 254.657719ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:02:07.088851Z","caller":"traceutil/trace.go:171","msg":"trace[421014992] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"253.282043ms","start":"2026-03-25T21:02:06.835539Z","end":"2026-03-25T21:02:07.088821Z","steps":["trace[421014992] 'process raft request' (duration: 253.017528ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:02:07.088938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.303894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:02:07.089156Z","caller":"traceutil/trace.go:171","msg":"trace[1662121727] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:464; }","duration":"235.503978ms","start":"2026-03-25T21:02:06.853571Z","end":"2026-03-25T21:02:07.089075Z","steps":["trace[1662121727] 'agreement among raft nodes before linearized reading' (duration: 235.132511ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:02:09.213143Z","caller":"traceutil/trace.go:171","msg":"trace[2067110182] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"115.636817ms","start":"2026-03-25T21:02:09.097483Z","end":"2026-03-25T21:02:09.21312Z","steps":["trace[2067110182] 'process raft request' (duration: 115.584526ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:02:09.213674Z","caller":"traceutil/trace.go:171","msg":"trace[18031274] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"117.509313ms","start":"2026-03-25T21:02:09.09615Z","end":"2026-03-25T21:02:09.213659Z","steps":["trace[18031274] 'process raft request' (duration: 93.06645ms)","trace[18031274] 'compare' (duration: 23.76137ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:02:10.036484Z","caller":"traceutil/trace.go:171","msg":"trace[1599600369] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"109.665661ms","start":"2026-03-25T21:02:09.926796Z","end":"2026-03-25T21:02:10.036462Z","steps":["trace[1599600369] 'process raft request' (duration: 109.527198ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:02:15.993559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.007201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:02:15.993649Z","caller":"traceutil/trace.go:171","msg":"trace[784314621] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:483; }","duration":"184.162774ms","start":"2026-03-25T21:02:15.809465Z","end":"2026-03-25T21:02:15.993628Z","steps":["trace[784314621] 'range keys from in-memory index tree' (duration: 183.882359ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:02:16.441717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.769169ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:02:16.441871Z","caller":"traceutil/trace.go:171","msg":"trace[1860839154] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"270.411758ms","start":"2026-03-25T21:02:16.171446Z","end":"2026-03-25T21:02:16.441858Z","steps":["trace[1860839154] 'process raft request' (duration: 270.366587ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:02:16.441956Z","caller":"traceutil/trace.go:171","msg":"trace[840194504] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"270.674944ms","start":"2026-03-25T21:02:16.171217Z","end":"2026-03-25T21:02:16.441892Z","steps":["trace[840194504] 'process raft request' (duration: 94.67315ms)","trace[840194504] 'compare' (duration: 175.633217ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:02:18.586777Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835591716677838461,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-25T21:02:19.044186Z","caller":"traceutil/trace.go:171","msg":"trace[1261643199] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"958.23154ms","start":"2026-03-25T21:02:18.085926Z","end":"2026-03-25T21:02:19.044157Z","steps":["trace[1261643199] 'process raft request' (duration: 863.273078ms)","trace[1261643199] 'compare' (duration: 94.82943ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:02:19.04433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:02:18.085911Z","time spent":"958.361932ms","remote":"127.0.0.1:50246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-25T21:02:19.055643Z","caller":"traceutil/trace.go:171","msg":"trace[881381964] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"948.367191ms","start":"2026-03-25T21:02:18.107253Z","end":"2026-03-25T21:02:19.05562Z","steps":["trace[881381964] 'process raft request' (duration: 947.885192ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:02:19.055645Z","caller":"traceutil/trace.go:171","msg":"trace[1623192365] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"958.74564ms","start":"2026-03-25T21:02:18.096861Z","end":"2026-03-25T21:02:19.055607Z","steps":["trace[1623192365] 'process raft request' (duration: 958.153288ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:02:19.055754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:02:18.107242Z","time spent":"948.453403ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:02:19.055777Z","caller":"traceutil/trace.go:171","msg":"trace[737777207] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"943.459236ms","start":"2026-03-25T21:02:18.112279Z","end":"2026-03-25T21:02:19.055738Z","steps":["trace[737777207] 'process raft request' (duration: 943.087989ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:02:19.055933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:02:18.11227Z","time spent":"943.575968ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:02:19.055933Z","caller":"traceutil/trace.go:171","msg":"trace[968098139] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:560; }","duration":"969.923595ms","start":"2026-03-25T21:02:18.085984Z","end":"2026-03-25T21:02:19.055908Z","steps":["trace[968098139] 'read index received' (duration: 863.312169ms)","trace[968098139] 'applied index is now lower than readState.Index' (duration: 106.610296ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:02:19.055977Z","caller":"traceutil/trace.go:171","msg":"trace[353757904] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"928.118003ms","start":"2026-03-25T21:02:18.127842Z","end":"2026-03-25T21:02:19.05596Z","steps":["trace[353757904] 'process raft request' (duration: 927.763756ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:02:19.056008Z","caller":"traceutil/trace.go:171","msg":"trace[877308688] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"836.731308ms","start":"2026-03-25T21:02:18.219241Z","end":"2026-03-25T21:02:19.055972Z","steps":["trace[877308688] 'process raft request' (duration: 836.592105ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:02:19.056049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:02:18.127824Z","time spent":"928.193704ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:02:19.056025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:02:18.096851Z","time spent":"958.911723ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:02:19.056117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:02:18.219211Z","time spent":"836.857241ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T21:02:19.056175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.178079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1010"} {"level":"warn","ts":"2026-03-25T21:02:19.056222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.339889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10078"} {"level":"info","ts":"2026-03-25T21:02:19.056269Z","caller":"traceutil/trace.go:171","msg":"trace[1818648617] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:548; }","duration":"970.289591ms","start":"2026-03-25T21:02:18.085962Z","end":"2026-03-25T21:02:19.056252Z","steps":["trace[1818648617] 'agreement among raft nodes before linearized reading' (duration: 970.061477ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:02:19.056285Z","caller":"traceutil/trace.go:171","msg":"trace[1982225996] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"921.646528ms","start":"2026-03-25T21:02:18.134626Z","end":"2026-03-25T21:02:19.056272Z","steps":["trace[1982225996] 'process raft request' (duration: 921.075597ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:02:19.056293Z","caller":"traceutil/trace.go:171","msg":"trace[745919749] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:548; }","duration":"937.403031ms","start":"2026-03-25T21:02:18.118869Z","end":"2026-03-25T21:02:19.056272Z","steps":["trace[745919749] 'agreement among raft nodes before linearized reading' (duration: 937.223487ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:02:19.056209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.175541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-25T21:02:19.056328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:02:18.085951Z","time spent":"970.363263ms","remote":"127.0.0.1:50246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":1033,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-03-25T21:02:19.056344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:02:18.11884Z","time spent":"937.491122ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":10101,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-03-25T21:02:19.056278Z","caller":"traceutil/trace.go:171","msg":"trace[114024386] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"962.89643ms","start":"2026-03-25T21:02:18.092675Z","end":"2026-03-25T21:02:19.055571Z","steps":["trace[114024386] 'process raft request' (duration: 962.217797ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:02:19.056339Z","caller":"traceutil/trace.go:171","msg":"trace[1941310894] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:548; }","duration":"151.336034ms","start":"2026-03-25T21:02:18.904995Z","end":"2026-03-25T21:02:19.056331Z","steps":["trace[1941310894] 'agreement among raft nodes before linearized reading' (duration: 151.15413ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:02:19.056413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:02:18.134599Z","time spent":"921.76801ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:02:19.056707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:02:18.092663Z","time spent":"963.722326ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-25T21:02:25.55055Z","caller":"traceutil/trace.go:171","msg":"trace[1532552378] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"336.343753ms","start":"2026-03-25T21:02:25.214181Z","end":"2026-03-25T21:02:25.550525Z","steps":["trace[1532552378] 'process raft request' (duration: 282.008565ms)","trace[1532552378] 'compare' (duration: 54.235216ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:02:25.550702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:02:25.21416Z","time spent":"336.466106ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:02:26.026081Z","caller":"traceutil/trace.go:171","msg":"trace[470866848] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"754.513312ms","start":"2026-03-25T21:02:25.27151Z","end":"2026-03-25T21:02:26.026024Z","steps":["trace[470866848] 'process raft request' (duration: 751.495894ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:02:26.025829Z","caller":"traceutil/trace.go:171","msg":"trace[972968443] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:652; }","duration":"666.97416ms","start":"2026-03-25T21:02:25.358753Z","end":"2026-03-25T21:02:26.025727Z","steps":["trace[972968443] 'read index received' (duration: 137.471485ms)","trace[972968443] 'applied index is now lower than readState.Index' (duration: 529.500705ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:02:26.026635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:02:25.27149Z","time spent":"754.772077ms","remote":"127.0.0.1:49850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:02:26.029999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.953692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:02:26.030049Z","caller":"traceutil/trace.go:171","msg":"trace[1422069090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"175.033923ms","start":"2026-03-25T21:02:25.855002Z","end":"2026-03-25T21:02:26.030036Z","steps":["trace[1422069090] 'agreement among raft nodes before linearized reading' (duration: 172.889122ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:02:26.030058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.310932ms","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-03-25T21:02:26.030092Z","caller":"traceutil/trace.go:171","msg":"trace[1090732972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:633; }","duration":"671.383644ms","start":"2026-03-25T21:02:25.358697Z","end":"2026-03-25T21:02:26.030081Z","steps":["trace[1090732972] 'agreement among raft nodes before linearized reading' (duration: 667.666183ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:02:26.03012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:02:25.358668Z","time spent":"671.445936ms","remote":"127.0.0.1:49992","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-03-25T21:02:26.030147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.716888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-sv2dk\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-25T21:02:26.030221Z","caller":"traceutil/trace.go:171","msg":"trace[885833496] range","detail":"{range_begin:/registry/pods/kube-system/cilium-sv2dk; range_end:; response_count:1; response_revision:633; }","duration":"552.83859ms","start":"2026-03-25T21:02:25.477365Z","end":"2026-03-25T21:02:26.030203Z","steps":["trace[885833496] 'agreement among raft nodes before linearized reading' (duration: 551.881222ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:02:26.03026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:02:25.477348Z","time spent":"552.905121ms","remote":"127.0.0.1:49906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-sv2dk\" "} {"level":"warn","ts":"2026-03-25T21:02:26.030434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.02618ms","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-03-25T21:02:26.030467Z","caller":"traceutil/trace.go:171","msg":"trace[1031707039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:633; }","duration":"624.086621ms","start":"2026-03-25T21:02:25.406372Z","end":"2026-03-25T21:02:26.030459Z","steps":["trace[1031707039] 'agreement among raft nodes before linearized reading' (duration: 620.502663ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:02:26.030487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:02:25.40635Z","time spent":"624.132122ms","remote":"127.0.0.1:49992","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-03-25T21:02:26.137931Z","caller":"traceutil/trace.go:171","msg":"trace[200861126] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"102.954195ms","start":"2026-03-25T21:02:26.034936Z","end":"2026-03-25T21:02:26.13789Z","steps":["trace[200861126] 'process raft request' (duration: 102.605429ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:02:27.307718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.451734ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:02:27.30783Z","caller":"traceutil/trace.go:171","msg":"trace[1609147504] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.10438104s","start":"2026-03-25T21:02:26.203426Z","end":"2026-03-25T21:02:27.307807Z","steps":["trace[1609147504] 'process raft request' (duration: 674.739152ms)","trace[1609147504] 'compare' (duration: 429.153219ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:02:27.307884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:02:26.203398Z","time spent":"1.104463561s","remote":"127.0.0.1:49830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-25T21:02:27.30917Z","caller":"traceutil/trace.go:171","msg":"trace[1602850499] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.104068034s","start":"2026-03-25T21:02:26.205081Z","end":"2026-03-25T21:02:27.309149Z","steps":["trace[1602850499] 'process raft request' (duration: 1.103686607s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:02:27.309304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:02:26.205057Z","time spent":"1.104190527s","remote":"127.0.0.1:49906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:02:27.309468Z","caller":"traceutil/trace.go:171","msg":"trace[509781846] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:659; }","duration":"744.885778ms","start":"2026-03-25T21:02:26.56456Z","end":"2026-03-25T21:02:27.309446Z","steps":["trace[509781846] 'read index received' (duration: 313.617289ms)","trace[509781846] 'applied index is now lower than readState.Index' (duration: 431.267369ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:02:27.30948Z","caller":"traceutil/trace.go:171","msg":"trace[811184581] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"1.104273548s","start":"2026-03-25T21:02:26.205167Z","end":"2026-03-25T21:02:27.309441Z","steps":["trace[811184581] 'process raft request' (duration: 1.103923532s)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:02:27.309544Z","caller":"traceutil/trace.go:171","msg":"trace[791261115] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"1.104319629s","start":"2026-03-25T21:02:26.205202Z","end":"2026-03-25T21:02:27.309522Z","steps":["trace[791261115] 'process raft request' (duration: 1.104135645s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:02:27.309653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.087642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-25T21:02:27.309688Z","caller":"traceutil/trace.go:171","msg":"trace[999050946] range","detail":"{range_begin:/registry/masterleases/199.204.45.209; range_end:; response_count:1; response_revision:642; }","duration":"745.168774ms","start":"2026-03-25T21:02:26.564509Z","end":"2026-03-25T21:02:27.309678Z","steps":["trace[999050946] 'agreement among raft nodes before linearized reading' (duration: 745.094612ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:02:27.309678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:02:26.205187Z","time spent":"1.10440821s","remote":"127.0.0.1:49960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:02:27.309665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:02:26.205145Z","time spent":"1.104432641s","remote":"127.0.0.1:49830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T21:02:27.309717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:02:26.564489Z","time spent":"745.221244ms","remote":"127.0.0.1:49770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.209\" "} {"level":"warn","ts":"2026-03-25T21:02:27.309743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.261387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-25T21:02:27.309791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.317463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-25T21:02:27.309825Z","caller":"traceutil/trace.go:171","msg":"trace[1820927858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"455.365509ms","start":"2026-03-25T21:02:26.854426Z","end":"2026-03-25T21:02:27.309791Z","steps":["trace[1820927858] 'agreement among raft nodes before linearized reading' (duration: 455.262287ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:02:27.309833Z","caller":"traceutil/trace.go:171","msg":"trace[269468250] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:642; }","duration":"123.398054ms","start":"2026-03-25T21:02:27.186423Z","end":"2026-03-25T21:02:27.309822Z","steps":["trace[269468250] 'agreement among raft nodes before linearized reading' (duration: 123.274821ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:02:27.309867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:02:26.854401Z","time spent":"455.458251ms","remote":"127.0.0.1:49734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-25T21:02:28.888172Z","caller":"traceutil/trace.go:171","msg":"trace[1014023966] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"159.603223ms","start":"2026-03-25T21:02:28.728546Z","end":"2026-03-25T21:02:28.88815Z","steps":["trace[1014023966] 'process raft request' (duration: 159.171065ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:02:40.4984Z","caller":"traceutil/trace.go:171","msg":"trace[229212698] linearizableReadLoop","detail":"{readStateIndex:805; appliedIndex:804; }","duration":"302.704943ms","start":"2026-03-25T21:02:40.195672Z","end":"2026-03-25T21:02:40.498377Z","steps":["trace[229212698] 'read index received' (duration: 302.485336ms)","trace[229212698] 'applied index is now lower than readState.Index' (duration: 218.657µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:02:40.498558Z","caller":"traceutil/trace.go:171","msg":"trace[161967991] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"315.984261ms","start":"2026-03-25T21:02:40.182544Z","end":"2026-03-25T21:02:40.498528Z","steps":["trace[161967991] 'process raft request' (duration: 315.680222ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:02:40.498589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.891908ms","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-03-25T21:02:40.498631Z","caller":"traceutil/trace.go:171","msg":"trace[1659890544] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:780; }","duration":"302.977481ms","start":"2026-03-25T21:02:40.195643Z","end":"2026-03-25T21:02:40.498621Z","steps":["trace[1659890544] 'agreement among raft nodes before linearized reading' (duration: 302.825016ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:02:40.498682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:02:40.195629Z","time spent":"303.044862ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-25T21:02:40.498716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:02:40.182523Z","time spent":"316.103795ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:02:43.356104Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7835591716677839025,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T21:02:43.449173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.332492ms","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-03-25T21:02:43.449251Z","caller":"traceutil/trace.go:171","msg":"trace[1857768466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:797; }","duration":"945.450885ms","start":"2026-03-25T21:02:42.503779Z","end":"2026-03-25T21:02:43.44923Z","steps":["trace[1857768466] 'range keys from in-memory index tree' (duration: 945.136176ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:02:43.449295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:02:42.503758Z","time spent":"945.526337ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-25T21:02:43.449393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.713968ms","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-03-25T21:02:43.44945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.745094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wmxfq\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-03-25T21:02:43.449485Z","caller":"traceutil/trace.go:171","msg":"trace[1689313053] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wmxfq; range_end:; response_count:1; response_revision:797; }","duration":"870.824876ms","start":"2026-03-25T21:02:42.578651Z","end":"2026-03-25T21:02:43.449476Z","steps":["trace[1689313053] 'range keys from in-memory index tree' (duration: 870.60781ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:02:43.449476Z","caller":"traceutil/trace.go:171","msg":"trace[1714023345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:797; }","duration":"936.850282ms","start":"2026-03-25T21:02:42.512607Z","end":"2026-03-25T21:02:43.449458Z","steps":["trace[1714023345] 'range keys from in-memory index tree' (duration: 936.522473ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:02:43.449531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:02:42.578612Z","time spent":"870.913008ms","remote":"127.0.0.1:49906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wmxfq\" "} {"level":"warn","ts":"2026-03-25T21:02:43.449548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:02:42.512586Z","time spent":"936.952585ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-25T21:02:43.449841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.19151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-25T21:02:43.449952Z","caller":"traceutil/trace.go:171","msg":"trace[773875814] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:797; }","duration":"852.354154ms","start":"2026-03-25T21:02:42.597583Z","end":"2026-03-25T21:02:43.449937Z","steps":["trace[773875814] 'range keys from in-memory index tree' (duration: 851.888571ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:02:43.45078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:02:42.59757Z","time spent":"853.106446ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-25T21:02:43.596002Z","caller":"traceutil/trace.go:171","msg":"trace[1334369138] linearizableReadLoop","detail":"{readStateIndex:823; appliedIndex:822; }","duration":"740.196226ms","start":"2026-03-25T21:02:42.855779Z","end":"2026-03-25T21:02:43.595976Z","steps":["trace[1334369138] 'read index received' (duration: 731.645405ms)","trace[1334369138] 'applied index is now lower than readState.Index' (duration: 8.549111ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:02:43.59614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.346061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:02:43.596172Z","caller":"traceutil/trace.go:171","msg":"trace[286943436] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:797; }","duration":"740.437473ms","start":"2026-03-25T21:02:42.855725Z","end":"2026-03-25T21:02:43.596163Z","steps":["trace[286943436] 'agreement among raft nodes before linearized reading' (duration: 740.358371ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:02:43.596212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:02:42.855672Z","time spent":"740.533076ms","remote":"127.0.0.1:49734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-25T21:02:43.598858Z","caller":"traceutil/trace.go:171","msg":"trace[345613431] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"140.277506ms","start":"2026-03-25T21:02:43.458563Z","end":"2026-03-25T21:02:43.59884Z","steps":["trace[345613431] 'process raft request' (duration: 139.795302ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:02:43.59883Z","caller":"traceutil/trace.go:171","msg":"trace[1820129953] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"142.301142ms","start":"2026-03-25T21:02:43.456483Z","end":"2026-03-25T21:02:43.598784Z","steps":["trace[1820129953] 'process raft request' (duration: 141.359796ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:02:43.59984Z","caller":"traceutil/trace.go:171","msg":"trace[112255221] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"138.505416ms","start":"2026-03-25T21:02:43.461294Z","end":"2026-03-25T21:02:43.599799Z","steps":["trace[112255221] 'process raft request' (duration: 137.183758ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:02:43.599956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.414854ms","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-03-25T21:02:43.600059Z","caller":"traceutil/trace.go:171","msg":"trace[728191899] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:801; }","duration":"217.535928ms","start":"2026-03-25T21:02:43.382487Z","end":"2026-03-25T21:02:43.600023Z","steps":["trace[728191899] 'agreement among raft nodes before linearized reading' (duration: 217.143247ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:02:43.600131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.5544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-25T21:02:43.600299Z","caller":"traceutil/trace.go:171","msg":"trace[1012108525] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:801; }","duration":"732.800167ms","start":"2026-03-25T21:02:42.867473Z","end":"2026-03-25T21:02:43.600273Z","steps":["trace[1012108525] 'agreement among raft nodes before linearized reading' (duration: 732.506589ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:02:43.600397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:02:42.867454Z","time spent":"732.9147ms","remote":"127.0.0.1:50206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-03-25T21:02:43.599163Z","caller":"traceutil/trace.go:171","msg":"trace[2126984184] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"140.540643ms","start":"2026-03-25T21:02:43.458599Z","end":"2026-03-25T21:02:43.59914Z","steps":["trace[2126984184] 'process raft request' (duration: 139.831883ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:26.270104Z","caller":"traceutil/trace.go:171","msg":"trace[1189585311] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"116.994127ms","start":"2026-03-25T21:04:26.153092Z","end":"2026-03-25T21:04:26.270087Z","steps":["trace[1189585311] 'process raft request' (duration: 116.909665ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:26.404932Z","caller":"traceutil/trace.go:171","msg":"trace[2044427408] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"251.371439ms","start":"2026-03-25T21:04:26.153522Z","end":"2026-03-25T21:04:26.404894Z","steps":["trace[2044427408] 'process raft request' (duration: 251.206055ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:26.405221Z","caller":"traceutil/trace.go:171","msg":"trace[399552657] linearizableReadLoop","detail":"{readStateIndex:1357; appliedIndex:1356; }","duration":"251.38342ms","start":"2026-03-25T21:04:26.153793Z","end":"2026-03-25T21:04:26.405176Z","steps":["trace[399552657] 'read index received' (duration: 116.433865ms)","trace[399552657] 'applied index is now lower than readState.Index' (duration: 134.947835ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:04:26.405315Z","caller":"traceutil/trace.go:171","msg":"trace[1285978427] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"249.753744ms","start":"2026-03-25T21:04:26.15555Z","end":"2026-03-25T21:04:26.405304Z","steps":["trace[1285978427] 'process raft request' (duration: 249.303345ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:26.405469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.657486ms","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-03-25T21:04:26.405514Z","caller":"traceutil/trace.go:171","msg":"trace[255874806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1307; }","duration":"251.693706ms","start":"2026-03-25T21:04:26.15379Z","end":"2026-03-25T21:04:26.405483Z","steps":["trace[255874806] 'agreement among raft nodes before linearized reading' (duration: 251.593604ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:26.405603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.965874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-25T21:04:26.405646Z","caller":"traceutil/trace.go:171","msg":"trace[158071662] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1307; }","duration":"247.057496ms","start":"2026-03-25T21:04:26.158577Z","end":"2026-03-25T21:04:26.405635Z","steps":["trace[158071662] 'agreement among raft nodes before linearized reading' (duration: 246.81974ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:34.669539Z","caller":"traceutil/trace.go:171","msg":"trace[902295456] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"365.22647ms","start":"2026-03-25T21:04:34.304288Z","end":"2026-03-25T21:04:34.669515Z","steps":["trace[902295456] 'process raft request' (duration: 365.065727ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:34.669582Z","caller":"traceutil/trace.go:171","msg":"trace[286548854] linearizableReadLoop","detail":"{readStateIndex:1391; appliedIndex:1390; }","duration":"231.353894ms","start":"2026-03-25T21:04:34.438196Z","end":"2026-03-25T21:04:34.66955Z","steps":["trace[286548854] 'read index received' (duration: 231.269152ms)","trace[286548854] 'applied index is now lower than readState.Index' (duration: 50.121µs)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:04:34.669736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:04:34.304266Z","time spent":"365.350833ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:04:34.669823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.604519ms","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-03-25T21:04:34.669892Z","caller":"traceutil/trace.go:171","msg":"trace[503254572] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1338; }","duration":"231.707001ms","start":"2026-03-25T21:04:34.438168Z","end":"2026-03-25T21:04:34.669875Z","steps":["trace[503254572] 'agreement among raft nodes before linearized reading' (duration: 231.503806ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:34.741611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.510783ms","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-03-25T21:04:34.741655Z","caller":"traceutil/trace.go:171","msg":"trace[1269751104] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"234.846849ms","start":"2026-03-25T21:04:34.506771Z","end":"2026-03-25T21:04:34.741618Z","steps":["trace[1269751104] 'process raft request' (duration: 226.242435ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:04:34.7417Z","caller":"traceutil/trace.go:171","msg":"trace[243291875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1339; }","duration":"299.632375ms","start":"2026-03-25T21:04:34.442049Z","end":"2026-03-25T21:04:34.741681Z","steps":["trace[243291875] 'agreement among raft nodes before linearized reading' (duration: 299.38664ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:34.741859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.267487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-25T21:04:34.741894Z","caller":"traceutil/trace.go:171","msg":"trace[718296767] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1339; }","duration":"291.319468ms","start":"2026-03-25T21:04:34.450565Z","end":"2026-03-25T21:04:34.741884Z","steps":["trace[718296767] 'agreement among raft nodes before linearized reading' (duration: 291.239176ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:04:34.742147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.300038ms","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-03-25T21:04:34.742263Z","caller":"traceutil/trace.go:171","msg":"trace[1877082280] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1339; }","duration":"299.468552ms","start":"2026-03-25T21:04:34.442775Z","end":"2026-03-25T21:04:34.742244Z","steps":["trace[1877082280] 'agreement among raft nodes before linearized reading' (duration: 299.062954ms)"],"step_count":1}