{"level":"warn","ts":"2026-01-31T19:24:17.008167Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-31T19:24:17.008248Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.155: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.155:2380","--initial-cluster=instance=https://199.204.45.155:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.155:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.155: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-01-31T19:24:17.008307Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-31T19:24:17.00832Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.155:2380"]} {"level":"info","ts":"2026-01-31T19:24:17.00834Z","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-01-31T19:24:17.008852Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"]} {"level":"info","ts":"2026-01-31T19:24:17.008919Z","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.155:2380"],"listen-peer-urls":["https://199.204.45.155:2380"],"advertise-client-urls":["https://199.204.45.155:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.155: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-01-31T19:24:17.013589Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.451491ms"} {"level":"info","ts":"2026-01-31T19:24:17.019667Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"344940fc142d902f","cluster-id":"c7d65bf2a36fbd4"} {"level":"info","ts":"2026-01-31T19:24:17.019711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=()"} {"level":"info","ts":"2026-01-31T19:24:17.019749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became follower at term 0"} {"level":"info","ts":"2026-01-31T19:24:17.019759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 344940fc142d902f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-31T19:24:17.019771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became follower at term 1"} {"level":"info","ts":"2026-01-31T19:24:17.019816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=(3767614014686597167)"} {"level":"warn","ts":"2026-01-31T19:24:17.027425Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-31T19:24:17.040334Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-31T19:24:17.045707Z","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-01-31T19:24:17.050693Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"344940fc142d902f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-31T19:24:17.052117Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"344940fc142d902f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-31T19:24:17.053204Z","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-01-31T19:24:17.05323Z","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-01-31T19:24:17.053238Z","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-01-31T19:24:17.053489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=(3767614014686597167)"} {"level":"info","ts":"2026-01-31T19:24:17.053905Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c7d65bf2a36fbd4","local-member-id":"344940fc142d902f","added-peer-id":"344940fc142d902f","added-peer-peer-urls":["https://199.204.45.155:2380"]} {"level":"info","ts":"2026-01-31T19:24:17.062053Z","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-01-31T19:24:17.063288Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"344940fc142d902f","initial-advertise-peer-urls":["https://199.204.45.155:2380"],"listen-peer-urls":["https://199.204.45.155:2380"],"advertise-client-urls":["https://199.204.45.155:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-31T19:24:17.063316Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-31T19:24:17.066078Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.155:2380"} {"level":"info","ts":"2026-01-31T19:24:17.066095Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.155:2380"} {"level":"info","ts":"2026-01-31T19:24:17.320706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f is starting a new election at term 1"} {"level":"info","ts":"2026-01-31T19:24:17.320752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became pre-candidate at term 1"} {"level":"info","ts":"2026-01-31T19:24:17.32078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f received MsgPreVoteResp from 344940fc142d902f at term 1"} {"level":"info","ts":"2026-01-31T19:24:17.320794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became candidate at term 2"} {"level":"info","ts":"2026-01-31T19:24:17.320806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f received MsgVoteResp from 344940fc142d902f at term 2"} {"level":"info","ts":"2026-01-31T19:24:17.320818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became leader at term 2"} {"level":"info","ts":"2026-01-31T19:24:17.320855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 344940fc142d902f elected leader 344940fc142d902f at term 2"} {"level":"info","ts":"2026-01-31T19:24:17.360498Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"344940fc142d902f","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.155:2379]}","request-path":"/0/members/344940fc142d902f/attributes","cluster-id":"c7d65bf2a36fbd4","publish-timeout":"7s"} {"level":"info","ts":"2026-01-31T19:24:17.360665Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-31T19:24:17.360718Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-31T19:24:17.363427Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-31T19:24:17.3635Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-31T19:24:17.363517Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T19:24:17.368202Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-31T19:24:17.369198Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.155:2379"} {"level":"info","ts":"2026-01-31T19:24:17.369528Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c7d65bf2a36fbd4","local-member-id":"344940fc142d902f","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T19:24:17.375484Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T19:24:17.375577Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T19:24:25.322997Z","caller":"traceutil/trace.go:171","msg":"trace[901994597] linearizableReadLoop","detail":"{readStateIndex:311; appliedIndex:310; }","duration":"107.718425ms","start":"2026-01-31T19:24:25.215253Z","end":"2026-01-31T19:24:25.322971Z","steps":["trace[901994597] 'read index received' (duration: 754.727µs)","trace[901994597] 'applied index is now lower than readState.Index' (duration: 106.962198ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T19:24:25.323173Z","caller":"traceutil/trace.go:171","msg":"trace[1170049170] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"114.586233ms","start":"2026-01-31T19:24:25.208579Z","end":"2026-01-31T19:24:25.323166Z","steps":["trace[1170049170] 'process raft request' (duration: 114.15525ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:24:25.323656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.38581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-01-31T19:24:25.323698Z","caller":"traceutil/trace.go:171","msg":"trace[822783537] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:295; }","duration":"108.493251ms","start":"2026-01-31T19:24:25.215197Z","end":"2026-01-31T19:24:25.32369Z","steps":["trace[822783537] 'agreement among raft nodes before linearized reading' (duration: 108.39588ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T19:24:25.951185Z","caller":"traceutil/trace.go:171","msg":"trace[528802938] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"122.27676ms","start":"2026-01-31T19:24:25.828883Z","end":"2026-01-31T19:24:25.95116Z","steps":["trace[528802938] 'process raft request' (duration: 122.115838ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:24:26.613922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.03883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-01-31T19:24:26.614262Z","caller":"traceutil/trace.go:171","msg":"trace[1858282088] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:302; }","duration":"135.404143ms","start":"2026-01-31T19:24:26.478821Z","end":"2026-01-31T19:24:26.614225Z","steps":["trace[1858282088] 'range keys from in-memory index tree' (duration: 134.90929ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:24:26.614696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.426788ms","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-01-31T19:24:26.614787Z","caller":"traceutil/trace.go:171","msg":"trace[1041324654] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:302; }","duration":"186.539169ms","start":"2026-01-31T19:24:26.428235Z","end":"2026-01-31T19:24:26.614775Z","steps":["trace[1041324654] 'range keys from in-memory index tree' (duration: 186.378598ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T19:24:26.849421Z","caller":"traceutil/trace.go:171","msg":"trace[1038878889] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"146.595009ms","start":"2026-01-31T19:24:26.702804Z","end":"2026-01-31T19:24:26.849399Z","steps":["trace[1038878889] 'process raft request' (duration: 54.998018ms)","trace[1038878889] 'compare' (duration: 91.50607ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T19:24:27.086964Z","caller":"traceutil/trace.go:171","msg":"trace[1090491174] linearizableReadLoop","detail":"{readStateIndex:321; appliedIndex:319; }","duration":"358.76413ms","start":"2026-01-31T19:24:26.728178Z","end":"2026-01-31T19:24:27.086942Z","steps":["trace[1090491174] 'read index received' (duration: 29.630732ms)","trace[1090491174] 'applied index is now lower than readState.Index' (duration: 329.132178ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T19:24:27.08733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.142423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-01-31T19:24:27.087844Z","caller":"traceutil/trace.go:171","msg":"trace[8674833] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:305; }","duration":"359.700528ms","start":"2026-01-31T19:24:26.72813Z","end":"2026-01-31T19:24:27.08783Z","steps":["trace[8674833] 'agreement among raft nodes before linearized reading' (duration: 359.098593ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:24:27.088004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T19:24:26.728114Z","time spent":"359.877559ms","remote":"127.0.0.1:45514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"info","ts":"2026-01-31T19:24:27.087466Z","caller":"traceutil/trace.go:171","msg":"trace[1552027800] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"381.932627ms","start":"2026-01-31T19:24:26.705499Z","end":"2026-01-31T19:24:27.087431Z","steps":["trace[1552027800] 'process raft request' (duration: 381.26069ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:24:27.088817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T19:24:26.705492Z","time spent":"382.847954ms","remote":"127.0.0.1:45438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2163,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T19:24:27.146874Z","caller":"traceutil/trace.go:171","msg":"trace[621468438] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"293.936553ms","start":"2026-01-31T19:24:26.852907Z","end":"2026-01-31T19:24:27.146844Z","steps":["trace[621468438] 'process raft request' (duration: 292.696682ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:24:27.147811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.655964ms","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-01-31T19:24:27.14803Z","caller":"traceutil/trace.go:171","msg":"trace[1691852079] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:306; }","duration":"117.931807ms","start":"2026-01-31T19:24:27.030075Z","end":"2026-01-31T19:24:27.148007Z","steps":["trace[1691852079] 'agreement among raft nodes before linearized reading' (duration: 117.677405ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:24:27.1487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.11212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-01-31T19:24:27.148742Z","caller":"traceutil/trace.go:171","msg":"trace[1232355895] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:306; }","duration":"169.179572ms","start":"2026-01-31T19:24:26.979551Z","end":"2026-01-31T19:24:27.148731Z","steps":["trace[1232355895] 'agreement among raft nodes before linearized reading' (duration: 169.109211ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:24:27.149109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.472148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-01-31T19:24:27.149147Z","caller":"traceutil/trace.go:171","msg":"trace[931898688] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:306; }","duration":"220.531978ms","start":"2026-01-31T19:24:26.928606Z","end":"2026-01-31T19:24:27.149138Z","steps":["trace[931898688] 'agreement among raft nodes before linearized reading' (duration: 220.453607ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:24:27.149439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.867468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-01-31T19:24:27.149479Z","caller":"traceutil/trace.go:171","msg":"trace[110765484] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:306; }","duration":"269.93315ms","start":"2026-01-31T19:24:26.879537Z","end":"2026-01-31T19:24:27.149471Z","steps":["trace[110765484] 'agreement among raft nodes before linearized reading' (duration: 269.868899ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:24:27.149807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.840652ms","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-01-31T19:24:27.149845Z","caller":"traceutil/trace.go:171","msg":"trace[2144135141] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:306; }","duration":"320.880393ms","start":"2026-01-31T19:24:26.828956Z","end":"2026-01-31T19:24:27.149836Z","steps":["trace[2144135141] 'agreement among raft nodes before linearized reading' (duration: 320.811602ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:24:27.149874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T19:24:26.828911Z","time spent":"320.954514ms","remote":"127.0.0.1:45514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":256,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-01-31T19:24:27.150161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.013908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-01-31T19:24:27.150198Z","caller":"traceutil/trace.go:171","msg":"trace[227113669] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:306; }","duration":"372.074689ms","start":"2026-01-31T19:24:26.778116Z","end":"2026-01-31T19:24:27.15019Z","steps":["trace[227113669] 'agreement among raft nodes before linearized reading' (duration: 372.010788ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:24:27.150232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T19:24:26.778105Z","time spent":"372.118429ms","remote":"127.0.0.1:45514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"info","ts":"2026-01-31T19:24:31.475561Z","caller":"traceutil/trace.go:171","msg":"trace[329734803] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"137.114917ms","start":"2026-01-31T19:24:31.338429Z","end":"2026-01-31T19:24:31.475544Z","steps":["trace[329734803] 'process raft request' (duration: 137.012646ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:24:31.770085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.419388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T19:24:31.770171Z","caller":"traceutil/trace.go:171","msg":"trace[509628579] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:371; }","duration":"126.565729ms","start":"2026-01-31T19:24:31.643586Z","end":"2026-01-31T19:24:31.770151Z","steps":["trace[509628579] 'range keys from in-memory index tree' (duration: 126.290147ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T19:24:34.872213Z","caller":"traceutil/trace.go:171","msg":"trace[1469304037] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"199.71411ms","start":"2026-01-31T19:24:34.672474Z","end":"2026-01-31T19:24:34.872188Z","steps":["trace[1469304037] 'process raft request' (duration: 199.555218ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T19:24:36.388433Z","caller":"traceutil/trace.go:171","msg":"trace[448612619] linearizableReadLoop","detail":"{readStateIndex:407; appliedIndex:406; }","duration":"154.55718ms","start":"2026-01-31T19:24:36.233842Z","end":"2026-01-31T19:24:36.388399Z","steps":["trace[448612619] 'read index received' (duration: 147.05642ms)","trace[448612619] 'applied index is now lower than readState.Index' (duration: 7.49936ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T19:24:36.389828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.950621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-01-31T19:24:36.389967Z","caller":"traceutil/trace.go:171","msg":"trace[1539620725] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:388; }","duration":"156.118622ms","start":"2026-01-31T19:24:36.233814Z","end":"2026-01-31T19:24:36.389933Z","steps":["trace[1539620725] 'agreement among raft nodes before linearized reading' (duration: 155.91541ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T19:24:41.890177Z","caller":"traceutil/trace.go:171","msg":"trace[1459081798] linearizableReadLoop","detail":"{readStateIndex:450; appliedIndex:449; }","duration":"245.216259ms","start":"2026-01-31T19:24:41.644943Z","end":"2026-01-31T19:24:41.89016Z","steps":["trace[1459081798] 'read index received' (duration: 245.079838ms)","trace[1459081798] 'applied index is now lower than readState.Index' (duration: 135.581µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T19:24:41.890438Z","caller":"traceutil/trace.go:171","msg":"trace[729301048] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"294.087286ms","start":"2026-01-31T19:24:41.596345Z","end":"2026-01-31T19:24:41.890432Z","steps":["trace[729301048] 'process raft request' (duration: 293.728863ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:24:41.890556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.600662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T19:24:41.890573Z","caller":"traceutil/trace.go:171","msg":"trace[369529591] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:429; }","duration":"245.667422ms","start":"2026-01-31T19:24:41.6449Z","end":"2026-01-31T19:24:41.890567Z","steps":["trace[369529591] 'agreement among raft nodes before linearized reading' (duration: 245.627881ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:24:41.890658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.14181ms","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-01-31T19:24:41.89067Z","caller":"traceutil/trace.go:171","msg":"trace[851068447] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:429; }","duration":"217.18196ms","start":"2026-01-31T19:24:41.673484Z","end":"2026-01-31T19:24:41.890666Z","steps":["trace[851068447] 'agreement among raft nodes before linearized reading' (duration: 217.1591ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:24:41.89076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.023318ms","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-01-31T19:24:41.890773Z","caller":"traceutil/trace.go:171","msg":"trace[1323093770] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:429; }","duration":"213.058959ms","start":"2026-01-31T19:24:41.677709Z","end":"2026-01-31T19:24:41.890768Z","steps":["trace[1323093770] 'agreement among raft nodes before linearized reading' (duration: 213.033069ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:24:50.956726Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389694481579310899,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-31T19:24:50.979865Z","caller":"traceutil/trace.go:171","msg":"trace[418555583] linearizableReadLoop","detail":"{readStateIndex:506; appliedIndex:505; }","duration":"524.011291ms","start":"2026-01-31T19:24:50.455827Z","end":"2026-01-31T19:24:50.979838Z","steps":["trace[418555583] 'read index received' (duration: 523.635807ms)","trace[418555583] 'applied index is now lower than readState.Index' (duration: 374.724µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T19:24:50.980031Z","caller":"traceutil/trace.go:171","msg":"trace[707800114] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"574.689107ms","start":"2026-01-31T19:24:50.40533Z","end":"2026-01-31T19:24:50.980019Z","steps":["trace[707800114] 'process raft request' (duration: 574.233924ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:24:50.980232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.131784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T19:24:50.980312Z","caller":"traceutil/trace.go:171","msg":"trace[1015428942] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:484; }","duration":"337.243645ms","start":"2026-01-31T19:24:50.643047Z","end":"2026-01-31T19:24:50.980291Z","steps":["trace[1015428942] 'agreement among raft nodes before linearized reading' (duration: 337.130934ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:24:50.980351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T19:24:50.643033Z","time spent":"337.311005ms","remote":"127.0.0.1:45326","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-01-31T19:24:50.980602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.781686ms","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-01-31T19:24:50.980631Z","caller":"traceutil/trace.go:171","msg":"trace[1779968255] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:484; }","duration":"524.846866ms","start":"2026-01-31T19:24:50.455774Z","end":"2026-01-31T19:24:50.980621Z","steps":["trace[1779968255] 'agreement among raft nodes before linearized reading' (duration: 524.743725ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:24:50.980657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T19:24:50.45575Z","time spent":"524.901866ms","remote":"127.0.0.1:45600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-31T19:24:50.980835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.000778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.155\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-31T19:24:50.980862Z","caller":"traceutil/trace.go:171","msg":"trace[900287895] range","detail":"{range_begin:/registry/masterleases/199.204.45.155; range_end:; response_count:1; response_revision:484; }","duration":"269.054068ms","start":"2026-01-31T19:24:50.7118Z","end":"2026-01-31T19:24:50.980854Z","steps":["trace[900287895] 'agreement among raft nodes before linearized reading' (duration: 269.000047ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:24:50.987775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T19:24:50.405318Z","time spent":"582.336233ms","remote":"127.0.0.1:45496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4254,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T19:24:56.145963Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389694481579310933,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-31T19:24:56.264509Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.207809338s","expected-duration":"1s"} {"level":"info","ts":"2026-01-31T19:24:56.265189Z","caller":"traceutil/trace.go:171","msg":"trace[1606917225] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"1.208584712s","start":"2026-01-31T19:24:55.056557Z","end":"2026-01-31T19:24:56.265142Z","steps":["trace[1606917225] 'process raft request' (duration: 1.208364382s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:24:56.265415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T19:24:55.056538Z","time spent":"1.20876477s","remote":"127.0.0.1:45600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T19:24:56.269408Z","caller":"traceutil/trace.go:171","msg":"trace[1348847145] linearizableReadLoop","detail":"{readStateIndex:519; appliedIndex:517; }","duration":"624.21778ms","start":"2026-01-31T19:24:55.645179Z","end":"2026-01-31T19:24:56.269397Z","steps":["trace[1348847145] 'read index received' (duration: 619.765884ms)","trace[1348847145] 'applied index is now lower than readState.Index' (duration: 4.451186ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T19:24:56.26953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.352827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T19:24:56.269573Z","caller":"traceutil/trace.go:171","msg":"trace[1770121698] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:495; }","duration":"624.443481ms","start":"2026-01-31T19:24:55.64512Z","end":"2026-01-31T19:24:56.269563Z","steps":["trace[1770121698] 'agreement among raft nodes before linearized reading' (duration: 624.381848ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:24:56.269612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T19:24:55.645091Z","time spent":"624.514353ms","remote":"127.0.0.1:45326","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-01-31T19:24:56.269831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.272237ms","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-01-31T19:24:56.269922Z","caller":"traceutil/trace.go:171","msg":"trace[256837787] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:495; }","duration":"215.414443ms","start":"2026-01-31T19:24:56.054475Z","end":"2026-01-31T19:24:56.26989Z","steps":["trace[256837787] 'agreement among raft nodes before linearized reading' (duration: 215.178673ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:24:56.270048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.181912ms","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-01-31T19:24:56.270111Z","caller":"traceutil/trace.go:171","msg":"trace[1158538402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:495; }","duration":"117.291467ms","start":"2026-01-31T19:24:56.152808Z","end":"2026-01-31T19:24:56.2701Z","steps":["trace[1158538402] 'agreement among raft nodes before linearized reading' (duration: 117.063397ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:24:56.270141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.7234ms","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-01-31T19:24:56.270171Z","caller":"traceutil/trace.go:171","msg":"trace[1280683295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:495; }","duration":"172.786233ms","start":"2026-01-31T19:24:56.097375Z","end":"2026-01-31T19:24:56.270161Z","steps":["trace[1280683295] 'agreement among raft nodes before linearized reading' (duration: 172.7254ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:25:00.400942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.829625ms","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-01-31T19:25:00.401097Z","caller":"traceutil/trace.go:171","msg":"trace[511533611] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:528; }","duration":"104.056065ms","start":"2026-01-31T19:25:00.297024Z","end":"2026-01-31T19:25:00.40108Z","steps":["trace[511533611] 'range keys from in-memory index tree' (duration: 103.386538ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T19:25:00.512531Z","caller":"traceutil/trace.go:171","msg":"trace[1102515500] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"105.919343ms","start":"2026-01-31T19:25:00.406547Z","end":"2026-01-31T19:25:00.512467Z","steps":["trace[1102515500] 'process raft request' (duration: 105.575059ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T19:25:00.544941Z","caller":"traceutil/trace.go:171","msg":"trace[288083074] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"138.271057ms","start":"2026-01-31T19:25:00.406647Z","end":"2026-01-31T19:25:00.544918Z","steps":["trace[288083074] 'process raft request' (duration: 137.87099ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T19:25:00.546788Z","caller":"traceutil/trace.go:171","msg":"trace[1323337772] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"137.473914ms","start":"2026-01-31T19:25:00.409303Z","end":"2026-01-31T19:25:00.546777Z","steps":["trace[1323337772] 'process raft request' (duration: 135.327694ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T19:25:00.547915Z","caller":"traceutil/trace.go:171","msg":"trace[1516303644] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"138.495127ms","start":"2026-01-31T19:25:00.409412Z","end":"2026-01-31T19:25:00.547907Z","steps":["trace[1516303644] 'process raft request' (duration: 135.242151ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:25:00.803365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.09881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T19:25:00.80346Z","caller":"traceutil/trace.go:171","msg":"trace[411872089] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"159.245626ms","start":"2026-01-31T19:25:00.644197Z","end":"2026-01-31T19:25:00.803442Z","steps":["trace[411872089] 'range keys from in-memory index tree' (duration: 159.026527ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:25:01.351424Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389694481579311010,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-31T19:25:01.852229Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389694481579311010,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-31T19:25:02.353302Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389694481579311010,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-31T19:25:02.853652Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389694481579311010,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-31T19:25:03.35485Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389694481579311010,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-31T19:25:03.64147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000889539s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-31T19:25:03.641567Z","caller":"traceutil/trace.go:171","msg":"trace[909504677] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001026685s","start":"2026-01-31T19:25:01.64051Z","end":"2026-01-31T19:25:03.641536Z","steps":["trace[909504677] 'agreement among raft nodes before linearized reading' (duration: 2.000919011s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:25:03.641611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T19:25:01.640489Z","time spent":"2.001113268s","remote":"127.0.0.1:45326","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-01-31T19:25:03.855956Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389694481579311010,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-31T19:25:04.35683Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389694481579311010,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-31T19:25:04.719947Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.86381842s","expected-duration":"1s"} {"level":"warn","ts":"2026-01-31T19:25:04.720106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T19:25:02.458541Z","time spent":"2.261558764s","remote":"127.0.0.1:39218","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-01-31T19:25:04.721566Z","caller":"traceutil/trace.go:171","msg":"trace[854104373] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"3.870657594s","start":"2026-01-31T19:25:00.85089Z","end":"2026-01-31T19:25:04.721548Z","steps":["trace[854104373] 'process raft request' (duration: 3.86979697s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:25:04.721694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T19:25:00.850878Z","time spent":"3.870732837s","remote":"127.0.0.1:45500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T19:25:04.782204Z","caller":"traceutil/trace.go:171","msg":"trace[1822168801] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:558; }","duration":"3.931129661s","start":"2026-01-31T19:25:00.85105Z","end":"2026-01-31T19:25:04.782179Z","steps":["trace[1822168801] 'read index received' (duration: 3.869157564s)","trace[1822168801] 'applied index is now lower than readState.Index' (duration: 61.970957ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T19:25:04.78235Z","caller":"traceutil/trace.go:171","msg":"trace[567461395] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"1.310768735s","start":"2026-01-31T19:25:03.471572Z","end":"2026-01-31T19:25:04.78234Z","steps":["trace[567461395] 'process raft request' (duration: 1.310471804s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:25:04.782443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T19:25:03.471534Z","time spent":"1.310836448s","remote":"127.0.0.1:45600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T19:25:04.782701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.264243027s","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-01-31T19:25:04.782764Z","caller":"traceutil/trace.go:171","msg":"trace[1915584963] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:535; }","duration":"2.26434694s","start":"2026-01-31T19:25:02.5184Z","end":"2026-01-31T19:25:04.782747Z","steps":["trace[1915584963] 'agreement among raft nodes before linearized reading' (duration: 2.264172394s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:25:04.782799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T19:25:02.518379Z","time spent":"2.264413143s","remote":"127.0.0.1:45600","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-01-31T19:25:04.782841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.231863203s","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-01-31T19:25:04.78288Z","caller":"traceutil/trace.go:171","msg":"trace[1070343626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:535; }","duration":"2.231908404s","start":"2026-01-31T19:25:02.550952Z","end":"2026-01-31T19:25:04.782861Z","steps":["trace[1070343626] 'agreement among raft nodes before linearized reading' (duration: 2.231773029s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:25:04.782899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T19:25:02.550945Z","time spent":"2.231949385s","remote":"127.0.0.1:45600","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-01-31T19:25:04.782968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.931917282s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-01-31T19:25:04.782982Z","caller":"traceutil/trace.go:171","msg":"trace[826432915] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:535; }","duration":"3.931941813s","start":"2026-01-31T19:25:00.851036Z","end":"2026-01-31T19:25:04.782978Z","steps":["trace[826432915] 'agreement among raft nodes before linearized reading' (duration: 3.931898981s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:25:04.782997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T19:25:00.851033Z","time spent":"3.931960363s","remote":"127.0.0.1:45604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-01-31T19:25:04.783049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.233237698s","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-01-31T19:25:04.783073Z","caller":"traceutil/trace.go:171","msg":"trace[1354589099] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:535; }","duration":"2.233281389s","start":"2026-01-31T19:25:02.549785Z","end":"2026-01-31T19:25:04.783067Z","steps":["trace[1354589099] 'agreement among raft nodes before linearized reading' (duration: 2.233196886s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:25:04.783092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T19:25:02.549779Z","time spent":"2.233309011s","remote":"127.0.0.1:45600","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-01-31T19:25:04.783217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.234711519s","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-01-31T19:25:04.783239Z","caller":"traceutil/trace.go:171","msg":"trace[1778436435] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:535; }","duration":"2.23475415s","start":"2026-01-31T19:25:02.548478Z","end":"2026-01-31T19:25:04.783232Z","steps":["trace[1778436435] 'agreement among raft nodes before linearized reading' (duration: 2.234697197s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:25:04.783264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T19:25:02.548465Z","time spent":"2.234795162s","remote":"127.0.0.1:45600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-31T19:25:04.783089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.141346507s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T19:25:04.785452Z","caller":"traceutil/trace.go:171","msg":"trace[983765430] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:535; }","duration":"1.143751653s","start":"2026-01-31T19:25:03.641693Z","end":"2026-01-31T19:25:04.785444Z","steps":["trace[983765430] 'agreement among raft nodes before linearized reading' (duration: 1.141383259s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:25:04.785541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T19:25:03.641668Z","time spent":"1.143864218s","remote":"127.0.0.1:45338","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-01-31T19:25:06.452385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.004827ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-31T19:25:06.452527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.588166ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-01-31T19:25:06.452555Z","caller":"traceutil/trace.go:171","msg":"trace[1168854615] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:545; }","duration":"235.200815ms","start":"2026-01-31T19:25:06.217331Z","end":"2026-01-31T19:25:06.452531Z","steps":["trace[1168854615] 'range keys from in-memory index tree' (duration: 234.980777ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T19:25:06.982173Z","caller":"traceutil/trace.go:171","msg":"trace[1802265187] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"100.101129ms","start":"2026-01-31T19:25:06.882036Z","end":"2026-01-31T19:25:06.982137Z","steps":["trace[1802265187] 'process raft request' (duration: 99.918532ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T19:25:07.53721Z","caller":"traceutil/trace.go:171","msg":"trace[516342085] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:572; }","duration":"642.979356ms","start":"2026-01-31T19:25:06.894206Z","end":"2026-01-31T19:25:07.537185Z","steps":["trace[516342085] 'read index received' (duration: 88.780137ms)","trace[516342085] 'applied index is now lower than readState.Index' (duration: 554.198329ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T19:25:07.537306Z","caller":"traceutil/trace.go:171","msg":"trace[569947652] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"649.892165ms","start":"2026-01-31T19:25:06.887407Z","end":"2026-01-31T19:25:07.5373Z","steps":["trace[569947652] 'process raft request' (duration: 649.566323ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:25:07.537386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T19:25:06.887364Z","time spent":"649.960028ms","remote":"127.0.0.1:45394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":754,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-31T19:25:07.537695Z","caller":"traceutil/trace.go:171","msg":"trace[1751035819] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"637.883476ms","start":"2026-01-31T19:25:06.899805Z","end":"2026-01-31T19:25:07.537688Z","steps":["trace[1751035819] 'process raft request' (duration: 637.323095ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:25:07.53774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T19:25:06.899793Z","time spent":"637.919608ms","remote":"127.0.0.1:45500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17156,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T19:25:07.537878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.670052ms","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-01-31T19:25:07.537899Z","caller":"traceutil/trace.go:171","msg":"trace[1512672746] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:548; }","duration":"643.745495ms","start":"2026-01-31T19:25:06.894148Z","end":"2026-01-31T19:25:07.537893Z","steps":["trace[1512672746] 'agreement among raft nodes before linearized reading' (duration: 643.650612ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:25:07.537914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T19:25:06.894132Z","time spent":"643.779257ms","remote":"127.0.0.1:45600","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-01-31T19:25:07.538024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.016716ms","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-01-31T19:25:07.538042Z","caller":"traceutil/trace.go:171","msg":"trace[1442579901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:548; }","duration":"643.048768ms","start":"2026-01-31T19:25:06.894988Z","end":"2026-01-31T19:25:07.538037Z","steps":["trace[1442579901] 'agreement among raft nodes before linearized reading' (duration: 643.000336ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:25:07.538054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T19:25:06.894982Z","time spent":"643.069948ms","remote":"127.0.0.1:45600","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-01-31T19:25:07.538255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.352981ms","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-01-31T19:25:07.538279Z","caller":"traceutil/trace.go:171","msg":"trace[1316424261] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:548; }","duration":"642.391092ms","start":"2026-01-31T19:25:06.895883Z","end":"2026-01-31T19:25:07.538274Z","steps":["trace[1316424261] 'agreement among raft nodes before linearized reading' (duration: 642.329549ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:25:07.538296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T19:25:06.895876Z","time spent":"642.413793ms","remote":"127.0.0.1:45600","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":"info","ts":"2026-01-31T19:25:07.689478Z","caller":"traceutil/trace.go:171","msg":"trace[1491395574] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"139.539946ms","start":"2026-01-31T19:25:07.549908Z","end":"2026-01-31T19:25:07.689448Z","steps":["trace[1491395574] 'process raft request' (duration: 93.692727ms)","trace[1491395574] 'compare' (duration: 45.732785ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T19:25:07.705713Z","caller":"traceutil/trace.go:171","msg":"trace[544666152] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"154.801287ms","start":"2026-01-31T19:25:07.550889Z","end":"2026-01-31T19:25:07.705691Z","steps":["trace[544666152] 'process raft request' (duration: 154.63109ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T19:25:07.70605Z","caller":"traceutil/trace.go:171","msg":"trace[106302958] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"154.514376ms","start":"2026-01-31T19:25:07.551508Z","end":"2026-01-31T19:25:07.706023Z","steps":["trace[106302958] 'process raft request' (duration: 154.11504ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T19:25:09.071637Z","caller":"traceutil/trace.go:171","msg":"trace[2017554676] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"171.935877ms","start":"2026-01-31T19:25:08.899662Z","end":"2026-01-31T19:25:09.071598Z","steps":["trace[2017554676] 'process raft request' (duration: 171.789371ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T19:25:09.081752Z","caller":"traceutil/trace.go:171","msg":"trace[431695031] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"178.827244ms","start":"2026-01-31T19:25:08.902907Z","end":"2026-01-31T19:25:09.081735Z","steps":["trace[431695031] 'process raft request' (duration: 178.488691ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T19:25:11.102131Z","caller":"traceutil/trace.go:171","msg":"trace[1914510127] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"165.110833ms","start":"2026-01-31T19:25:10.936998Z","end":"2026-01-31T19:25:11.102109Z","steps":["trace[1914510127] 'read index received' (duration: 144.673248ms)","trace[1914510127] 'applied index is now lower than readState.Index' (duration: 20.436855ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T19:25:11.102255Z","caller":"traceutil/trace.go:171","msg":"trace[1369440367] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"169.094313ms","start":"2026-01-31T19:25:10.93315Z","end":"2026-01-31T19:25:11.102245Z","steps":["trace[1369440367] 'process raft request' (duration: 148.551534ms)","trace[1369440367] 'compare' (duration: 20.303539ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T19:25:11.102512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.499618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T19:25:11.102576Z","caller":"traceutil/trace.go:171","msg":"trace[1119922473] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:600; }","duration":"165.605171ms","start":"2026-01-31T19:25:10.936958Z","end":"2026-01-31T19:25:11.102563Z","steps":["trace[1119922473] 'agreement among raft nodes before linearized reading' (duration: 165.508908ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T19:25:20.711492Z","caller":"traceutil/trace.go:171","msg":"trace[849030937] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"233.028226ms","start":"2026-01-31T19:25:20.478434Z","end":"2026-01-31T19:25:20.711462Z","steps":["trace[849030937] 'process raft request' (duration: 149.246463ms)","trace[849030937] 'compare' (duration: 83.582336ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T19:25:20.720869Z","caller":"traceutil/trace.go:171","msg":"trace[1077065686] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"240.002789ms","start":"2026-01-31T19:25:20.48085Z","end":"2026-01-31T19:25:20.720853Z","steps":["trace[1077065686] 'process raft request' (duration: 239.927867ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T19:25:20.721132Z","caller":"traceutil/trace.go:171","msg":"trace[4382299] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"242.290215ms","start":"2026-01-31T19:25:20.478824Z","end":"2026-01-31T19:25:20.721114Z","steps":["trace[4382299] 'process raft request' (duration: 241.84015ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T19:25:20.7214Z","caller":"traceutil/trace.go:171","msg":"trace[1876009781] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"242.506102ms","start":"2026-01-31T19:25:20.478886Z","end":"2026-01-31T19:25:20.721392Z","steps":["trace[1876009781] 'process raft request' (duration: 241.854381ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T19:25:22.181708Z","caller":"traceutil/trace.go:171","msg":"trace[1697463751] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"178.666011ms","start":"2026-01-31T19:25:22.003033Z","end":"2026-01-31T19:25:22.181699Z","steps":["trace[1697463751] 'process raft request' (duration: 177.045398ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T19:25:22.181488Z","caller":"traceutil/trace.go:171","msg":"trace[1061932441] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:750; }","duration":"125.984456ms","start":"2026-01-31T19:25:22.055485Z","end":"2026-01-31T19:25:22.181469Z","steps":["trace[1061932441] 'read index received' (duration: 124.621211ms)","trace[1061932441] 'applied index is now lower than readState.Index' (duration: 1.362545ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T19:25:22.183117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.069219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/local-path-provisioner-6f56467975-6rfls\" ","response":"range_response_count:1 size:3796"} {"level":"info","ts":"2026-01-31T19:25:22.183156Z","caller":"traceutil/trace.go:171","msg":"trace[228127027] range","detail":"{range_begin:/registry/pods/local-path-storage/local-path-provisioner-6f56467975-6rfls; range_end:; response_count:1; response_revision:722; }","duration":"126.140881ms","start":"2026-01-31T19:25:22.057004Z","end":"2026-01-31T19:25:22.183145Z","steps":["trace[228127027] 'agreement among raft nodes before linearized reading' (duration: 126.034438ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T19:25:22.183314Z","caller":"traceutil/trace.go:171","msg":"trace[908264815] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"125.293843ms","start":"2026-01-31T19:25:22.05801Z","end":"2026-01-31T19:25:22.183304Z","steps":["trace[908264815] 'process raft request' (duration: 124.933541ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:25:22.183562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.108873ms","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-01-31T19:25:22.183708Z","caller":"traceutil/trace.go:171","msg":"trace[1707558979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:721; }","duration":"128.203928ms","start":"2026-01-31T19:25:22.055442Z","end":"2026-01-31T19:25:22.183645Z","steps":["trace[1707558979] 'agreement among raft nodes before linearized reading' (duration: 126.333808ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:25:24.377999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.092516ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T19:25:24.378356Z","caller":"traceutil/trace.go:171","msg":"trace[1387002978] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"180.187908ms","start":"2026-01-31T19:25:24.198144Z","end":"2026-01-31T19:25:24.378332Z","steps":["trace[1387002978] 'process raft request' (duration: 47.653788ms)","trace[1387002978] 'compare' (duration: 131.857608ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T19:25:24.378372Z","caller":"traceutil/trace.go:171","msg":"trace[71497928] linearizableReadLoop","detail":"{readStateIndex:763; appliedIndex:762; }","duration":"168.149702ms","start":"2026-01-31T19:25:24.210203Z","end":"2026-01-31T19:25:24.378352Z","steps":["trace[71497928] 'read index received' (duration: 35.542789ms)","trace[71497928] 'applied index is now lower than readState.Index' (duration: 132.604983ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T19:25:24.380245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.034151ms","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-01-31T19:25:24.380356Z","caller":"traceutil/trace.go:171","msg":"trace[839292384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:732; }","duration":"170.146565ms","start":"2026-01-31T19:25:24.210196Z","end":"2026-01-31T19:25:24.380343Z","steps":["trace[839292384] 'agreement among raft nodes before linearized reading' (duration: 168.246225ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T19:25:26.161768Z","caller":"traceutil/trace.go:171","msg":"trace[788692284] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"195.504732ms","start":"2026-01-31T19:25:25.966242Z","end":"2026-01-31T19:25:26.161747Z","steps":["trace[788692284] 'process raft request' (duration: 195.372208ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:25:26.288592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.023306ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-01-31T19:25:26.288822Z","caller":"traceutil/trace.go:171","msg":"trace[1852384521] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"122.695953ms","start":"2026-01-31T19:25:26.166109Z","end":"2026-01-31T19:25:26.288805Z","steps":["trace[1852384521] 'process raft request' (duration: 122.571189ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T19:25:27.591014Z","caller":"traceutil/trace.go:171","msg":"trace[236832206] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"304.798773ms","start":"2026-01-31T19:25:27.286187Z","end":"2026-01-31T19:25:27.590986Z","steps":["trace[236832206] 'process raft request' (duration: 304.636888ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:25:27.591181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T19:25:27.286158Z","time spent":"304.929738ms","remote":"127.0.0.1:45600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T19:25:27.940316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.672011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T19:25:27.940386Z","caller":"traceutil/trace.go:171","msg":"trace[1862146000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:740; }","duration":"296.779645ms","start":"2026-01-31T19:25:27.643589Z","end":"2026-01-31T19:25:27.940368Z","steps":["trace[1862146000] 'range keys from in-memory index tree' (duration: 296.60117ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T19:25:28.32843Z","caller":"traceutil/trace.go:171","msg":"trace[592126132] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"158.708503ms","start":"2026-01-31T19:25:28.169683Z","end":"2026-01-31T19:25:28.328391Z","steps":["trace[592126132] 'process raft request' (duration: 158.29376ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T19:25:28.443538Z","caller":"traceutil/trace.go:171","msg":"trace[973155750] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"108.648153ms","start":"2026-01-31T19:25:28.334857Z","end":"2026-01-31T19:25:28.443505Z","steps":["trace[973155750] 'process raft request' (duration: 64.943748ms)","trace[973155750] 'compare' (duration: 43.266492ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T19:25:48.955842Z","caller":"traceutil/trace.go:171","msg":"trace[396221139] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"140.238266ms","start":"2026-01-31T19:25:48.815568Z","end":"2026-01-31T19:25:48.955806Z","steps":["trace[396221139] 'process raft request' (duration: 98.315723ms)","trace[396221139] 'compare' (duration: 41.788769ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T19:25:51.019867Z","caller":"traceutil/trace.go:171","msg":"trace[1103944920] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"183.302752ms","start":"2026-01-31T19:25:50.836537Z","end":"2026-01-31T19:25:51.01984Z","steps":["trace[1103944920] 'process raft request' (duration: 151.770011ms)","trace[1103944920] 'compare' (duration: 31.223624ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T19:26:27.12907Z","caller":"traceutil/trace.go:171","msg":"trace[1755130948] linearizableReadLoop","detail":"{readStateIndex:987; appliedIndex:986; }","duration":"145.917054ms","start":"2026-01-31T19:26:26.98313Z","end":"2026-01-31T19:26:27.129048Z","steps":["trace[1755130948] 'read index received' (duration: 145.71139ms)","trace[1755130948] 'applied index is now lower than readState.Index' (duration: 204.774µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T19:26:27.129657Z","caller":"traceutil/trace.go:171","msg":"trace[1194950062] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"238.625334ms","start":"2026-01-31T19:26:26.891018Z","end":"2026-01-31T19:26:27.129643Z","steps":["trace[1194950062] 'process raft request' (duration: 237.89466ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:26:27.129973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.738772ms","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":"warn","ts":"2026-01-31T19:26:27.13038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.236288ms","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-01-31T19:26:27.131097Z","caller":"traceutil/trace.go:171","msg":"trace[821878502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:942; }","duration":"147.990881ms","start":"2026-01-31T19:26:26.983089Z","end":"2026-01-31T19:26:27.13108Z","steps":["trace[821878502] 'agreement among raft nodes before linearized reading' (duration: 147.198427ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T19:26:27.130026Z","caller":"traceutil/trace.go:171","msg":"trace[629405685] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:942; }","duration":"143.845195ms","start":"2026-01-31T19:26:26.986169Z","end":"2026-01-31T19:26:27.130014Z","steps":["trace[629405685] 'agreement among raft nodes before linearized reading' (duration: 143.689072ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T19:26:36.577138Z","caller":"traceutil/trace.go:171","msg":"trace[553303806] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"507.803681ms","start":"2026-01-31T19:26:36.069306Z","end":"2026-01-31T19:26:36.577109Z","steps":["trace[553303806] 'process raft request' (duration: 507.470125ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T19:26:36.577236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T19:26:36.06929Z","time spent":"507.899692ms","remote":"127.0.0.1:45600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"}