{"level":"warn","ts":"2026-02-12T03:35:11.784326Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-12T03:35:11.784447Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.38: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.38:2380","--initial-cluster=instance=https://199.204.45.38:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.38:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.38:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-12T03:35:11.78466Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-12T03:35:11.784675Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-02-12T03:35:11.784691Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-12T03:35:11.785792Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"]} {"level":"info","ts":"2026-02-12T03:35:11.785999Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.38:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-12T03:35:11.791702Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.343976ms"} {"level":"info","ts":"2026-02-12T03:35:11.80508Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"331455d6226d3423","cluster-id":"25c7803fb83a1455"} {"level":"info","ts":"2026-02-12T03:35:11.805171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=()"} {"level":"info","ts":"2026-02-12T03:35:11.805196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 0"} {"level":"info","ts":"2026-02-12T03:35:11.805215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 331455d6226d3423 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-12T03:35:11.805231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 1"} {"level":"info","ts":"2026-02-12T03:35:11.805281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"warn","ts":"2026-02-12T03:35:11.827167Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-12T03:35:11.830921Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-12T03:35:11.834379Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-12T03:35:11.839529Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"331455d6226d3423","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-12T03:35:11.840726Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"331455d6226d3423","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-12T03:35:11.840874Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-12T03:35:11.840898Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-12T03:35:11.840911Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-12T03:35:11.841368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"info","ts":"2026-02-12T03:35:11.841427Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","added-peer-id":"331455d6226d3423","added-peer-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-02-12T03:35:11.863425Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-12T03:35:11.863634Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"331455d6226d3423","initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-12T03:35:11.863658Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-12T03:35:11.863715Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-02-12T03:35:11.863724Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-02-12T03:35:12.605575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 is starting a new election at term 1"} {"level":"info","ts":"2026-02-12T03:35:12.605621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-12T03:35:12.605645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgPreVoteResp from 331455d6226d3423 at term 1"} {"level":"info","ts":"2026-02-12T03:35:12.60566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became candidate at term 2"} {"level":"info","ts":"2026-02-12T03:35:12.605666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgVoteResp from 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-02-12T03:35:12.605677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became leader at term 2"} {"level":"info","ts":"2026-02-12T03:35:12.605684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 331455d6226d3423 elected leader 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-02-12T03:35:12.607852Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"331455d6226d3423","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.38:2379]}","request-path":"/0/members/331455d6226d3423/attributes","cluster-id":"25c7803fb83a1455","publish-timeout":"7s"} {"level":"info","ts":"2026-02-12T03:35:12.607978Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T03:35:12.609872Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T03:35:12.610123Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T03:35:12.610236Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-12T03:35:12.610244Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-12T03:35:12.610293Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T03:35:12.610402Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T03:35:12.610423Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T03:35:12.611536Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.38:2379"} {"level":"info","ts":"2026-02-12T03:35:12.634254Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-12T03:35:14.736407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.431569ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-12T03:35:14.736781Z","caller":"traceutil/trace.go:171","msg":"trace[498347231] transaction","detail":"{read_only:false; response_revision:63; number_of_response:1; }","duration":"178.18666ms","start":"2026-02-12T03:35:14.558495Z","end":"2026-02-12T03:35:14.736681Z","steps":["trace[498347231] 'process raft request' (duration: 178.113289ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:35:14.737126Z","caller":"traceutil/trace.go:171","msg":"trace[1711632504] transaction","detail":"{read_only:false; response_revision:62; number_of_response:1; }","duration":"196.158946ms","start":"2026-02-12T03:35:14.540919Z","end":"2026-02-12T03:35:14.737078Z","steps":["trace[1711632504] 'process raft request' (duration: 59.597002ms)","trace[1711632504] 'compare' (duration: 135.249566ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:35:15.307065Z","caller":"traceutil/trace.go:171","msg":"trace[1893814169] linearizableReadLoop","detail":"{readStateIndex:84; appliedIndex:83; }","duration":"178.460934ms","start":"2026-02-12T03:35:15.128566Z","end":"2026-02-12T03:35:15.307027Z","steps":["trace[1893814169] 'read index received' (duration: 115.063907ms)","trace[1893814169] 'applied index is now lower than readState.Index' (duration: 63.395457ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:35:15.308092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.492549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-12T03:35:15.308183Z","caller":"traceutil/trace.go:171","msg":"trace[1101983304] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:79; }","duration":"179.635471ms","start":"2026-02-12T03:35:15.128532Z","end":"2026-02-12T03:35:15.308168Z","steps":["trace[1101983304] 'agreement among raft nodes before linearized reading' (duration: 179.313876ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:35:15.309959Z","caller":"traceutil/trace.go:171","msg":"trace[76800672] transaction","detail":"{read_only:false; response_revision:79; number_of_response:1; }","duration":"203.641854ms","start":"2026-02-12T03:35:15.103517Z","end":"2026-02-12T03:35:15.307159Z","steps":["trace[76800672] 'process raft request' (duration: 140.173416ms)","trace[76800672] 'compare' (duration: 63.098963ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:35:15.652658Z","caller":"traceutil/trace.go:171","msg":"trace[489187685] transaction","detail":"{read_only:false; response_revision:90; number_of_response:1; }","duration":"119.621032ms","start":"2026-02-12T03:35:15.533014Z","end":"2026-02-12T03:35:15.652635Z","steps":["trace[489187685] 'process raft request' (duration: 27.503904ms)","trace[489187685] 'compare' (duration: 92.032667ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:35:16.16198Z","caller":"traceutil/trace.go:171","msg":"trace[1841050257] transaction","detail":"{read_only:false; response_revision:108; number_of_response:1; }","duration":"136.357263ms","start":"2026-02-12T03:35:16.025548Z","end":"2026-02-12T03:35:16.161905Z","steps":["trace[1841050257] 'process raft request' (duration: 80.739996ms)","trace[1841050257] 'compare' (duration: 55.315212ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:35:16.454796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.033949ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-12T03:35:16.455446Z","caller":"traceutil/trace.go:171","msg":"trace[41808529] transaction","detail":"{read_only:false; response_revision:111; number_of_response:1; }","duration":"236.213483ms","start":"2026-02-12T03:35:16.219213Z","end":"2026-02-12T03:35:16.455426Z","steps":["trace[41808529] 'process raft request' (duration: 108.478714ms)","trace[41808529] 'compare' (duration: 126.908307ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:35:16.454897Z","caller":"traceutil/trace.go:171","msg":"trace[1080284303] linearizableReadLoop","detail":"{readStateIndex:116; appliedIndex:115; }","duration":"177.771925ms","start":"2026-02-12T03:35:16.277109Z","end":"2026-02-12T03:35:16.45488Z","steps":["trace[1080284303] 'read index received' (duration: 50.548283ms)","trace[1080284303] 'applied index is now lower than readState.Index' (duration: 127.222432ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:35:16.457514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.391032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-12T03:35:16.457643Z","caller":"traceutil/trace.go:171","msg":"trace[34881482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:111; }","duration":"180.508624ms","start":"2026-02-12T03:35:16.277038Z","end":"2026-02-12T03:35:16.457547Z","steps":["trace[34881482] 'agreement among raft nodes before linearized reading' (duration: 180.383752ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:16.459781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.591235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:430"} {"level":"info","ts":"2026-02-12T03:35:16.460004Z","caller":"traceutil/trace.go:171","msg":"trace[502004372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:111; }","duration":"182.854048ms","start":"2026-02-12T03:35:16.277132Z","end":"2026-02-12T03:35:16.459986Z","steps":["trace[502004372] 'agreement among raft nodes before linearized reading' (duration: 182.568684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:16.460477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.772998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-12T03:35:16.460565Z","caller":"traceutil/trace.go:171","msg":"trace[109480872] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:111; }","duration":"131.900909ms","start":"2026-02-12T03:35:16.328652Z","end":"2026-02-12T03:35:16.460553Z","steps":["trace[109480872] 'agreement among raft nodes before linearized reading' (duration: 131.774027ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:35:16.79775Z","caller":"traceutil/trace.go:171","msg":"trace[896519084] transaction","detail":"{read_only:false; response_revision:120; number_of_response:1; }","duration":"124.756957ms","start":"2026-02-12T03:35:16.672959Z","end":"2026-02-12T03:35:16.797716Z","steps":["trace[896519084] 'process raft request' (duration: 63.315327ms)","trace[896519084] 'compare' (duration: 61.261547ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:35:17.674923Z","caller":"traceutil/trace.go:171","msg":"trace[205494808] transaction","detail":"{read_only:false; response_revision:193; number_of_response:1; }","duration":"105.135136ms","start":"2026-02-12T03:35:17.569763Z","end":"2026-02-12T03:35:17.674899Z","steps":["trace[205494808] 'process raft request' (duration: 105.091995ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:35:17.675259Z","caller":"traceutil/trace.go:171","msg":"trace[680238294] transaction","detail":"{read_only:false; response_revision:192; number_of_response:1; }","duration":"111.583198ms","start":"2026-02-12T03:35:17.563601Z","end":"2026-02-12T03:35:17.675184Z","steps":["trace[680238294] 'process raft request' (duration: 45.130646ms)","trace[680238294] 'compare' (duration: 65.969285ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:35:17.918967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.984958ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-12T03:35:17.919155Z","caller":"traceutil/trace.go:171","msg":"trace[1083467814] linearizableReadLoop","detail":"{readStateIndex:203; appliedIndex:201; }","duration":"112.280778ms","start":"2026-02-12T03:35:17.80686Z","end":"2026-02-12T03:35:17.919141Z","steps":["trace[1083467814] 'read index received' (duration: 73.118317ms)","trace[1083467814] 'applied index is now lower than readState.Index' (duration: 39.161781ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:35:17.919405Z","caller":"traceutil/trace.go:171","msg":"trace[1002998994] transaction","detail":"{read_only:false; response_revision:196; number_of_response:1; }","duration":"208.516957ms","start":"2026-02-12T03:35:17.710875Z","end":"2026-02-12T03:35:17.919392Z","steps":["trace[1002998994] 'process raft request' (duration: 79.057083ms)","trace[1002998994] 'compare' (duration: 128.890926ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:35:17.919621Z","caller":"traceutil/trace.go:171","msg":"trace[1781913907] transaction","detail":"{read_only:false; response_revision:197; number_of_response:1; }","duration":"185.788881ms","start":"2026-02-12T03:35:17.733823Z","end":"2026-02-12T03:35:17.919612Z","steps":["trace[1781913907] 'process raft request' (duration: 185.268194ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:17.919986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.004968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:35:17.920061Z","caller":"traceutil/trace.go:171","msg":"trace[1565035980] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:197; }","duration":"113.242552ms","start":"2026-02-12T03:35:17.806809Z","end":"2026-02-12T03:35:17.920052Z","steps":["trace[1565035980] 'agreement among raft nodes before linearized reading' (duration: 112.959818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:18.291276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.277064ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-12T03:35:18.291629Z","caller":"traceutil/trace.go:171","msg":"trace[1812530359] linearizableReadLoop","detail":"{readStateIndex:208; appliedIndex:204; }","duration":"321.220143ms","start":"2026-02-12T03:35:17.970391Z","end":"2026-02-12T03:35:18.291611Z","steps":["trace[1812530359] 'read index received' (duration: 80.599045ms)","trace[1812530359] 'applied index is now lower than readState.Index' (duration: 240.620168ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:35:18.291794Z","caller":"traceutil/trace.go:171","msg":"trace[82902092] transaction","detail":"{read_only:false; response_revision:199; number_of_response:1; }","duration":"357.3317ms","start":"2026-02-12T03:35:17.93445Z","end":"2026-02-12T03:35:18.291781Z","steps":["trace[82902092] 'process raft request' (duration: 116.482398ms)","trace[82902092] 'compare' (duration: 240.094821ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:35:18.291887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:17.934441Z","time spent":"357.414281ms","remote":"127.0.0.1:37422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":696,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T03:35:18.292228Z","caller":"traceutil/trace.go:171","msg":"trace[425094654] transaction","detail":"{read_only:false; response_revision:200; number_of_response:1; }","duration":"347.404738ms","start":"2026-02-12T03:35:17.944812Z","end":"2026-02-12T03:35:18.292216Z","steps":["trace[425094654] 'process raft request' (duration: 346.663918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:18.29251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:17.944802Z","time spent":"347.496079ms","remote":"127.0.0.1:37532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6879,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:35:18.293098Z","caller":"traceutil/trace.go:171","msg":"trace[255089827] transaction","detail":"{read_only:false; response_revision:201; number_of_response:1; }","duration":"325.234ms","start":"2026-02-12T03:35:17.967852Z","end":"2026-02-12T03:35:18.293086Z","steps":["trace[255089827] 'process raft request' (duration: 323.696859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:18.293208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:17.967838Z","time spent":"325.340972ms","remote":"127.0.0.1:37688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":744,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-12T03:35:18.293442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.026469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-12T03:35:18.293688Z","caller":"traceutil/trace.go:171","msg":"trace[511501123] transaction","detail":"{read_only:false; response_revision:202; number_of_response:1; }","duration":"323.441365ms","start":"2026-02-12T03:35:17.970234Z","end":"2026-02-12T03:35:18.293675Z","steps":["trace[511501123] 'process raft request' (duration: 321.345685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:18.293794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:17.97022Z","time spent":"323.541687ms","remote":"127.0.0.1:37422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":753,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T03:35:18.29352Z","caller":"traceutil/trace.go:171","msg":"trace[1758340898] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:202; }","duration":"323.135691ms","start":"2026-02-12T03:35:17.970366Z","end":"2026-02-12T03:35:18.293502Z","steps":["trace[1758340898] 'agreement among raft nodes before linearized reading' (duration: 322.177007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:18.294768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:17.970358Z","time spent":"324.220356ms","remote":"127.0.0.1:37608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-12T03:35:18.536247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.199091ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T03:35:18.536568Z","caller":"traceutil/trace.go:171","msg":"trace[206086160] linearizableReadLoop","detail":"{readStateIndex:210; appliedIndex:208; }","duration":"106.990204ms","start":"2026-02-12T03:35:18.429565Z","end":"2026-02-12T03:35:18.536556Z","steps":["trace[206086160] 'read index received' (duration: 65.465208ms)","trace[206086160] 'applied index is now lower than readState.Index' (duration: 41.524456ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:35:18.536735Z","caller":"traceutil/trace.go:171","msg":"trace[443292549] transaction","detail":"{read_only:false; response_revision:203; number_of_response:1; }","duration":"235.82165ms","start":"2026-02-12T03:35:18.300894Z","end":"2026-02-12T03:35:18.536716Z","steps":["trace[443292549] 'process raft request' (duration: 99.091261ms)","trace[443292549] 'compare' (duration: 135.982238ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:35:18.536848Z","caller":"traceutil/trace.go:171","msg":"trace[2067480201] transaction","detail":"{read_only:false; response_revision:204; number_of_response:1; }","duration":"235.782029ms","start":"2026-02-12T03:35:18.300998Z","end":"2026-02-12T03:35:18.53678Z","steps":["trace[2067480201] 'process raft request' (duration: 235.508755ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:18.53707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.494511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:35:18.537137Z","caller":"traceutil/trace.go:171","msg":"trace[902680876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:204; }","duration":"107.569332ms","start":"2026-02-12T03:35:18.429559Z","end":"2026-02-12T03:35:18.537129Z","steps":["trace[902680876] 'agreement among raft nodes before linearized reading' (duration: 107.47864ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:35:19.119588Z","caller":"traceutil/trace.go:171","msg":"trace[880620399] transaction","detail":"{read_only:false; response_revision:228; number_of_response:1; }","duration":"114.44787ms","start":"2026-02-12T03:35:19.005112Z","end":"2026-02-12T03:35:19.11956Z","steps":["trace[880620399] 'process raft request' (duration: 41.096428ms)","trace[880620399] 'compare' (duration: 73.150689ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:35:19.296996Z","caller":"traceutil/trace.go:171","msg":"trace[444836328] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"125.105214ms","start":"2026-02-12T03:35:19.171867Z","end":"2026-02-12T03:35:19.296972Z","steps":["trace[444836328] 'process raft request' (duration: 59.065096ms)","trace[444836328] 'compare' (duration: 65.604772ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:35:19.554869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.815663ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-12T03:35:19.555059Z","caller":"traceutil/trace.go:171","msg":"trace[1773089391] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"182.487375ms","start":"2026-02-12T03:35:19.372538Z","end":"2026-02-12T03:35:19.555025Z","steps":["trace[1773089391] 'process raft request' (duration: 56.435479ms)","trace[1773089391] 'compare' (duration: 125.662561ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:35:19.876794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.596818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-12T03:35:19.877075Z","caller":"traceutil/trace.go:171","msg":"trace[1914297991] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"255.183097ms","start":"2026-02-12T03:35:19.621853Z","end":"2026-02-12T03:35:19.877036Z","steps":["trace[1914297991] 'process raft request' (duration: 65.293535ms)","trace[1914297991] 'compare' (duration: 189.475546ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:35:20.204918Z","caller":"traceutil/trace.go:171","msg":"trace[1644794932] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"140.063579ms","start":"2026-02-12T03:35:20.064834Z","end":"2026-02-12T03:35:20.204898Z","steps":["trace[1644794932] 'process raft request' (duration: 118.258226ms)","trace[1644794932] 'compare' (duration: 21.683321ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:35:25.187779Z","caller":"traceutil/trace.go:171","msg":"trace[783983672] linearizableReadLoop","detail":"{readStateIndex:363; appliedIndex:362; }","duration":"116.453332ms","start":"2026-02-12T03:35:25.071297Z","end":"2026-02-12T03:35:25.18775Z","steps":["trace[783983672] 'read index received' (duration: 116.339861ms)","trace[783983672] 'applied index is now lower than readState.Index' (duration: 111.941µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:35:25.188017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.680095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4256"} {"level":"info","ts":"2026-02-12T03:35:25.188055Z","caller":"traceutil/trace.go:171","msg":"trace[1553945462] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:350; }","duration":"116.817137ms","start":"2026-02-12T03:35:25.071224Z","end":"2026-02-12T03:35:25.188041Z","steps":["trace[1553945462] 'agreement among raft nodes before linearized reading' (duration: 116.639875ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:35:25.188055Z","caller":"traceutil/trace.go:171","msg":"trace[1816613194] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"256.290759ms","start":"2026-02-12T03:35:24.931726Z","end":"2026-02-12T03:35:25.188017Z","steps":["trace[1816613194] 'process raft request' (duration: 255.853603ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:35:25.921619Z","caller":"traceutil/trace.go:171","msg":"trace[1745681871] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"158.553727ms","start":"2026-02-12T03:35:25.763034Z","end":"2026-02-12T03:35:25.921588Z","steps":["trace[1745681871] 'process raft request' (duration: 158.447625ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:35:25.922379Z","caller":"traceutil/trace.go:171","msg":"trace[136073714] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"200.397847ms","start":"2026-02-12T03:35:25.72197Z","end":"2026-02-12T03:35:25.922368Z","steps":["trace[136073714] 'process raft request' (duration: 199.434703ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:35:25.922865Z","caller":"traceutil/trace.go:171","msg":"trace[490508227] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"200.993305ms","start":"2026-02-12T03:35:25.721809Z","end":"2026-02-12T03:35:25.922803Z","steps":["trace[490508227] 'process raft request' (duration: 114.742307ms)","trace[490508227] 'compare' (duration: 84.637195ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:35:25.923042Z","caller":"traceutil/trace.go:171","msg":"trace[977900456] linearizableReadLoop","detail":"{readStateIndex:373; appliedIndex:371; }","duration":"168.818053ms","start":"2026-02-12T03:35:25.754213Z","end":"2026-02-12T03:35:25.923031Z","steps":["trace[977900456] 'read index received' (duration: 82.354692ms)","trace[977900456] 'applied index is now lower than readState.Index' (duration: 86.462221ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:35:25.923127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.388652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-12T03:35:25.927075Z","caller":"traceutil/trace.go:171","msg":"trace[1436768242] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:361; }","duration":"196.574772ms","start":"2026-02-12T03:35:25.730485Z","end":"2026-02-12T03:35:25.92706Z","steps":["trace[1436768242] 'agreement among raft nodes before linearized reading' (duration: 192.582585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:25.927346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.662637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-12T03:35:25.927376Z","caller":"traceutil/trace.go:171","msg":"trace[1682098168] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:361; }","duration":"146.695067ms","start":"2026-02-12T03:35:25.780673Z","end":"2026-02-12T03:35:25.927368Z","steps":["trace[1682098168] 'agreement among raft nodes before linearized reading' (duration: 146.642096ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:35:26.232784Z","caller":"traceutil/trace.go:171","msg":"trace[1179281945] linearizableReadLoop","detail":"{readStateIndex:384; appliedIndex:383; }","duration":"151.062099ms","start":"2026-02-12T03:35:26.081697Z","end":"2026-02-12T03:35:26.23276Z","steps":["trace[1179281945] 'read index received' (duration: 108.971054ms)","trace[1179281945] 'applied index is now lower than readState.Index' (duration: 42.090075ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:35:26.233099Z","caller":"traceutil/trace.go:171","msg":"trace[495572451] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"163.858274ms","start":"2026-02-12T03:35:26.069038Z","end":"2026-02-12T03:35:26.232897Z","steps":["trace[495572451] 'process raft request' (duration: 121.696938ms)","trace[495572451] 'compare' (duration: 41.910752ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:35:26.233578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.456067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-12T03:35:26.233752Z","caller":"traceutil/trace.go:171","msg":"trace[1163968398] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:371; }","duration":"101.68554ms","start":"2026-02-12T03:35:26.132045Z","end":"2026-02-12T03:35:26.233731Z","steps":["trace[1163968398] 'agreement among raft nodes before linearized reading' (duration: 101.435986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:26.234177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.460779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-12T03:35:26.234231Z","caller":"traceutil/trace.go:171","msg":"trace[1760279153] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:371; }","duration":"152.54117ms","start":"2026-02-12T03:35:26.081675Z","end":"2026-02-12T03:35:26.234216Z","steps":["trace[1760279153] 'agreement among raft nodes before linearized reading' (duration: 152.379858ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:35:26.495317Z","caller":"traceutil/trace.go:171","msg":"trace[532607484] linearizableReadLoop","detail":"{readStateIndex:390; appliedIndex:385; }","duration":"214.141245ms","start":"2026-02-12T03:35:26.281154Z","end":"2026-02-12T03:35:26.495295Z","steps":["trace[532607484] 'read index received' (duration: 36.426833ms)","trace[532607484] 'applied index is now lower than readState.Index' (duration: 177.713492ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:35:26.495617Z","caller":"traceutil/trace.go:171","msg":"trace[1329440440] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"248.518848ms","start":"2026-02-12T03:35:26.247004Z","end":"2026-02-12T03:35:26.495523Z","steps":["trace[1329440440] 'process raft request' (duration: 247.96851ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:35:26.49584Z","caller":"traceutil/trace.go:171","msg":"trace[659977557] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"246.218205ms","start":"2026-02-12T03:35:26.249608Z","end":"2026-02-12T03:35:26.495827Z","steps":["trace[659977557] 'process raft request' (duration: 245.461223ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:35:26.496099Z","caller":"traceutil/trace.go:171","msg":"trace[1253303193] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"246.429569ms","start":"2026-02-12T03:35:26.249658Z","end":"2026-02-12T03:35:26.496088Z","steps":["trace[1253303193] 'process raft request' (duration: 245.475854ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:35:26.496411Z","caller":"traceutil/trace.go:171","msg":"trace[1575188510] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"246.630241ms","start":"2026-02-12T03:35:26.249766Z","end":"2026-02-12T03:35:26.496396Z","steps":["trace[1575188510] 'process raft request' (duration: 245.489524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:26.495568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.280836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-12T03:35:26.497106Z","caller":"traceutil/trace.go:171","msg":"trace[746613605] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:377; }","duration":"215.985361ms","start":"2026-02-12T03:35:26.281107Z","end":"2026-02-12T03:35:26.497092Z","steps":["trace[746613605] 'agreement among raft nodes before linearized reading' (duration: 214.275916ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:35:26.497666Z","caller":"traceutil/trace.go:171","msg":"trace[2049501281] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"251.140425ms","start":"2026-02-12T03:35:26.24651Z","end":"2026-02-12T03:35:26.497651Z","steps":["trace[2049501281] 'process raft request' (duration: 218.818701ms)","trace[2049501281] 'compare' (duration: 29.514653ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:35:26.497939Z","caller":"traceutil/trace.go:171","msg":"trace[68064476] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"177.013032ms","start":"2026-02-12T03:35:26.320916Z","end":"2026-02-12T03:35:26.497929Z","steps":["trace[68064476] 'process raft request' (duration: 176.962721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:35.409781Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757018381212238039,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T03:35:35.910028Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757018381212238039,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T03:35:36.410798Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757018381212238039,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T03:35:36.45466Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.597467104s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-12T03:35:36.909398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000457332s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-12T03:35:36.909474Z","caller":"traceutil/trace.go:171","msg":"trace[343200005] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000588044s","start":"2026-02-12T03:35:34.908873Z","end":"2026-02-12T03:35:36.909461Z","steps":["trace[343200005] 'agreement among raft nodes before linearized reading' (duration: 2.000494163s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:36.909511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:34.908853Z","time spent":"2.000651705s","remote":"127.0.0.1:37332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T03:35:37.037602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.50732594s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:35:37.037682Z","caller":"traceutil/trace.go:171","msg":"trace[1684309128] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:448; }","duration":"1.507422892s","start":"2026-02-12T03:35:35.530248Z","end":"2026-02-12T03:35:37.037671Z","steps":["trace[1684309128] 'range keys from in-memory index tree' (duration: 1.50731214s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:37.038285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.181173696s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T03:35:37.038381Z","caller":"traceutil/trace.go:171","msg":"trace[216750981] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"2.207435352s","start":"2026-02-12T03:35:34.83094Z","end":"2026-02-12T03:35:37.038375Z","steps":["trace[216750981] 'process raft request' (duration: 2.207409522s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:37.038422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:34.830931Z","time spent":"2.207476143s","remote":"127.0.0.1:37608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:35:37.038564Z","caller":"traceutil/trace.go:171","msg":"trace[1908055232] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"2.219576307s","start":"2026-02-12T03:35:34.818982Z","end":"2026-02-12T03:35:37.038558Z","steps":["trace[1908055232] 'process raft request' (duration: 38.093037ms)","trace[1908055232] 'compare' (duration: 2.18079096s)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:35:37.038594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:34.818972Z","time spent":"2.219608027s","remote":"127.0.0.1:37532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3991,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:35:37.038678Z","caller":"traceutil/trace.go:171","msg":"trace[25750651] transaction","detail":"{read_only:false; number_of_response:1; response_revision:449; }","duration":"2.212928382s","start":"2026-02-12T03:35:34.825745Z","end":"2026-02-12T03:35:37.038673Z","steps":["trace[25750651] 'process raft request' (duration: 2.212576317s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:37.038705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:34.825734Z","time spent":"2.212956512s","remote":"127.0.0.1:37824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":56,"response count":0,"response size":3866,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:35:37.893215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.638637ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-12T03:35:37.893381Z","caller":"traceutil/trace.go:171","msg":"trace[692001937] linearizableReadLoop","detail":"{readStateIndex:468; appliedIndex:464; }","duration":"2.984439643s","start":"2026-02-12T03:35:34.90892Z","end":"2026-02-12T03:35:37.89336Z","steps":["trace[692001937] 'read index received' (duration: 1.545905151s)","trace[692001937] 'applied index is now lower than readState.Index' (duration: 1.438532462s)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:35:37.893414Z","caller":"traceutil/trace.go:171","msg":"trace[1827368413] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"848.015794ms","start":"2026-02-12T03:35:37.04539Z","end":"2026-02-12T03:35:37.893406Z","steps":["trace[1827368413] 'process raft request' (duration: 847.990153ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:37.893476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:37.045385Z","time spent":"848.048804ms","remote":"127.0.0.1:37824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3818,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:35:37.893566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.18890337s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-12T03:35:37.893595Z","caller":"traceutil/trace.go:171","msg":"trace[1670280093] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:453; }","duration":"1.18895972s","start":"2026-02-12T03:35:36.704626Z","end":"2026-02-12T03:35:37.893585Z","steps":["trace[1670280093] 'agreement among raft nodes before linearized reading' (duration: 1.188833469s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:37.893636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:36.704613Z","time spent":"1.189005312s","remote":"127.0.0.1:37608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-12T03:35:37.893689Z","caller":"traceutil/trace.go:171","msg":"trace[154241578] transaction","detail":"{read_only:false; number_of_response:1; response_revision:451; }","duration":"853.665805ms","start":"2026-02-12T03:35:37.040018Z","end":"2026-02-12T03:35:37.893684Z","steps":["trace[154241578] 'process raft request' (duration: 853.262769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:37.893733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:37.040008Z","time spent":"853.693745ms","remote":"127.0.0.1:37532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":55,"response count":0,"response size":40,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-02-12T03:35:37.893792Z","caller":"traceutil/trace.go:171","msg":"trace[1688231894] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"848.536931ms","start":"2026-02-12T03:35:37.045251Z","end":"2026-02-12T03:35:37.893788Z","steps":["trace[1688231894] 'process raft request' (duration: 848.099195ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:37.89379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"983.89818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-12T03:35:37.893814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:37.04524Z","time spent":"848.563781ms","remote":"127.0.0.1:37422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":721,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T03:35:37.893817Z","caller":"traceutil/trace.go:171","msg":"trace[2089613007] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:453; }","duration":"983.94127ms","start":"2026-02-12T03:35:36.909868Z","end":"2026-02-12T03:35:37.893809Z","steps":["trace[2089613007] 'agreement among raft nodes before linearized reading' (duration: 983.90194ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:37.893841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:36.909859Z","time spent":"983.977421ms","remote":"127.0.0.1:37334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T03:35:37.893993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069480531s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-12T03:35:37.894021Z","caller":"traceutil/trace.go:171","msg":"trace[92086945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:453; }","duration":"1.069515191s","start":"2026-02-12T03:35:36.824498Z","end":"2026-02-12T03:35:37.894013Z","steps":["trace[92086945] 'agreement among raft nodes before linearized reading' (duration: 1.069468431s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:37.89404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.149347921s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-12T03:35:37.894055Z","caller":"traceutil/trace.go:171","msg":"trace[1006800889] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:453; }","duration":"1.149364311s","start":"2026-02-12T03:35:36.744687Z","end":"2026-02-12T03:35:37.894051Z","steps":["trace[1006800889] 'agreement among raft nodes before linearized reading' (duration: 1.14933724s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:37.894045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:36.824496Z","time spent":"1.069543562s","remote":"127.0.0.1:37608","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-02-12T03:35:37.894094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069603342s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-12T03:35:37.894125Z","caller":"traceutil/trace.go:171","msg":"trace[91496012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:453; }","duration":"1.069633233s","start":"2026-02-12T03:35:36.824487Z","end":"2026-02-12T03:35:37.894121Z","steps":["trace[91496012] 'agreement among raft nodes before linearized reading' (duration: 1.069595212s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:37.894136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:36.824456Z","time spent":"1.069676454s","remote":"127.0.0.1:37608","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-02-12T03:35:37.894075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:36.74465Z","time spent":"1.149422412s","remote":"127.0.0.1:37608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-12T03:35:50.118267Z","caller":"traceutil/trace.go:171","msg":"trace[2033776396] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:579; }","duration":"122.248128ms","start":"2026-02-12T03:35:49.996002Z","end":"2026-02-12T03:35:50.11825Z","steps":["trace[2033776396] 'read index received' (duration: 122.210706ms)","trace[2033776396] 'applied index is now lower than readState.Index' (duration: 36.852µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:35:50.118354Z","caller":"traceutil/trace.go:171","msg":"trace[1452262655] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"135.691531ms","start":"2026-02-12T03:35:49.982655Z","end":"2026-02-12T03:35:50.118347Z","steps":["trace[1452262655] 'process raft request' (duration: 135.501195ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:50.118564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.548747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-12T03:35:50.118587Z","caller":"traceutil/trace.go:171","msg":"trace[1348039875] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:560; }","duration":"122.603349ms","start":"2026-02-12T03:35:49.995977Z","end":"2026-02-12T03:35:50.11858Z","steps":["trace[1348039875] 'agreement among raft nodes before linearized reading' (duration: 122.511356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:50.118661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.966657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-12T03:35:50.11869Z","caller":"traceutil/trace.go:171","msg":"trace[1229586694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:560; }","duration":"115.06429ms","start":"2026-02-12T03:35:50.003619Z","end":"2026-02-12T03:35:50.118683Z","steps":["trace[1229586694] 'agreement among raft nodes before linearized reading' (duration: 115.025268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:50.118828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.793007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-12T03:35:50.118855Z","caller":"traceutil/trace.go:171","msg":"trace[1758677429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:560; }","duration":"113.831589ms","start":"2026-02-12T03:35:50.005009Z","end":"2026-02-12T03:35:50.118841Z","steps":["trace[1758677429] 'agreement among raft nodes before linearized reading' (duration: 113.803808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:50.834355Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757018381212238247,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T03:35:51.259241Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.13275219s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-12T03:35:51.607201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.832473ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T03:35:51.607326Z","caller":"traceutil/trace.go:171","msg":"trace[1131034870] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"1.480883986s","start":"2026-02-12T03:35:50.126421Z","end":"2026-02-12T03:35:51.607305Z","steps":["trace[1131034870] 'process raft request' (duration: 1.133833526s)","trace[1131034870] 'compare' (duration: 346.206473ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:35:51.60738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:50.126407Z","time spent":"1.480949269s","remote":"127.0.0.1:37608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:35:51.966971Z","caller":"traceutil/trace.go:171","msg":"trace[361072359] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"1.832215633s","start":"2026-02-12T03:35:50.134401Z","end":"2026-02-12T03:35:51.966616Z","steps":["trace[361072359] 'process raft request' (duration: 1.830869139s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:51.96757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:50.134384Z","time spent":"1.832682389s","remote":"127.0.0.1:37608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:35:51.968736Z","caller":"traceutil/trace.go:171","msg":"trace[16333423] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"1.827597003s","start":"2026-02-12T03:35:50.141125Z","end":"2026-02-12T03:35:51.968722Z","steps":["trace[16333423] 'process raft request' (duration: 1.825442003s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:51.968835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:50.14111Z","time spent":"1.827666795s","remote":"127.0.0.1:37608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:35:51.971217Z","caller":"traceutil/trace.go:171","msg":"trace[998612237] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"1.699030089s","start":"2026-02-12T03:35:50.272156Z","end":"2026-02-12T03:35:51.971186Z","steps":["trace[998612237] 'process raft request' (duration: 1.696127555s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:51.971525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:50.272138Z","time spent":"1.699158824s","remote":"127.0.0.1:37408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T03:35:51.972282Z","caller":"traceutil/trace.go:171","msg":"trace[32380180] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"1.301456078s","start":"2026-02-12T03:35:50.670809Z","end":"2026-02-12T03:35:51.972265Z","steps":["trace[32380180] 'process raft request' (duration: 1.301097697s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:51.972352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:50.670774Z","time spent":"1.301549811s","remote":"127.0.0.1:37408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T03:35:51.972808Z","caller":"traceutil/trace.go:171","msg":"trace[893827603] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:580; }","duration":"1.63951118s","start":"2026-02-12T03:35:50.333284Z","end":"2026-02-12T03:35:51.972795Z","steps":["trace[893827603] 'read index received' (duration: 927.408544ms)","trace[893827603] 'applied index is now lower than readState.Index' (duration: 712.100946ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:35:51.973011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.639704656s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-12T03:35:51.973115Z","caller":"traceutil/trace.go:171","msg":"trace[1486099168] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:565; }","duration":"1.639783769s","start":"2026-02-12T03:35:50.33328Z","end":"2026-02-12T03:35:51.973064Z","steps":["trace[1486099168] 'agreement among raft nodes before linearized reading' (duration: 1.639624794s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:51.973167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:50.33324Z","time spent":"1.639914713s","remote":"127.0.0.1:37384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.38\" "} {"level":"warn","ts":"2026-02-12T03:35:51.974262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.956919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-12T03:35:51.974445Z","caller":"traceutil/trace.go:171","msg":"trace[713850373] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:566; }","duration":"707.049693ms","start":"2026-02-12T03:35:51.267249Z","end":"2026-02-12T03:35:51.974299Z","steps":["trace[713850373] 'agreement among raft nodes before linearized reading' (duration: 706.872536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:51.974518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:51.267229Z","time spent":"707.252409ms","remote":"127.0.0.1:37408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5199,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"info","ts":"2026-02-12T03:35:51.97476Z","caller":"traceutil/trace.go:171","msg":"trace[1100777492] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"292.56952ms","start":"2026-02-12T03:35:51.682177Z","end":"2026-02-12T03:35:51.974747Z","steps":["trace[1100777492] 'process raft request' (duration: 289.866512ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:51.975014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.425389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-12T03:35:51.975054Z","caller":"traceutil/trace.go:171","msg":"trace[1984053465] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:566; }","duration":"106.524582ms","start":"2026-02-12T03:35:51.86852Z","end":"2026-02-12T03:35:51.975045Z","steps":["trace[1984053465] 'agreement among raft nodes before linearized reading' (duration: 106.395048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:51.975297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.716022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-12T03:35:51.975337Z","caller":"traceutil/trace.go:171","msg":"trace[1313935429] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:566; }","duration":"507.757744ms","start":"2026-02-12T03:35:51.467569Z","end":"2026-02-12T03:35:51.975327Z","steps":["trace[1313935429] 'agreement among raft nodes before linearized reading' (duration: 507.66274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:51.97543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:51.467511Z","time spent":"507.887008ms","remote":"127.0.0.1:37408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4355,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-02-12T03:35:51.976055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.083313ms","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-02-12T03:35:51.976099Z","caller":"traceutil/trace.go:171","msg":"trace[1842227466] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:566; }","duration":"930.151954ms","start":"2026-02-12T03:35:51.045936Z","end":"2026-02-12T03:35:51.976088Z","steps":["trace[1842227466] 'agreement among raft nodes before linearized reading' (duration: 930.049671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:51.976428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:51.045921Z","time spent":"930.445744ms","remote":"127.0.0.1:37608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-12T03:35:51.976682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.763889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:35:51.976726Z","caller":"traceutil/trace.go:171","msg":"trace[523144566] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:566; }","duration":"892.844261ms","start":"2026-02-12T03:35:51.08387Z","end":"2026-02-12T03:35:51.976714Z","steps":["trace[523144566] 'agreement among raft nodes before linearized reading' (duration: 892.775789ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:51.97682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:51.08385Z","time spent":"892.937784ms","remote":"127.0.0.1:37448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"warn","ts":"2026-02-12T03:35:51.977044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.709049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-12T03:35:51.977083Z","caller":"traceutil/trace.go:171","msg":"trace[999959254] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:566; }","duration":"909.793692ms","start":"2026-02-12T03:35:51.067279Z","end":"2026-02-12T03:35:51.977073Z","steps":["trace[999959254] 'agreement among raft nodes before linearized reading' (duration: 909.696698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:51.977153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:51.067252Z","time spent":"909.888156ms","remote":"127.0.0.1:37408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4202,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-02-12T03:35:51.978013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066678476s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:35:51.97807Z","caller":"traceutil/trace.go:171","msg":"trace[152001940] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:566; }","duration":"1.066756798s","start":"2026-02-12T03:35:50.911296Z","end":"2026-02-12T03:35:51.978053Z","steps":["trace[152001940] 'agreement among raft nodes before linearized reading' (duration: 1.066679045s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:51.978179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:50.911279Z","time spent":"1.066889213s","remote":"127.0.0.1:37332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T03:35:51.978554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.11109347s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-12T03:35:51.978576Z","caller":"traceutil/trace.go:171","msg":"trace[1896414031] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:566; }","duration":"1.111139092s","start":"2026-02-12T03:35:50.867432Z","end":"2026-02-12T03:35:51.978571Z","steps":["trace[1896414031] 'agreement among raft nodes before linearized reading' (duration: 1.11108687s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:51.978628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:50.867416Z","time spent":"1.111206664s","remote":"127.0.0.1:37408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5608,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-02-12T03:35:51.978918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.510602546s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:35:51.978944Z","caller":"traceutil/trace.go:171","msg":"trace[874447536] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:566; }","duration":"1.510651097s","start":"2026-02-12T03:35:50.468285Z","end":"2026-02-12T03:35:51.978936Z","steps":["trace[874447536] 'agreement among raft nodes before linearized reading' (duration: 1.505791439s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:51.978984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:50.468272Z","time spent":"1.510706689s","remote":"127.0.0.1:37408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-02-12T03:35:53.387455Z","caller":"traceutil/trace.go:171","msg":"trace[1937808677] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"466.160645ms","start":"2026-02-12T03:35:52.921279Z","end":"2026-02-12T03:35:53.387439Z","steps":["trace[1937808677] 'process raft request' (duration: 462.799948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:53.389274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:52.921268Z","time spent":"467.936513ms","remote":"127.0.0.1:37408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188480,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:35:53.398002Z","caller":"traceutil/trace.go:171","msg":"trace[247921206] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"456.616586ms","start":"2026-02-12T03:35:52.941375Z","end":"2026-02-12T03:35:53.397992Z","steps":["trace[247921206] 'process raft request' (duration: 456.157211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:53.398058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:52.94136Z","time spent":"456.665658ms","remote":"127.0.0.1:37408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:35:53.402008Z","caller":"traceutil/trace.go:171","msg":"trace[1589812557] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"193.051042ms","start":"2026-02-12T03:35:53.208952Z","end":"2026-02-12T03:35:53.402003Z","steps":["trace[1589812557] 'process raft request' (duration: 188.995732ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:35:53.40216Z","caller":"traceutil/trace.go:171","msg":"trace[1204004440] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"180.2258ms","start":"2026-02-12T03:35:53.221928Z","end":"2026-02-12T03:35:53.402153Z","steps":["trace[1204004440] 'process raft request' (duration: 176.043217ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:35:53.401926Z","caller":"traceutil/trace.go:171","msg":"trace[1325287368] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:599; }","duration":"325.310636ms","start":"2026-02-12T03:35:53.076605Z","end":"2026-02-12T03:35:53.401916Z","steps":["trace[1325287368] 'read index received' (duration: 310.787331ms)","trace[1325287368] 'applied index is now lower than readState.Index' (duration: 14.522795ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:35:53.4024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.157483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-02-12T03:35:53.40243Z","caller":"traceutil/trace.go:171","msg":"trace[1169017803] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:582; }","duration":"133.211555ms","start":"2026-02-12T03:35:53.269213Z","end":"2026-02-12T03:35:53.402424Z","steps":["trace[1169017803] 'agreement among raft nodes before linearized reading' (duration: 133.098221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:53.402578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.970867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-12T03:35:53.402592Z","caller":"traceutil/trace.go:171","msg":"trace[1643136537] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:582; }","duration":"326.007708ms","start":"2026-02-12T03:35:53.076579Z","end":"2026-02-12T03:35:53.402587Z","steps":["trace[1643136537] 'agreement among raft nodes before linearized reading' (duration: 325.706679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:53.402622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:53.076558Z","time spent":"326.059829ms","remote":"127.0.0.1:37408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24266,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"info","ts":"2026-02-12T03:35:57.821898Z","caller":"traceutil/trace.go:171","msg":"trace[1691188943] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"157.3096ms","start":"2026-02-12T03:35:57.664573Z","end":"2026-02-12T03:35:57.821882Z","steps":["trace[1691188943] 'process raft request' (duration: 157.201307ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:35:59.789622Z","caller":"traceutil/trace.go:171","msg":"trace[229336145] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"473.654455ms","start":"2026-02-12T03:35:59.315951Z","end":"2026-02-12T03:35:59.789606Z","steps":["trace[229336145] 'process raft request' (duration: 473.563532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:59.789723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:59.315933Z","time spent":"473.737827ms","remote":"127.0.0.1:37532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:36:00.411397Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757018381212238407,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-12T03:36:00.707825Z","caller":"traceutil/trace.go:171","msg":"trace[282193936] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"796.548546ms","start":"2026-02-12T03:35:59.911255Z","end":"2026-02-12T03:36:00.707803Z","steps":["trace[282193936] 'read index received' (duration: 796.32474ms)","trace[282193936] 'applied index is now lower than readState.Index' (duration: 222.956µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:36:00.707927Z","caller":"traceutil/trace.go:171","msg":"trace[1891852307] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"876.521318ms","start":"2026-02-12T03:35:59.831395Z","end":"2026-02-12T03:36:00.707917Z","steps":["trace[1891852307] 'process raft request' (duration: 876.233699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:00.708012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:59.83138Z","time spent":"876.570139ms","remote":"127.0.0.1:37608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:36:00.708051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.757954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-12T03:36:00.708083Z","caller":"traceutil/trace.go:171","msg":"trace[1648112883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:627; }","duration":"684.815476ms","start":"2026-02-12T03:36:00.023259Z","end":"2026-02-12T03:36:00.708074Z","steps":["trace[1648112883] 'agreement among raft nodes before linearized reading' (duration: 684.708473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:00.708105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:36:00.023245Z","time spent":"684.855237ms","remote":"127.0.0.1:37608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T03:36:00.708223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.764278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-12T03:36:00.708233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.411923ms","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-02-12T03:36:00.708253Z","caller":"traceutil/trace.go:171","msg":"trace[372393378] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:627; }","duration":"518.464195ms","start":"2026-02-12T03:36:00.189783Z","end":"2026-02-12T03:36:00.708247Z","steps":["trace[372393378] 'agreement among raft nodes before linearized reading' (duration: 518.408593ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:36:00.708251Z","caller":"traceutil/trace.go:171","msg":"trace[1874984497] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:627; }","duration":"504.790239ms","start":"2026-02-12T03:36:00.203452Z","end":"2026-02-12T03:36:00.708242Z","steps":["trace[1874984497] 'agreement among raft nodes before linearized reading' (duration: 504.708066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:00.70827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:36:00.189739Z","time spent":"518.526667ms","remote":"127.0.0.1:37608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-12T03:36:00.70828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:36:00.203396Z","time spent":"504.878671ms","remote":"127.0.0.1:37608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-12T03:36:00.70837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.036477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-12T03:36:00.708387Z","caller":"traceutil/trace.go:171","msg":"trace[11866830] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:627; }","duration":"672.073269ms","start":"2026-02-12T03:36:00.036308Z","end":"2026-02-12T03:36:00.708381Z","steps":["trace[11866830] 'agreement among raft nodes before linearized reading' (duration: 672.024027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:00.708388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.164755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-12T03:36:00.708404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:36:00.036296Z","time spent":"672.104129ms","remote":"127.0.0.1:37608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-12T03:36:00.708411Z","caller":"traceutil/trace.go:171","msg":"trace[982119543] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"797.186515ms","start":"2026-02-12T03:35:59.911217Z","end":"2026-02-12T03:36:00.708404Z","steps":["trace[982119543] 'agreement among raft nodes before linearized reading' (duration: 797.153134ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:00.708434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:59.911122Z","time spent":"797.307109ms","remote":"127.0.0.1:37332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T03:36:00.708584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.36417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-12T03:36:00.708608Z","caller":"traceutil/trace.go:171","msg":"trace[712611631] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:627; }","duration":"374.404251ms","start":"2026-02-12T03:36:00.334196Z","end":"2026-02-12T03:36:00.708601Z","steps":["trace[712611631] 'agreement among raft nodes before linearized reading' (duration: 374.335839ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:00.708634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:36:00.334184Z","time spent":"374.444392ms","remote":"127.0.0.1:37384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.38\" "} {"level":"warn","ts":"2026-02-12T03:36:01.848641Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757018381212238413,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T03:36:01.96927Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.255291868s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-12T03:36:02.180157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.728591ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-02-12T03:36:02.180385Z","caller":"traceutil/trace.go:171","msg":"trace[1147561033] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:649; }","duration":"832.259748ms","start":"2026-02-12T03:36:01.348115Z","end":"2026-02-12T03:36:02.180375Z","steps":["trace[1147561033] 'read index received' (duration: 621.30439ms)","trace[1147561033] 'applied index is now lower than readState.Index' (duration: 210.954818ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:36:02.18044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:36:00.713901Z","time spent":"1.466538004s","remote":"127.0.0.1:37384","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-12T03:36:02.180608Z","caller":"traceutil/trace.go:171","msg":"trace[1901110548] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"1.463013157s","start":"2026-02-12T03:36:00.717588Z","end":"2026-02-12T03:36:02.180601Z","steps":["trace[1901110548] 'process raft request' (duration: 1.462642416s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:02.180662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:36:00.717575Z","time spent":"1.463046857s","remote":"127.0.0.1:37608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:36:02.18072Z","caller":"traceutil/trace.go:171","msg":"trace[1953567779] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"1.463064568s","start":"2026-02-12T03:36:00.717651Z","end":"2026-02-12T03:36:02.180715Z","steps":["trace[1953567779] 'process raft request' (duration: 1.462672126s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:02.180743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:36:00.717646Z","time spent":"1.463083149s","remote":"127.0.0.1:37608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:36:02.180795Z","caller":"traceutil/trace.go:171","msg":"trace[1443244915] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"1.463110919s","start":"2026-02-12T03:36:00.71768Z","end":"2026-02-12T03:36:02.180791Z","steps":["trace[1443244915] 'process raft request' (duration: 1.462661836s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:02.180818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:36:00.717676Z","time spent":"1.46312907s","remote":"127.0.0.1:37608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:36:02.18088Z","caller":"traceutil/trace.go:171","msg":"trace[1642557857] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"1.462547752s","start":"2026-02-12T03:36:00.718328Z","end":"2026-02-12T03:36:02.180876Z","steps":["trace[1642557857] 'process raft request' (duration: 1.462029197s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:02.180907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:36:00.718317Z","time spent":"1.462576583s","remote":"127.0.0.1:37608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:36:02.181164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.045501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:36:02.181181Z","caller":"traceutil/trace.go:171","msg":"trace[480601304] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"833.087513ms","start":"2026-02-12T03:36:01.348088Z","end":"2026-02-12T03:36:02.181176Z","steps":["trace[480601304] 'agreement among raft nodes before linearized reading' (duration: 832.862366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:02.181202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:36:01.348075Z","time spent":"833.124454ms","remote":"127.0.0.1:37332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T03:36:02.18154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.272258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:36:02.181559Z","caller":"traceutil/trace.go:171","msg":"trace[33424237] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"273.31703ms","start":"2026-02-12T03:36:01.908237Z","end":"2026-02-12T03:36:02.181554Z","steps":["trace[33424237] 'agreement among raft nodes before linearized reading' (duration: 273.282629ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:36:02.503021Z","caller":"traceutil/trace.go:171","msg":"trace[374420006] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"318.990201ms","start":"2026-02-12T03:36:02.184013Z","end":"2026-02-12T03:36:02.503004Z","steps":["trace[374420006] 'process raft request' (duration: 286.575688ms)","trace[374420006] 'compare' (duration: 32.343591ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:36:02.503135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:36:02.183999Z","time spent":"319.086573ms","remote":"127.0.0.1:37384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:36:09.439448Z","caller":"traceutil/trace.go:171","msg":"trace[1162488184] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"195.277439ms","start":"2026-02-12T03:36:09.244153Z","end":"2026-02-12T03:36:09.43943Z","steps":["trace[1162488184] 'process raft request' (duration: 194.995251ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:36:09.598285Z","caller":"traceutil/trace.go:171","msg":"trace[375290799] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"140.493062ms","start":"2026-02-12T03:36:09.457773Z","end":"2026-02-12T03:36:09.598266Z","steps":["trace[375290799] 'process raft request' (duration: 99.294962ms)","trace[375290799] 'compare' (duration: 41.121768ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:36:33.185926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.75353ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T03:36:33.186051Z","caller":"traceutil/trace.go:171","msg":"trace[1645512961] linearizableReadLoop","detail":"{readStateIndex:850; appliedIndex:849; }","duration":"198.703888ms","start":"2026-02-12T03:36:32.987326Z","end":"2026-02-12T03:36:33.18603Z","steps":["trace[1645512961] 'read index received' (duration: 23.68µs)","trace[1645512961] 'applied index is now lower than readState.Index' (duration: 198.678888ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:36:33.186294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.956674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-12T03:36:33.186336Z","caller":"traceutil/trace.go:171","msg":"trace[1192256819] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:819; }","duration":"198.999715ms","start":"2026-02-12T03:36:32.987323Z","end":"2026-02-12T03:36:33.186322Z","steps":["trace[1192256819] 'agreement among raft nodes before linearized reading' (duration: 198.76394ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:36:33.186683Z","caller":"traceutil/trace.go:171","msg":"trace[598879559] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"624.546974ms","start":"2026-02-12T03:36:32.562118Z","end":"2026-02-12T03:36:33.186665Z","steps":["trace[598879559] 'process raft request' (duration: 175.947163ms)","trace[598879559] 'compare' (duration: 447.320639ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:36:33.18677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:36:32.562088Z","time spent":"624.633415ms","remote":"127.0.0.1:37608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:36:50.931338Z","caller":"traceutil/trace.go:171","msg":"trace[1778989450] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"146.847134ms","start":"2026-02-12T03:36:50.784471Z","end":"2026-02-12T03:36:50.931318Z","steps":["trace[1778989450] 'process raft request' (duration: 146.728432ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:37:13.873008Z","caller":"traceutil/trace.go:171","msg":"trace[1469139094] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"173.603133ms","start":"2026-02-12T03:37:13.699383Z","end":"2026-02-12T03:37:13.872986Z","steps":["trace[1469139094] 'process raft request' (duration: 173.530832ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:37:13.873045Z","caller":"traceutil/trace.go:171","msg":"trace[1134758525] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"185.333001ms","start":"2026-02-12T03:37:13.687676Z","end":"2026-02-12T03:37:13.873009Z","steps":["trace[1134758525] 'process raft request' (duration: 107.952558ms)","trace[1134758525] 'compare' (duration: 77.057466ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:37:13.873178Z","caller":"traceutil/trace.go:171","msg":"trace[573430167] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:976; }","duration":"173.991391ms","start":"2026-02-12T03:37:13.699175Z","end":"2026-02-12T03:37:13.873167Z","steps":["trace[573430167] 'read index received' (duration: 96.420324ms)","trace[573430167] 'applied index is now lower than readState.Index' (duration: 77.569377ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:37:13.873319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.114243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:37:13.874779Z","caller":"traceutil/trace.go:171","msg":"trace[160479010] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:939; }","duration":"175.601024ms","start":"2026-02-12T03:37:13.69916Z","end":"2026-02-12T03:37:13.874761Z","steps":["trace[160479010] 'agreement among raft nodes before linearized reading' (duration: 174.039502ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:37:14.703935Z","caller":"traceutil/trace.go:171","msg":"trace[266214320] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"125.881667ms","start":"2026-02-12T03:37:14.578022Z","end":"2026-02-12T03:37:14.703904Z","steps":["trace[266214320] 'process raft request' (duration: 112.153028ms)","trace[266214320] 'compare' (duration: 13.584086ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:37:23.145274Z","caller":"traceutil/trace.go:171","msg":"trace[1845761998] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"227.446511ms","start":"2026-02-12T03:37:22.917803Z","end":"2026-02-12T03:37:23.14525Z","steps":["trace[1845761998] 'process raft request' (duration: 227.260426ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:37:23.145793Z","caller":"traceutil/trace.go:171","msg":"trace[1477836612] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1031; }","duration":"227.809118ms","start":"2026-02-12T03:37:22.917975Z","end":"2026-02-12T03:37:23.145784Z","steps":["trace[1477836612] 'read index received' (duration: 227.803938ms)","trace[1477836612] 'applied index is now lower than readState.Index' (duration: 3.66µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:37:23.14776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.149025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:37:23.147797Z","caller":"traceutil/trace.go:171","msg":"trace[1725377754] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:989; }","duration":"229.824248ms","start":"2026-02-12T03:37:22.917962Z","end":"2026-02-12T03:37:23.147786Z","steps":["trace[1725377754] 'agreement among raft nodes before linearized reading' (duration: 228.010852ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:37:23.287418Z","caller":"traceutil/trace.go:171","msg":"trace[314998346] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"135.803089ms","start":"2026-02-12T03:37:23.151602Z","end":"2026-02-12T03:37:23.287405Z","steps":["trace[314998346] 'process raft request' (duration: 135.250158ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:30.753045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:30.339771Z","time spent":"413.270879ms","remote":"127.0.0.1:37384","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""}