{"level":"warn","ts":"2026-02-08T22:09:34.661932Z","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-08T22:09:34.662213Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89: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.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89: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-08T22:09:34.663306Z","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-08T22:09:34.663361Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-02-08T22:09:34.66349Z","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-08T22:09:34.664392Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-02-08T22:09:34.665001Z","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.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89: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-08T22:09:34.692501Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"27.149073ms"} {"level":"info","ts":"2026-02-08T22:09:34.907517Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-02-08T22:09:34.907746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-02-08T22:09:34.90782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-02-08T22:09:34.907855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-08T22:09:34.907897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-02-08T22:09:34.907938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-02-08T22:09:34.997047Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-08T22:09:35.075497Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-08T22:09:35.112383Z","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-08T22:09:35.128096Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-08T22:09:35.130193Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"warn","ts":"2026-02-08T22:09:35.130369Z","logger":"raft","caller":"etcdserver/zap_raft.go:85","msg":"c3b8446219deb8c1 cannot campaign at term 1 since there are still 1 pending configuration changes to apply"} {"level":"info","ts":"2026-02-08T22:09:35.130569Z","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-08T22:09:35.13066Z","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-08T22:09:35.130725Z","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-08T22:09:35.135028Z","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-08T22:09:35.135303Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-08T22:09:35.135342Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-08T22:09:35.13592Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-02-08T22:09:35.135935Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-02-08T22:09:35.147445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-02-08T22:09:35.147611Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-02-08T22:09:36.110578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-02-08T22:09:36.110625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-08T22:09:36.110654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-02-08T22:09:36.110672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-02-08T22:09:36.110677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-02-08T22:09:36.110686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-02-08T22:09:36.110693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-02-08T22:09:36.111661Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-02-08T22:09:36.111686Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T22:09:36.112312Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T22:09:36.11246Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T22:09:36.112671Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-08T22:09:36.11268Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-08T22:09:36.114035Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"info","ts":"2026-02-08T22:09:36.115594Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T22:09:36.115736Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T22:09:36.115778Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T22:09:36.116301Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-08T22:09:40.455645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.225143ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-08T22:09:40.455787Z","caller":"traceutil/trace.go:171","msg":"trace[1450619482] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"160.212461ms","start":"2026-02-08T22:09:40.295552Z","end":"2026-02-08T22:09:40.455764Z","steps":["trace[1450619482] 'process raft request' (duration: 20.646773ms)","trace[1450619482] 'compare' (duration: 137.987491ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:09:41.123971Z","caller":"traceutil/trace.go:171","msg":"trace[750027503] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"148.509301ms","start":"2026-02-08T22:09:40.975441Z","end":"2026-02-08T22:09:41.12395Z","steps":["trace[750027503] 'process raft request' (duration: 119.014205ms)","trace[750027503] 'compare' (duration: 29.085711ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:09:41.153406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.40471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-08T22:09:41.153487Z","caller":"traceutil/trace.go:171","msg":"trace[1408120633] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:270; }","duration":"123.49898ms","start":"2026-02-08T22:09:41.029964Z","end":"2026-02-08T22:09:41.153463Z","steps":["trace[1408120633] 'agreement among raft nodes before linearized reading' (duration: 123.372749ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:09:41.345849Z","caller":"traceutil/trace.go:171","msg":"trace[14786596] linearizableReadLoop","detail":"{readStateIndex:278; appliedIndex:277; }","duration":"192.64065ms","start":"2026-02-08T22:09:41.153116Z","end":"2026-02-08T22:09:41.345757Z","steps":["trace[14786596] 'read index received' (duration: 98.16355ms)","trace[14786596] 'applied index is now lower than readState.Index' (duration: 94.47593ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:09:41.345989Z","caller":"traceutil/trace.go:171","msg":"trace[1355901875] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"219.083093ms","start":"2026-02-08T22:09:41.12689Z","end":"2026-02-08T22:09:41.345974Z","steps":["trace[1355901875] 'process raft request' (duration: 124.37571ms)","trace[1355901875] 'compare' (duration: 94.327029ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:09:41.346535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.841459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3704"} {"level":"info","ts":"2026-02-08T22:09:41.34678Z","caller":"traceutil/trace.go:171","msg":"trace[767418801] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:271; }","duration":"218.102112ms","start":"2026-02-08T22:09:41.128653Z","end":"2026-02-08T22:09:41.346755Z","steps":["trace[767418801] 'agreement among raft nodes before linearized reading' (duration: 217.718998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:41.347052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.244974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:115"} {"level":"info","ts":"2026-02-08T22:09:41.347109Z","caller":"traceutil/trace.go:171","msg":"trace[535444983] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:271; }","duration":"219.337245ms","start":"2026-02-08T22:09:41.127758Z","end":"2026-02-08T22:09:41.347095Z","steps":["trace[535444983] 'agreement among raft nodes before linearized reading' (duration: 219.207503ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:09:41.382888Z","caller":"traceutil/trace.go:171","msg":"trace[743331687] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"203.462096ms","start":"2026-02-08T22:09:41.179407Z","end":"2026-02-08T22:09:41.382869Z","steps":["trace[743331687] 'process raft request' (duration: 203.098972ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:09:41.38317Z","caller":"traceutil/trace.go:171","msg":"trace[1954574375] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"203.617218ms","start":"2026-02-08T22:09:41.179542Z","end":"2026-02-08T22:09:41.383159Z","steps":["trace[1954574375] 'process raft request' (duration: 203.125982ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:09:41.383312Z","caller":"traceutil/trace.go:171","msg":"trace[36177326] transaction","detail":"{read_only:false; number_of_response:0; response_revision:272; }","duration":"203.780779ms","start":"2026-02-08T22:09:41.17951Z","end":"2026-02-08T22:09:41.383291Z","steps":["trace[36177326] 'process raft request' (duration: 203.121742ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:09:41.383762Z","caller":"traceutil/trace.go:171","msg":"trace[1702817623] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"204.162093ms","start":"2026-02-08T22:09:41.179586Z","end":"2026-02-08T22:09:41.383748Z","steps":["trace[1702817623] 'process raft request' (duration: 203.262283ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:09:41.384089Z","caller":"traceutil/trace.go:171","msg":"trace[1495709064] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"204.500556ms","start":"2026-02-08T22:09:41.179576Z","end":"2026-02-08T22:09:41.384077Z","steps":["trace[1495709064] 'process raft request' (duration: 203.318944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:41.384417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.968162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-08T22:09:41.384462Z","caller":"traceutil/trace.go:171","msg":"trace[271524785] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:275; }","duration":"205.063482ms","start":"2026-02-08T22:09:41.179387Z","end":"2026-02-08T22:09:41.384451Z","steps":["trace[271524785] 'agreement among raft nodes before linearized reading' (duration: 204.958631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:41.384788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.176781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3704"} {"level":"info","ts":"2026-02-08T22:09:41.392724Z","caller":"traceutil/trace.go:171","msg":"trace[288989746] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:275; }","duration":"238.152367ms","start":"2026-02-08T22:09:41.154553Z","end":"2026-02-08T22:09:41.392705Z","steps":["trace[288989746] 'agreement among raft nodes before linearized reading' (duration: 229.439874ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:09:43.123004Z","caller":"traceutil/trace.go:171","msg":"trace[833406584] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"103.498605ms","start":"2026-02-08T22:09:43.019475Z","end":"2026-02-08T22:09:43.122973Z","steps":["trace[833406584] 'process raft request' (duration: 103.267543ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:09:46.508925Z","caller":"traceutil/trace.go:171","msg":"trace[276528929] linearizableReadLoop","detail":"{readStateIndex:379; appliedIndex:378; }","duration":"101.96664ms","start":"2026-02-08T22:09:46.406932Z","end":"2026-02-08T22:09:46.508899Z","steps":["trace[276528929] 'read index received' (duration: 101.707388ms)","trace[276528929] 'applied index is now lower than readState.Index' (duration: 258.492µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:09:46.509112Z","caller":"traceutil/trace.go:171","msg":"trace[1761998284] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"146.693166ms","start":"2026-02-08T22:09:46.362408Z","end":"2026-02-08T22:09:46.509102Z","steps":["trace[1761998284] 'process raft request' (duration: 146.329063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:46.509324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.365454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-08T22:09:46.50935Z","caller":"traceutil/trace.go:171","msg":"trace[1127641346] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:370; }","duration":"102.419135ms","start":"2026-02-08T22:09:46.406922Z","end":"2026-02-08T22:09:46.509341Z","steps":["trace[1127641346] 'agreement among raft nodes before linearized reading' (duration: 102.333024ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:09:48.289622Z","caller":"traceutil/trace.go:171","msg":"trace[876066622] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"181.6472ms","start":"2026-02-08T22:09:48.107942Z","end":"2026-02-08T22:09:48.289589Z","steps":["trace[876066622] 'process raft request' (duration: 181.452298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:52.047755Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093769182363788,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-08T22:09:52.343958Z","caller":"traceutil/trace.go:171","msg":"trace[1093528856] linearizableReadLoop","detail":"{readStateIndex:426; appliedIndex:425; }","duration":"796.870515ms","start":"2026-02-08T22:09:51.547071Z","end":"2026-02-08T22:09:52.343942Z","steps":["trace[1093528856] 'read index received' (duration: 790.328572ms)","trace[1093528856] 'applied index is now lower than readState.Index' (duration: 6.541273ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:09:52.344099Z","caller":"traceutil/trace.go:171","msg":"trace[851839243] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"811.927973ms","start":"2026-02-08T22:09:51.532164Z","end":"2026-02-08T22:09:52.344092Z","steps":["trace[851839243] 'process raft request' (duration: 811.690761ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:52.344295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:09:51.532148Z","time spent":"811.969023ms","remote":"127.0.0.1:54174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:09:52.344419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.34954ms","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-08T22:09:52.34444Z","caller":"traceutil/trace.go:171","msg":"trace[280657701] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:415; }","duration":"797.39041ms","start":"2026-02-08T22:09:51.547043Z","end":"2026-02-08T22:09:52.344434Z","steps":["trace[280657701] 'agreement among raft nodes before linearized reading' (duration: 797.35018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:52.344458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:09:51.547034Z","time spent":"797.42004ms","remote":"127.0.0.1:54174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-08T22:09:52.344538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.304668ms","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-08T22:09:52.344555Z","caller":"traceutil/trace.go:171","msg":"trace[1783191546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:415; }","duration":"537.357998ms","start":"2026-02-08T22:09:51.807191Z","end":"2026-02-08T22:09:52.344549Z","steps":["trace[1783191546] 'agreement among raft nodes before linearized reading' (duration: 537.331228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:52.34457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:09:51.807174Z","time spent":"537.393369ms","remote":"127.0.0.1:54174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-08T22:09:52.345448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.664647ms","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-08T22:09:52.345496Z","caller":"traceutil/trace.go:171","msg":"trace[399309986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:415; }","duration":"508.742768ms","start":"2026-02-08T22:09:51.836744Z","end":"2026-02-08T22:09:52.345487Z","steps":["trace[399309986] 'agreement among raft nodes before linearized reading' (duration: 508.673268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:52.345518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:09:51.836728Z","time spent":"508.783859ms","remote":"127.0.0.1:54174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-08T22:09:52.345834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.220346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4018"} {"level":"info","ts":"2026-02-08T22:09:52.345877Z","caller":"traceutil/trace.go:171","msg":"trace[960563037] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:415; }","duration":"496.272516ms","start":"2026-02-08T22:09:51.849579Z","end":"2026-02-08T22:09:52.345851Z","steps":["trace[960563037] 'agreement among raft nodes before linearized reading' (duration: 496.220235ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:52.345899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:09:51.849569Z","time spent":"496.323866ms","remote":"127.0.0.1:54072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4042,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-08T22:09:52.346165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.943155ms","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-08T22:09:52.352037Z","caller":"traceutil/trace.go:171","msg":"trace[1322983600] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:415; }","duration":"534.241857ms","start":"2026-02-08T22:09:51.812156Z","end":"2026-02-08T22:09:52.346398Z","steps":["trace[1322983600] 'agreement among raft nodes before linearized reading' (duration: 533.979025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:52.352133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:09:51.812134Z","time spent":"539.968333ms","remote":"127.0.0.1:54174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T22:09:53.035968Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093769182363792,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T22:09:53.430572Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.066944859s","expected-duration":"1s"} {"level":"info","ts":"2026-02-08T22:09:53.439273Z","caller":"traceutil/trace.go:171","msg":"trace[1533794842] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"1.074229129s","start":"2026-02-08T22:09:52.365026Z","end":"2026-02-08T22:09:53.439255Z","steps":["trace[1533794842] 'process raft request' (duration: 1.074133998s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:53.439399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:09:52.365019Z","time spent":"1.07431567s","remote":"127.0.0.1:54174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:09:53.439543Z","caller":"traceutil/trace.go:171","msg":"trace[555790507] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"1.076011426s","start":"2026-02-08T22:09:52.363523Z","end":"2026-02-08T22:09:53.439534Z","steps":["trace[555790507] 'process raft request' (duration: 1.067895767s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:53.439596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:09:52.363509Z","time spent":"1.076059587s","remote":"127.0.0.1:54174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:09:53.439913Z","caller":"traceutil/trace.go:171","msg":"trace[933488772] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"1.073816705s","start":"2026-02-08T22:09:52.366088Z","end":"2026-02-08T22:09:53.439905Z","steps":["trace[933488772] 'process raft request' (duration: 1.073140669s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:53.439961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:09:52.366082Z","time spent":"1.073858626s","remote":"127.0.0.1:54174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:09:53.439877Z","caller":"traceutil/trace.go:171","msg":"trace[121043086] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:426; }","duration":"904.841056ms","start":"2026-02-08T22:09:52.53499Z","end":"2026-02-08T22:09:53.439831Z","steps":["trace[121043086] 'read index received' (duration: 896.333753ms)","trace[121043086] 'applied index is now lower than readState.Index' (duration: 8.506233ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:09:53.440063Z","caller":"traceutil/trace.go:171","msg":"trace[1726179397] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"1.072506173s","start":"2026-02-08T22:09:52.36755Z","end":"2026-02-08T22:09:53.440056Z","steps":["trace[1726179397] 'process raft request' (duration: 1.071772366s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:53.441088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:09:52.367544Z","time spent":"1.073429262s","remote":"127.0.0.1:54174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:09:53.442334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.500316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4034"} {"level":"info","ts":"2026-02-08T22:09:53.442479Z","caller":"traceutil/trace.go:171","msg":"trace[149650291] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:419; }","duration":"346.689408ms","start":"2026-02-08T22:09:53.095779Z","end":"2026-02-08T22:09:53.442468Z","steps":["trace[149650291] 'agreement among raft nodes before linearized reading' (duration: 346.488626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:53.442672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:09:53.095763Z","time spent":"346.89954ms","remote":"127.0.0.1:54362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4058,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-08T22:09:53.440105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.114738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:09:53.443069Z","caller":"traceutil/trace.go:171","msg":"trace[14713464] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:419; }","duration":"908.077217ms","start":"2026-02-08T22:09:52.534982Z","end":"2026-02-08T22:09:53.443059Z","steps":["trace[14713464] 'agreement among raft nodes before linearized reading' (duration: 905.101388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:09:53.445334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:09:52.534801Z","time spent":"910.51055ms","remote":"127.0.0.1:53904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-08T22:10:08.021149Z","caller":"traceutil/trace.go:171","msg":"trace[493563067] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:552; }","duration":"428.060527ms","start":"2026-02-08T22:10:07.593069Z","end":"2026-02-08T22:10:08.02113Z","steps":["trace[493563067] 'read index received' (duration: 398.391326ms)","trace[493563067] 'applied index is now lower than readState.Index' (duration: 29.668491ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:10:08.021223Z","caller":"traceutil/trace.go:171","msg":"trace[1312768337] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"431.97661ms","start":"2026-02-08T22:10:07.589238Z","end":"2026-02-08T22:10:08.021214Z","steps":["trace[1312768337] 'process raft request' (duration: 402.214809ms)","trace[1312768337] 'compare' (duration: 29.487269ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:10:08.021306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:07.589229Z","time spent":"432.010821ms","remote":"127.0.0.1:54174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:10:08.021339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.269568ms","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-08T22:10:08.021366Z","caller":"traceutil/trace.go:171","msg":"trace[1439637307] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:537; }","duration":"428.324529ms","start":"2026-02-08T22:10:07.593033Z","end":"2026-02-08T22:10:08.021357Z","steps":["trace[1439637307] 'agreement among raft nodes before linearized reading' (duration: 428.231578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:08.021384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:07.593027Z","time spent":"428.352589ms","remote":"127.0.0.1:54174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-08T22:10:08.024285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.3924ms","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-08T22:10:08.02432Z","caller":"traceutil/trace.go:171","msg":"trace[165472483] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:540; }","duration":"427.440631ms","start":"2026-02-08T22:10:07.596871Z","end":"2026-02-08T22:10:08.024312Z","steps":["trace[165472483] 'agreement among raft nodes before linearized reading' (duration: 427.36815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:08.024338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:07.596866Z","time spent":"427.468481ms","remote":"127.0.0.1:54174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-08T22:10:08.024467Z","caller":"traceutil/trace.go:171","msg":"trace[415441857] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"314.635038ms","start":"2026-02-08T22:10:07.709825Z","end":"2026-02-08T22:10:08.02446Z","steps":["trace[415441857] 'process raft request' (duration: 314.299865ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:08.02451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:07.709805Z","time spent":"314.679068ms","remote":"127.0.0.1:53956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-08T22:10:08.024619Z","caller":"traceutil/trace.go:171","msg":"trace[502186361] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"210.658162ms","start":"2026-02-08T22:10:07.813954Z","end":"2026-02-08T22:10:08.024612Z","steps":["trace[502186361] 'process raft request' (duration: 210.234238ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:10:08.024685Z","caller":"traceutil/trace.go:171","msg":"trace[1394789598] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"114.905191ms","start":"2026-02-08T22:10:07.909772Z","end":"2026-02-08T22:10:08.024678Z","steps":["trace[1394789598] 'process raft request' (duration: 114.445607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:08.024764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.397392ms","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-08T22:10:08.02478Z","caller":"traceutil/trace.go:171","msg":"trace[1414689029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:540; }","duration":"417.426663ms","start":"2026-02-08T22:10:07.607348Z","end":"2026-02-08T22:10:08.024775Z","steps":["trace[1414689029] 'agreement among raft nodes before linearized reading' (duration: 417.395332ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:08.024795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:07.607341Z","time spent":"417.450373ms","remote":"127.0.0.1:54174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-08T22:10:08.550043Z","caller":"traceutil/trace.go:171","msg":"trace[194598124] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"214.415988ms","start":"2026-02-08T22:10:08.335612Z","end":"2026-02-08T22:10:08.550028Z","steps":["trace[194598124] 'process raft request' (duration: 214.322797ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:10:10.561778Z","caller":"traceutil/trace.go:171","msg":"trace[903004059] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"402.487418ms","start":"2026-02-08T22:10:10.159271Z","end":"2026-02-08T22:10:10.561758Z","steps":["trace[903004059] 'process raft request' (duration: 305.130714ms)","trace[903004059] 'compare' (duration: 97.277124ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:10:10.561994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:10.159264Z","time spent":"402.566489ms","remote":"127.0.0.1:54266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1066,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-08T22:10:10.562793Z","caller":"traceutil/trace.go:171","msg":"trace[1778689328] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:586; }","duration":"255.863578ms","start":"2026-02-08T22:10:10.306922Z","end":"2026-02-08T22:10:10.562786Z","steps":["trace[1778689328] 'read index received' (duration: 157.487995ms)","trace[1778689328] 'applied index is now lower than readState.Index' (duration: 98.375063ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:10:10.563224Z","caller":"traceutil/trace.go:171","msg":"trace[314701618] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"394.065426ms","start":"2026-02-08T22:10:10.169151Z","end":"2026-02-08T22:10:10.563216Z","steps":["trace[314701618] 'process raft request' (duration: 393.294189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:10.563836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:10.169137Z","time spent":"394.106076ms","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-08T22:10:10.564099Z","caller":"traceutil/trace.go:171","msg":"trace[603550710] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"394.886573ms","start":"2026-02-08T22:10:10.169204Z","end":"2026-02-08T22:10:10.564091Z","steps":["trace[603550710] 'process raft request' (duration: 393.274219ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:10.564301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:10.169201Z","time spent":"394.919143ms","remote":"127.0.0.1:54008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1745,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-08T22:10:10.565595Z","caller":"traceutil/trace.go:171","msg":"trace[1144121685] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"362.568883ms","start":"2026-02-08T22:10:10.203017Z","end":"2026-02-08T22:10:10.565586Z","steps":["trace[1144121685] 'process raft request' (duration: 359.601957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:10.56642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:10.203001Z","time spent":"362.621183ms","remote":"127.0.0.1:53956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:10:10.567582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.66002ms","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-08T22:10:10.567634Z","caller":"traceutil/trace.go:171","msg":"trace[520438590] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:573; }","duration":"260.71577ms","start":"2026-02-08T22:10:10.306888Z","end":"2026-02-08T22:10:10.567604Z","steps":["trace[520438590] 'agreement among raft nodes before linearized reading' (duration: 260.621029ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:10:15.610101Z","caller":"traceutil/trace.go:171","msg":"trace[1707815042] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"400.20917ms","start":"2026-02-08T22:10:15.209877Z","end":"2026-02-08T22:10:15.610086Z","steps":["trace[1707815042] 'process raft request' (duration: 399.867317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:15.6102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:15.209861Z","time spent":"400.28627ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:10:16.347855Z","caller":"traceutil/trace.go:171","msg":"trace[1463707957] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"143.739542ms","start":"2026-02-08T22:10:16.204095Z","end":"2026-02-08T22:10:16.347835Z","steps":["trace[1463707957] 'read index received' (duration: 143.57315ms)","trace[1463707957] 'applied index is now lower than readState.Index' (duration: 165.532µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:10:16.348134Z","caller":"traceutil/trace.go:171","msg":"trace[1639183755] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"162.999625ms","start":"2026-02-08T22:10:16.185123Z","end":"2026-02-08T22:10:16.348122Z","steps":["trace[1639183755] 'process raft request' (duration: 162.596212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:16.348337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.222577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:10:16.348371Z","caller":"traceutil/trace.go:171","msg":"trace[786101226] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:606; }","duration":"144.296587ms","start":"2026-02-08T22:10:16.204065Z","end":"2026-02-08T22:10:16.348361Z","steps":["trace[786101226] 'agreement among raft nodes before linearized reading' (duration: 144.225237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:16.348527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.092926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:10:16.348555Z","caller":"traceutil/trace.go:171","msg":"trace[1330219589] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:606; }","duration":"144.141586ms","start":"2026-02-08T22:10:16.204405Z","end":"2026-02-08T22:10:16.348547Z","steps":["trace[1330219589] 'agreement among raft nodes before linearized reading' (duration: 144.098325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:16.348972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.337597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:10:16.349003Z","caller":"traceutil/trace.go:171","msg":"trace[28078153] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:606; }","duration":"112.378288ms","start":"2026-02-08T22:10:16.236615Z","end":"2026-02-08T22:10:16.348994Z","steps":["trace[28078153] 'agreement among raft nodes before linearized reading' (duration: 112.333977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:16.349124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.56563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:10:16.349151Z","caller":"traceutil/trace.go:171","msg":"trace[451676686] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:606; }","duration":"112.59418ms","start":"2026-02-08T22:10:16.236548Z","end":"2026-02-08T22:10:16.349142Z","steps":["trace[451676686] 'agreement among raft nodes before linearized reading' (duration: 112.55539ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:16.349263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.718451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:10:16.349291Z","caller":"traceutil/trace.go:171","msg":"trace[2053437583] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:606; }","duration":"112.756181ms","start":"2026-02-08T22:10:16.236527Z","end":"2026-02-08T22:10:16.349283Z","steps":["trace[2053437583] 'agreement among raft nodes before linearized reading' (duration: 112.712861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:16.349433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.955272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-08T22:10:16.349459Z","caller":"traceutil/trace.go:171","msg":"trace[112335365] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:606; }","duration":"112.992193ms","start":"2026-02-08T22:10:16.236459Z","end":"2026-02-08T22:10:16.349451Z","steps":["trace[112335365] 'agreement among raft nodes before linearized reading' (duration: 112.916963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:16.350826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.465181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:10:16.350881Z","caller":"traceutil/trace.go:171","msg":"trace[412843434] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:606; }","duration":"123.542581ms","start":"2026-02-08T22:10:16.227325Z","end":"2026-02-08T22:10:16.350867Z","steps":["trace[412843434] 'agreement among raft nodes before linearized reading' (duration: 123.438301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:16.351283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.921435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:10:16.351349Z","caller":"traceutil/trace.go:171","msg":"trace[973713167] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:606; }","duration":"124.016415ms","start":"2026-02-08T22:10:16.227319Z","end":"2026-02-08T22:10:16.351336Z","steps":["trace[973713167] 'agreement among raft nodes before linearized reading' (duration: 123.870524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:16.351461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.169327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:10:16.351474Z","caller":"traceutil/trace.go:171","msg":"trace[1930110378] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:606; }","duration":"124.184878ms","start":"2026-02-08T22:10:16.227285Z","end":"2026-02-08T22:10:16.35147Z","steps":["trace[1930110378] 'agreement among raft nodes before linearized reading' (duration: 124.162127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:16.351584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.452795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:10:16.351598Z","caller":"traceutil/trace.go:171","msg":"trace[379023218] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:606; }","duration":"133.473796ms","start":"2026-02-08T22:10:16.21812Z","end":"2026-02-08T22:10:16.351593Z","steps":["trace[379023218] 'agreement among raft nodes before linearized reading' (duration: 133.449515ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:16.352317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.224523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:10:16.352336Z","caller":"traceutil/trace.go:171","msg":"trace[1015507774] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:606; }","duration":"134.267453ms","start":"2026-02-08T22:10:16.218063Z","end":"2026-02-08T22:10:16.35233Z","steps":["trace[1015507774] 'agreement among raft nodes before linearized reading' (duration: 134.235213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:16.352416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.957628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:10:16.352442Z","caller":"traceutil/trace.go:171","msg":"trace[2036554060] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:606; }","duration":"147.985399ms","start":"2026-02-08T22:10:16.20444Z","end":"2026-02-08T22:10:16.352426Z","steps":["trace[2036554060] 'agreement among raft nodes before linearized reading' (duration: 147.961488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:16.352794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.671386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:10:16.352833Z","caller":"traceutil/trace.go:171","msg":"trace[1490611943] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:606; }","duration":"134.725416ms","start":"2026-02-08T22:10:16.218102Z","end":"2026-02-08T22:10:16.352827Z","steps":["trace[1490611943] 'agreement among raft nodes before linearized reading' (duration: 133.601767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:17.032872Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093769182364177,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T22:10:17.533346Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093769182364177,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T22:10:17.535816Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.174811872s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-08T22:10:18.122933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.926457ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T22:10:18.123063Z","caller":"traceutil/trace.go:171","msg":"trace[963701473] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:624; }","duration":"1.590866289s","start":"2026-02-08T22:10:16.532185Z","end":"2026-02-08T22:10:18.123051Z","steps":["trace[963701473] 'read index received' (duration: 1.00377403s)","trace[963701473] 'applied index is now lower than readState.Index' (duration: 587.091439ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:10:18.123386Z","caller":"traceutil/trace.go:171","msg":"trace[948364164] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"1.762433436s","start":"2026-02-08T22:10:16.360943Z","end":"2026-02-08T22:10:18.123377Z","steps":["trace[948364164] 'process raft request' (duration: 1.175012234s)","trace[948364164] 'compare' (duration: 586.572625ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:10:18.123523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:16.360935Z","time spent":"1.762561227s","remote":"127.0.0.1:54174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:10:18.124141Z","caller":"traceutil/trace.go:171","msg":"trace[1842129272] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"1.754304698s","start":"2026-02-08T22:10:16.369827Z","end":"2026-02-08T22:10:18.124131Z","steps":["trace[1842129272] 'process raft request' (duration: 1.753172478s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:18.124318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:16.369809Z","time spent":"1.754432709s","remote":"127.0.0.1:54174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:10:18.124592Z","caller":"traceutil/trace.go:171","msg":"trace[1294305844] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"1.754701551s","start":"2026-02-08T22:10:16.369882Z","end":"2026-02-08T22:10:18.124584Z","steps":["trace[1294305844] 'process raft request' (duration: 1.753147108s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:18.124738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:16.369877Z","time spent":"1.754816372s","remote":"127.0.0.1:54174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:10:18.125042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.592854335s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:10:18.125136Z","caller":"traceutil/trace.go:171","msg":"trace[112562225] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:609; }","duration":"1.592978036s","start":"2026-02-08T22:10:16.53215Z","end":"2026-02-08T22:10:18.125128Z","steps":["trace[112562225] 'agreement among raft nodes before linearized reading' (duration: 1.592866445s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:18.125282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:16.532122Z","time spent":"1.593151207s","remote":"127.0.0.1:53904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T22:10:18.125611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.363608671s","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-08T22:10:18.125696Z","caller":"traceutil/trace.go:171","msg":"trace[1400600213] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:609; }","duration":"1.363725533s","start":"2026-02-08T22:10:16.76196Z","end":"2026-02-08T22:10:18.125686Z","steps":["trace[1400600213] 'agreement among raft nodes before linearized reading' (duration: 1.363578492s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:18.125767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:16.761949Z","time spent":"1.363810913s","remote":"127.0.0.1:54174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-08T22:10:18.474551Z","caller":"traceutil/trace.go:171","msg":"trace[716390645] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:627; }","duration":"115.393176ms","start":"2026-02-08T22:10:18.359137Z","end":"2026-02-08T22:10:18.47453Z","steps":["trace[716390645] 'read index received' (duration: 70.319498ms)","trace[716390645] 'applied index is now lower than readState.Index' (duration: 45.072718ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:10:18.474763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.632517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-08T22:10:18.474795Z","caller":"traceutil/trace.go:171","msg":"trace[442636925] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:611; }","duration":"115.712519ms","start":"2026-02-08T22:10:18.359073Z","end":"2026-02-08T22:10:18.474786Z","steps":["trace[442636925] 'agreement among raft nodes before linearized reading' (duration: 115.538977ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:10:18.474958Z","caller":"traceutil/trace.go:171","msg":"trace[580367947] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"327.50351ms","start":"2026-02-08T22:10:18.147444Z","end":"2026-02-08T22:10:18.474948Z","steps":["trace[580367947] 'process raft request' (duration: 327.047636ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:10:18.474941Z","caller":"traceutil/trace.go:171","msg":"trace[744837305] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"335.344995ms","start":"2026-02-08T22:10:18.139574Z","end":"2026-02-08T22:10:18.474919Z","steps":["trace[744837305] 'process raft request' (duration: 289.867574ms)","trace[744837305] 'compare' (duration: 44.940996ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:10:18.475032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:18.147432Z","time spent":"327.54917ms","remote":"127.0.0.1:54174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:10:18.47514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:18.139565Z","time spent":"335.432445ms","remote":"127.0.0.1:54174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:10:23.360342Z","caller":"traceutil/trace.go:171","msg":"trace[1222215310] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"103.999813ms","start":"2026-02-08T22:10:23.256321Z","end":"2026-02-08T22:10:23.360321Z","steps":["trace[1222215310] 'process raft request' (duration: 103.896428ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:10:28.789765Z","caller":"traceutil/trace.go:171","msg":"trace[1023489861] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"137.780715ms","start":"2026-02-08T22:10:28.651968Z","end":"2026-02-08T22:10:28.789749Z","steps":["trace[1023489861] 'process raft request' (duration: 137.696341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:31.574227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.401091ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-08T22:10:31.574303Z","caller":"traceutil/trace.go:171","msg":"trace[2013829074] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"279.476885ms","start":"2026-02-08T22:10:31.29481Z","end":"2026-02-08T22:10:31.574287Z","steps":["trace[2013829074] 'process raft request' (duration: 172.967739ms)","trace[2013829074] 'compare' (duration: 106.321998ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:10:31.781196Z","caller":"traceutil/trace.go:171","msg":"trace[671660651] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"125.57052ms","start":"2026-02-08T22:10:31.655603Z","end":"2026-02-08T22:10:31.781174Z","steps":["trace[671660651] 'process raft request' (duration: 45.737285ms)","trace[671660651] 'compare' (duration: 79.635636ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:11:35.110447Z","caller":"traceutil/trace.go:171","msg":"trace[899972942] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1003; }","duration":"172.093823ms","start":"2026-02-08T22:11:34.938329Z","end":"2026-02-08T22:11:35.110422Z","steps":["trace[899972942] 'read index received' (duration: 171.889258ms)","trace[899972942] 'applied index is now lower than readState.Index' (duration: 203.455µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T22:11:35.110733Z","caller":"traceutil/trace.go:171","msg":"trace[731806591] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"266.909186ms","start":"2026-02-08T22:11:34.843814Z","end":"2026-02-08T22:11:35.110723Z","steps":["trace[731806591] 'process raft request' (duration: 266.461856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:11:35.110942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.599496ms","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-08T22:11:35.110967Z","caller":"traceutil/trace.go:171","msg":"trace[1916649517] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:967; }","duration":"172.687598ms","start":"2026-02-08T22:11:34.938271Z","end":"2026-02-08T22:11:35.110958Z","steps":["trace[1916649517] 'agreement among raft nodes before linearized reading' (duration: 172.587546ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:11:35.111098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.04322ms","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-08T22:11:35.111117Z","caller":"traceutil/trace.go:171","msg":"trace[1331516861] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:967; }","duration":"120.090502ms","start":"2026-02-08T22:11:34.991021Z","end":"2026-02-08T22:11:35.111111Z","steps":["trace[1331516861] 'agreement among raft nodes before linearized reading' (duration: 120.03722ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:11:41.267521Z","caller":"traceutil/trace.go:171","msg":"trace[945934824] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"118.64674ms","start":"2026-02-08T22:11:41.148844Z","end":"2026-02-08T22:11:41.267491Z","steps":["trace[945934824] 'process raft request' (duration: 118.451455ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:11:41.412911Z","caller":"traceutil/trace.go:171","msg":"trace[1662596404] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"263.894847ms","start":"2026-02-08T22:11:41.148984Z","end":"2026-02-08T22:11:41.412879Z","steps":["trace[1662596404] 'process raft request' (duration: 263.695742ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:12:01.05019Z","caller":"traceutil/trace.go:171","msg":"trace[483812495] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"126.804412ms","start":"2026-02-08T22:12:00.923348Z","end":"2026-02-08T22:12:01.050153Z","steps":["trace[483812495] 'process raft request' (duration: 110.135319ms)","trace[483812495] 'compare' (duration: 16.296875ms)"],"step_count":2}