{"level":"warn","ts":"2026-02-10T18:45:54.876509Z","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-10T18:45:54.87663Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.218: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://162.253.55.218:2380","--initial-cluster=instance=https://162.253.55.218:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.218:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.218: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-10T18:45:54.876719Z","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-10T18:45:54.876758Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.218:2380"]} {"level":"info","ts":"2026-02-10T18:45:54.876819Z","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-10T18:45:54.877456Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.218:2379"]} {"level":"info","ts":"2026-02-10T18:45:54.877584Z","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://162.253.55.218:2380"],"listen-peer-urls":["https://162.253.55.218:2380"],"advertise-client-urls":["https://162.253.55.218:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.218:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.218: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-10T18:45:54.885908Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.043075ms"} {"level":"info","ts":"2026-02-10T18:45:54.936965Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"bb7d59f4041c8abe","cluster-id":"5330ca29224af3c4"} {"level":"info","ts":"2026-02-10T18:45:54.937092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe switched to configuration voters=()"} {"level":"info","ts":"2026-02-10T18:45:54.937123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became follower at term 0"} {"level":"info","ts":"2026-02-10T18:45:54.937152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft bb7d59f4041c8abe [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-10T18:45:54.937173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became follower at term 1"} {"level":"info","ts":"2026-02-10T18:45:54.937227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe switched to configuration voters=(13510053361757227710)"} {"level":"warn","ts":"2026-02-10T18:45:54.94911Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-10T18:45:54.950668Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-10T18:45:54.951726Z","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-10T18:45:54.954381Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"bb7d59f4041c8abe","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-10T18:45:54.955068Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"bb7d59f4041c8abe","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-10T18:45:54.955213Z","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-10T18:45:54.955247Z","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-10T18:45:54.955263Z","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-10T18:45:54.975803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe switched to configuration voters=(13510053361757227710)"} {"level":"info","ts":"2026-02-10T18:45:54.975975Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5330ca29224af3c4","local-member-id":"bb7d59f4041c8abe","added-peer-id":"bb7d59f4041c8abe","added-peer-peer-urls":["https://162.253.55.218:2380"]} {"level":"info","ts":"2026-02-10T18:45:54.982103Z","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-10T18:45:54.982439Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.218:2380"} {"level":"info","ts":"2026-02-10T18:45:54.983592Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.218:2380"} {"level":"info","ts":"2026-02-10T18:45:54.984231Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"bb7d59f4041c8abe","initial-advertise-peer-urls":["https://162.253.55.218:2380"],"listen-peer-urls":["https://162.253.55.218:2380"],"advertise-client-urls":["https://162.253.55.218:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.218:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-10T18:45:54.985283Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-10T18:45:55.737814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe is starting a new election at term 1"} {"level":"info","ts":"2026-02-10T18:45:55.737917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became pre-candidate at term 1"} {"level":"info","ts":"2026-02-10T18:45:55.737995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe received MsgPreVoteResp from bb7d59f4041c8abe at term 1"} {"level":"info","ts":"2026-02-10T18:45:55.738092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became candidate at term 2"} {"level":"info","ts":"2026-02-10T18:45:55.738132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe received MsgVoteResp from bb7d59f4041c8abe at term 2"} {"level":"info","ts":"2026-02-10T18:45:55.73818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became leader at term 2"} {"level":"info","ts":"2026-02-10T18:45:55.738222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: bb7d59f4041c8abe elected leader bb7d59f4041c8abe at term 2"} {"level":"info","ts":"2026-02-10T18:45:55.852361Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"bb7d59f4041c8abe","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.218:2379]}","request-path":"/0/members/bb7d59f4041c8abe/attributes","cluster-id":"5330ca29224af3c4","publish-timeout":"7s"} {"level":"info","ts":"2026-02-10T18:45:55.85245Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T18:45:55.852562Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T18:45:55.852962Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T18:45:55.854766Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-10T18:45:55.856155Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.218:2379"} {"level":"info","ts":"2026-02-10T18:45:55.856535Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5330ca29224af3c4","local-member-id":"bb7d59f4041c8abe","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T18:45:55.856647Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T18:45:55.85674Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T18:45:55.856963Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-10T18:45:55.857056Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-10T18:45:57.688386Z","caller":"traceutil/trace.go:171","msg":"trace[573781945] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"139.790842ms","start":"2026-02-10T18:45:57.548574Z","end":"2026-02-10T18:45:57.688365Z","steps":["trace[573781945] 'process raft request' (duration: 139.761899ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:45:57.689079Z","caller":"traceutil/trace.go:171","msg":"trace[1197570155] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"178.76821ms","start":"2026-02-10T18:45:57.510296Z","end":"2026-02-10T18:45:57.689065Z","steps":["trace[1197570155] 'process raft request' (duration: 145.641841ms)","trace[1197570155] 'compare' (duration: 32.126906ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:45:57.68938Z","caller":"traceutil/trace.go:171","msg":"trace[26171017] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"178.439376ms","start":"2026-02-10T18:45:57.51093Z","end":"2026-02-10T18:45:57.689369Z","steps":["trace[26171017] 'process raft request' (duration: 177.235066ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:45:57.689657Z","caller":"traceutil/trace.go:171","msg":"trace[362136040] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"176.485036ms","start":"2026-02-10T18:45:57.513162Z","end":"2026-02-10T18:45:57.689647Z","steps":["trace[362136040] 'process raft request' (duration: 175.031913ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:45:57.690262Z","caller":"traceutil/trace.go:171","msg":"trace[1755881753] transaction","detail":"{read_only:false; response_revision:39; number_of_response:1; }","duration":"175.901338ms","start":"2026-02-10T18:45:57.514349Z","end":"2026-02-10T18:45:57.69025Z","steps":["trace[1755881753] 'process raft request' (duration: 173.914794ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:45:57.690573Z","caller":"traceutil/trace.go:171","msg":"trace[1098128835] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"177.356692ms","start":"2026-02-10T18:45:57.513205Z","end":"2026-02-10T18:45:57.690562Z","steps":["trace[1098128835] 'process raft request' (duration: 175.01587ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:45:57.690721Z","caller":"traceutil/trace.go:171","msg":"trace[759360342] linearizableReadLoop","detail":"{readStateIndex:42; appliedIndex:37; }","duration":"148.351732ms","start":"2026-02-10T18:45:57.542358Z","end":"2026-02-10T18:45:57.690709Z","steps":["trace[759360342] 'read index received' (duration: 113.627351ms)","trace[759360342] 'applied index is now lower than readState.Index' (duration: 34.722871ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:45:57.690824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.419771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T18:45:57.691627Z","caller":"traceutil/trace.go:171","msg":"trace[1306218830] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:41; }","duration":"162.242781ms","start":"2026-02-10T18:45:57.529342Z","end":"2026-02-10T18:45:57.691585Z","steps":["trace[1306218830] 'agreement among raft nodes before linearized reading' (duration: 161.429433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:45:57.69172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.759571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-10T18:45:57.691936Z","caller":"traceutil/trace.go:171","msg":"trace[1353206114] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:41; }","duration":"140.938645ms","start":"2026-02-10T18:45:57.550873Z","end":"2026-02-10T18:45:57.691812Z","steps":["trace[1353206114] 'agreement among raft nodes before linearized reading' (duration: 140.735778ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:45:57.690872Z","caller":"traceutil/trace.go:171","msg":"trace[1814749041] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"142.351213ms","start":"2026-02-10T18:45:57.54851Z","end":"2026-02-10T18:45:57.690861Z","steps":["trace[1814749041] 'process raft request' (duration: 139.801674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:45:57.692286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.09388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-10T18:45:57.692328Z","caller":"traceutil/trace.go:171","msg":"trace[1452164474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:41; }","duration":"139.158138ms","start":"2026-02-10T18:45:57.553158Z","end":"2026-02-10T18:45:57.692316Z","steps":["trace[1452164474] 'agreement among raft nodes before linearized reading' (duration: 139.067606ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:02.236624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.458698ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:46:02.236684Z","caller":"traceutil/trace.go:171","msg":"trace[1303451249] linearizableReadLoop","detail":"{readStateIndex:321; appliedIndex:320; }","duration":"180.123889ms","start":"2026-02-10T18:46:02.056549Z","end":"2026-02-10T18:46:02.236673Z","steps":["trace[1303451249] 'read index received' (duration: 79.310767ms)","trace[1303451249] 'applied index is now lower than readState.Index' (duration: 100.812512ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:46:02.236748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.199818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-02-10T18:46:02.236761Z","caller":"traceutil/trace.go:171","msg":"trace[1021045759] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:315; }","duration":"180.244614ms","start":"2026-02-10T18:46:02.056511Z","end":"2026-02-10T18:46:02.236756Z","steps":["trace[1021045759] 'agreement among raft nodes before linearized reading' (duration: 180.186476ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:02.236862Z","caller":"traceutil/trace.go:171","msg":"trace[147820525] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"210.731089ms","start":"2026-02-10T18:46:02.026124Z","end":"2026-02-10T18:46:02.236855Z","steps":["trace[147820525] 'process raft request' (duration: 109.735354ms)","trace[147820525] 'compare' (duration: 100.24123ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:46:02.376511Z","caller":"traceutil/trace.go:171","msg":"trace[1161435978] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"125.667424ms","start":"2026-02-10T18:46:02.250831Z","end":"2026-02-10T18:46:02.376498Z","steps":["trace[1161435978] 'process raft request' (duration: 120.958067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:12.410058Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9997600059616033945,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:46:12.910759Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9997600059616033945,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:46:13.345396Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.486378948s","expected-duration":"1s"} {"level":"info","ts":"2026-02-10T18:46:13.345791Z","caller":"traceutil/trace.go:171","msg":"trace[1452659743] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:426; }","duration":"1.435954971s","start":"2026-02-10T18:46:11.909816Z","end":"2026-02-10T18:46:13.345771Z","steps":["trace[1452659743] 'read index received' (duration: 1.435752867s)","trace[1452659743] 'applied index is now lower than readState.Index' (duration: 201.304µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:46:13.345868Z","caller":"traceutil/trace.go:171","msg":"trace[1491835388] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"1.486946754s","start":"2026-02-10T18:46:11.858913Z","end":"2026-02-10T18:46:13.34586Z","steps":["trace[1491835388] 'process raft request' (duration: 1.486744091s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:13.346046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:11.858899Z","time spent":"1.487042765s","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:46:13.346277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.43646471s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-10T18:46:13.346317Z","caller":"traceutil/trace.go:171","msg":"trace[1341611559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:418; }","duration":"1.436550461s","start":"2026-02-10T18:46:11.909755Z","end":"2026-02-10T18:46:13.346306Z","steps":["trace[1341611559] 'agreement among raft nodes before linearized reading' (duration: 1.436451529s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:13.346398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:11.909738Z","time spent":"1.436651162s","remote":"127.0.0.1:36464","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-10T18:46:13.346603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.309190356s","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-10T18:46:13.346638Z","caller":"traceutil/trace.go:171","msg":"trace[473196552] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:418; }","duration":"1.309258704s","start":"2026-02-10T18:46:12.037371Z","end":"2026-02-10T18:46:13.34663Z","steps":["trace[473196552] 'agreement among raft nodes before linearized reading' (duration: 1.309172164s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:13.346715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:12.037323Z","time spent":"1.309383419s","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-10T18:46:13.346871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.475184ms","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-10T18:46:13.34705Z","caller":"traceutil/trace.go:171","msg":"trace[178368221] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:418; }","duration":"591.691429ms","start":"2026-02-10T18:46:12.755342Z","end":"2026-02-10T18:46:13.347033Z","steps":["trace[178368221] 'agreement among raft nodes before linearized reading' (duration: 591.279631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:13.347162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:12.755327Z","time spent":"591.824765ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-10T18:46:13.34724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.137391459s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:46:13.347349Z","caller":"traceutil/trace.go:171","msg":"trace[1561591150] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:418; }","duration":"1.137464218s","start":"2026-02-10T18:46:12.209805Z","end":"2026-02-10T18:46:13.347269Z","steps":["trace[1561591150] 'agreement among raft nodes before linearized reading' (duration: 1.137378188s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:13.347446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:12.209784Z","time spent":"1.137605425s","remote":"127.0.0.1:36214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:46:13.347631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.647837ms","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-10T18:46:13.347754Z","caller":"traceutil/trace.go:171","msg":"trace[1874923046] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:418; }","duration":"153.807146ms","start":"2026-02-10T18:46:13.193935Z","end":"2026-02-10T18:46:13.347742Z","steps":["trace[1874923046] 'agreement among raft nodes before linearized reading' (duration: 153.66119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:13.347045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.079923534s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:46:13.347987Z","caller":"traceutil/trace.go:171","msg":"trace[230782473] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:418; }","duration":"1.080865084s","start":"2026-02-10T18:46:12.26711Z","end":"2026-02-10T18:46:13.347975Z","steps":["trace[230782473] 'agreement among raft nodes before linearized reading' (duration: 1.079910793s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:13.866689Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9997600059616033950,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T18:46:13.962645Z","caller":"traceutil/trace.go:171","msg":"trace[1447169704] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"598.561962ms","start":"2026-02-10T18:46:13.364056Z","end":"2026-02-10T18:46:13.962618Z","steps":["trace[1447169704] 'process raft request' (duration: 598.327684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:13.96284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:13.364037Z","time spent":"598.689816ms","remote":"127.0.0.1:36388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5083,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:46:13.971752Z","caller":"traceutil/trace.go:171","msg":"trace[591295696] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"603.517456ms","start":"2026-02-10T18:46:13.368217Z","end":"2026-02-10T18:46:13.971734Z","steps":["trace[591295696] 'process raft request' (duration: 603.470091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:13.971998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:13.368213Z","time spent":"603.72045ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:46:13.972112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.014886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:46:13.972144Z","caller":"traceutil/trace.go:171","msg":"trace[810627005] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:423; }","duration":"606.077223ms","start":"2026-02-10T18:46:13.36606Z","end":"2026-02-10T18:46:13.972138Z","steps":["trace[810627005] 'agreement among raft nodes before linearized reading' (duration: 606.016626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:13.972167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:13.366048Z","time spent":"606.114137ms","remote":"127.0.0.1:36214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-10T18:46:13.972325Z","caller":"traceutil/trace.go:171","msg":"trace[2126141100] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"602.69304ms","start":"2026-02-10T18:46:13.369624Z","end":"2026-02-10T18:46:13.972317Z","steps":["trace[2126141100] 'process raft request' (duration: 602.26434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:13.972378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:13.369619Z","time spent":"602.727924ms","remote":"127.0.0.1:36610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:46:13.972498Z","caller":"traceutil/trace.go:171","msg":"trace[2106153728] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"604.316979ms","start":"2026-02-10T18:46:13.368169Z","end":"2026-02-10T18:46:13.972486Z","steps":["trace[2106153728] 'process raft request' (duration: 603.433916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:13.972629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:13.368159Z","time spent":"604.423441ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:46:13.972025Z","caller":"traceutil/trace.go:171","msg":"trace[2084651649] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"602.815274ms","start":"2026-02-10T18:46:13.369196Z","end":"2026-02-10T18:46:13.972011Z","steps":["trace[2084651649] 'process raft request' (duration: 602.513199ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:13.97278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:13.369188Z","time spent":"603.567682ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:46:13.972064Z","caller":"traceutil/trace.go:171","msg":"trace[1190950362] linearizableReadLoop","detail":"{readStateIndex:432; appliedIndex:427; }","duration":"605.969051ms","start":"2026-02-10T18:46:13.366088Z","end":"2026-02-10T18:46:13.972058Z","steps":["trace[1190950362] 'read index received' (duration: 596.30868ms)","trace[1190950362] 'applied index is now lower than readState.Index' (duration: 9.659831ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:46:14.156318Z","caller":"traceutil/trace.go:171","msg":"trace[509161174] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"119.093492ms","start":"2026-02-10T18:46:14.0372Z","end":"2026-02-10T18:46:14.156294Z","steps":["trace[509161174] 'process raft request' (duration: 59.658564ms)","trace[509161174] 'compare' (duration: 59.314344ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:46:14.169295Z","caller":"traceutil/trace.go:171","msg":"trace[143987135] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"120.278979ms","start":"2026-02-10T18:46:14.040904Z","end":"2026-02-10T18:46:14.161183Z","steps":["trace[143987135] 'process raft request' (duration: 119.831637ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:14.170013Z","caller":"traceutil/trace.go:171","msg":"trace[1601606698] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"119.264232ms","start":"2026-02-10T18:46:14.041875Z","end":"2026-02-10T18:46:14.161139Z","steps":["trace[1601606698] 'process raft request' (duration: 118.998311ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:14.320033Z","caller":"traceutil/trace.go:171","msg":"trace[28889636] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"103.587866ms","start":"2026-02-10T18:46:14.216415Z","end":"2026-02-10T18:46:14.320003Z","steps":["trace[28889636] 'process raft request' (duration: 99.852825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:14.536143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.690235ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:46:14.536337Z","caller":"traceutil/trace.go:171","msg":"trace[661449934] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"149.831444ms","start":"2026-02-10T18:46:14.38646Z","end":"2026-02-10T18:46:14.536291Z","steps":["trace[661449934] 'process raft request' (duration: 34.935976ms)","trace[661449934] 'compare' (duration: 114.584263ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:46:14.536721Z","caller":"traceutil/trace.go:171","msg":"trace[1100002608] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"146.460286ms","start":"2026-02-10T18:46:14.39025Z","end":"2026-02-10T18:46:14.53671Z","steps":["trace[1100002608] 'process raft request' (duration: 146.009214ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:14.536914Z","caller":"traceutil/trace.go:171","msg":"trace[1640914157] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"147.703189ms","start":"2026-02-10T18:46:14.389203Z","end":"2026-02-10T18:46:14.536906Z","steps":["trace[1640914157] 'process raft request' (duration: 147.008769ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:14.537114Z","caller":"traceutil/trace.go:171","msg":"trace[1315306996] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"129.175894ms","start":"2026-02-10T18:46:14.407929Z","end":"2026-02-10T18:46:14.537105Z","steps":["trace[1315306996] 'process raft request' (duration: 128.382103ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:14.537223Z","caller":"traceutil/trace.go:171","msg":"trace[358323851] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"146.84163ms","start":"2026-02-10T18:46:14.390376Z","end":"2026-02-10T18:46:14.537218Z","steps":["trace[358323851] 'process raft request' (duration: 145.909093ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:14.540619Z","caller":"traceutil/trace.go:171","msg":"trace[1244159851] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"132.392504ms","start":"2026-02-10T18:46:14.408212Z","end":"2026-02-10T18:46:14.540605Z","steps":["trace[1244159851] 'process raft request' (duration: 128.126143ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:21.678252Z","caller":"traceutil/trace.go:171","msg":"trace[678599812] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:480; }","duration":"278.645321ms","start":"2026-02-10T18:46:21.399591Z","end":"2026-02-10T18:46:21.678236Z","steps":["trace[678599812] 'read index received' (duration: 278.481543ms)","trace[678599812] 'applied index is now lower than readState.Index' (duration: 163.098µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:46:21.678369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.764595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-10T18:46:21.678387Z","caller":"traceutil/trace.go:171","msg":"trace[2036861236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:467; }","duration":"278.81463ms","start":"2026-02-10T18:46:21.399566Z","end":"2026-02-10T18:46:21.67838Z","steps":["trace[2036861236] 'agreement among raft nodes before linearized reading' (duration: 278.731791ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:21.678514Z","caller":"traceutil/trace.go:171","msg":"trace[232043877] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"445.181153ms","start":"2026-02-10T18:46:21.233303Z","end":"2026-02-10T18:46:21.678484Z","steps":["trace[232043877] 'process raft request' (duration: 444.818773ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:21.678719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:21.233287Z","time spent":"445.306447ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:46:24.176181Z","caller":"traceutil/trace.go:171","msg":"trace[1823089524] linearizableReadLoop","detail":"{readStateIndex:488; appliedIndex:487; }","duration":"104.809255ms","start":"2026-02-10T18:46:24.071333Z","end":"2026-02-10T18:46:24.176142Z","steps":["trace[1823089524] 'read index received' (duration: 104.604693ms)","trace[1823089524] 'applied index is now lower than readState.Index' (duration: 203.672µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:46:24.176451Z","caller":"traceutil/trace.go:171","msg":"trace[779672507] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"132.098275ms","start":"2026-02-10T18:46:24.044323Z","end":"2026-02-10T18:46:24.176421Z","steps":["trace[779672507] 'process raft request' (duration: 131.669258ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:24.176496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.144692ms","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-10T18:46:24.176654Z","caller":"traceutil/trace.go:171","msg":"trace[1152566795] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:473; }","duration":"105.31286ms","start":"2026-02-10T18:46:24.071329Z","end":"2026-02-10T18:46:24.176641Z","steps":["trace[1152566795] 'agreement among raft nodes before linearized reading' (duration: 105.112939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:24.176803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.817421ms","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-10T18:46:24.176848Z","caller":"traceutil/trace.go:171","msg":"trace[1686052059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:473; }","duration":"101.897089ms","start":"2026-02-10T18:46:24.074939Z","end":"2026-02-10T18:46:24.176836Z","steps":["trace[1686052059] 'agreement among raft nodes before linearized reading' (duration: 101.80816ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:25.16861Z","caller":"traceutil/trace.go:171","msg":"trace[650267183] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"208.644151ms","start":"2026-02-10T18:46:24.959922Z","end":"2026-02-10T18:46:25.168567Z","steps":["trace[650267183] 'process raft request' (duration: 208.145597ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:25.345663Z","caller":"traceutil/trace.go:171","msg":"trace[1869694088] linearizableReadLoop","detail":"{readStateIndex:493; appliedIndex:491; }","duration":"133.005658ms","start":"2026-02-10T18:46:25.212636Z","end":"2026-02-10T18:46:25.345641Z","steps":["trace[1869694088] 'read index received' (duration: 129.901123ms)","trace[1869694088] 'applied index is now lower than readState.Index' (duration: 3.103594ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:46:25.345915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.257315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:46:25.345997Z","caller":"traceutil/trace.go:171","msg":"trace[1347885783] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:478; }","duration":"133.389359ms","start":"2026-02-10T18:46:25.212593Z","end":"2026-02-10T18:46:25.345983Z","steps":["trace[1347885783] 'agreement among raft nodes before linearized reading' (duration: 133.236803ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:25.346103Z","caller":"traceutil/trace.go:171","msg":"trace[1824295165] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"151.684262ms","start":"2026-02-10T18:46:25.194409Z","end":"2026-02-10T18:46:25.346093Z","steps":["trace[1824295165] 'process raft request' (duration: 151.180138ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:25.345921Z","caller":"traceutil/trace.go:171","msg":"trace[2117310216] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"151.441116ms","start":"2026-02-10T18:46:25.19431Z","end":"2026-02-10T18:46:25.345752Z","steps":["trace[2117310216] 'process raft request' (duration: 148.218089ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:27.644074Z","caller":"traceutil/trace.go:171","msg":"trace[2079371512] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"138.162554ms","start":"2026-02-10T18:46:27.505899Z","end":"2026-02-10T18:46:27.644061Z","steps":["trace[2079371512] 'process raft request' (duration: 138.071864ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:29.108289Z","caller":"traceutil/trace.go:171","msg":"trace[1860045564] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:532; }","duration":"132.724493ms","start":"2026-02-10T18:46:28.975551Z","end":"2026-02-10T18:46:29.108275Z","steps":["trace[1860045564] 'read index received' (duration: 132.618442ms)","trace[1860045564] 'applied index is now lower than readState.Index' (duration: 105.521µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:46:29.108409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.855706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.218\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-10T18:46:29.108427Z","caller":"traceutil/trace.go:171","msg":"trace[558474640] range","detail":"{range_begin:/registry/masterleases/162.253.55.218; range_end:; response_count:1; response_revision:518; }","duration":"132.922123ms","start":"2026-02-10T18:46:28.975498Z","end":"2026-02-10T18:46:29.10842Z","steps":["trace[558474640] 'agreement among raft nodes before linearized reading' (duration: 132.836324ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:29.108597Z","caller":"traceutil/trace.go:171","msg":"trace[1699523512] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"520.10285ms","start":"2026-02-10T18:46:28.588487Z","end":"2026-02-10T18:46:29.10859Z","steps":["trace[1699523512] 'process raft request' (duration: 519.718349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:29.108645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:28.588465Z","time spent":"520.145364ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:46:30.542971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.198839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-10T18:46:30.543041Z","caller":"traceutil/trace.go:171","msg":"trace[1680454615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:519; }","duration":"164.316221ms","start":"2026-02-10T18:46:30.378712Z","end":"2026-02-10T18:46:30.543028Z","steps":["trace[1680454615] 'range keys from in-memory index tree' (duration: 164.078706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:30.543431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.926733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-10T18:46:30.543453Z","caller":"traceutil/trace.go:171","msg":"trace[1230538848] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:519; }","duration":"715.980859ms","start":"2026-02-10T18:46:29.827466Z","end":"2026-02-10T18:46:30.543446Z","steps":["trace[1230538848] 'range keys from in-memory index tree' (duration: 715.776367ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:30.543478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:29.827448Z","time spent":"716.021033ms","remote":"127.0.0.1:36464","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-10T18:46:30.543587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.011142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:46:30.543607Z","caller":"traceutil/trace.go:171","msg":"trace[88153944] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:519; }","duration":"335.055266ms","start":"2026-02-10T18:46:30.208544Z","end":"2026-02-10T18:46:30.5436Z","steps":["trace[88153944] 'range keys from in-memory index tree' (duration: 334.965627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:30.543627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:30.208529Z","time spent":"335.09077ms","remote":"127.0.0.1:36214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:46:30.543749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.424195ms","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-10T18:46:30.543768Z","caller":"traceutil/trace.go:171","msg":"trace[725040323] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:519; }","duration":"299.480341ms","start":"2026-02-10T18:46:30.244281Z","end":"2026-02-10T18:46:30.543761Z","steps":["trace[725040323] 'range keys from in-memory index tree' (duration: 299.328965ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:30.543896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.220119ms","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-10T18:46:30.543915Z","caller":"traceutil/trace.go:171","msg":"trace[1719965831] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:519; }","duration":"211.273205ms","start":"2026-02-10T18:46:30.332636Z","end":"2026-02-10T18:46:30.543909Z","steps":["trace[1719965831] 'range keys from in-memory index tree' (duration: 211.138581ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:30.813026Z","caller":"traceutil/trace.go:171","msg":"trace[2030175452] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"263.330006ms","start":"2026-02-10T18:46:30.549679Z","end":"2026-02-10T18:46:30.813009Z","steps":["trace[2030175452] 'process raft request' (duration: 263.233376ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:30.813388Z","caller":"traceutil/trace.go:171","msg":"trace[171501144] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:536; }","duration":"263.552859ms","start":"2026-02-10T18:46:30.549827Z","end":"2026-02-10T18:46:30.81338Z","steps":["trace[171501144] 'read index received' (duration: 263.550349ms)","trace[171501144] 'applied index is now lower than readState.Index' (duration: 2.05µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:46:30.813434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.593544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:46:30.813454Z","caller":"traceutil/trace.go:171","msg":"trace[385655517] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:520; }","duration":"263.624387ms","start":"2026-02-10T18:46:30.549824Z","end":"2026-02-10T18:46:30.813449Z","steps":["trace[385655517] 'agreement among raft nodes before linearized reading' (duration: 263.581843ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:30.819482Z","caller":"traceutil/trace.go:171","msg":"trace[1959956175] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"267.350849ms","start":"2026-02-10T18:46:30.552117Z","end":"2026-02-10T18:46:30.819468Z","steps":["trace[1959956175] 'process raft request' (duration: 267.253728ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:30.819733Z","caller":"traceutil/trace.go:171","msg":"trace[1431103829] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"269.785035ms","start":"2026-02-10T18:46:30.549916Z","end":"2026-02-10T18:46:30.819701Z","steps":["trace[1431103829] 'process raft request' (duration: 269.330887ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:30.819811Z","caller":"traceutil/trace.go:171","msg":"trace[2094459253] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"267.811367ms","start":"2026-02-10T18:46:30.551994Z","end":"2026-02-10T18:46:30.819805Z","steps":["trace[2094459253] 'process raft request' (duration: 267.337957ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:32.1855Z","caller":"traceutil/trace.go:171","msg":"trace[570593969] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"396.622681ms","start":"2026-02-10T18:46:31.788862Z","end":"2026-02-10T18:46:32.185485Z","steps":["trace[570593969] 'process raft request' (duration: 396.531851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:32.185638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:31.788845Z","time spent":"396.702299ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:46:39.356295Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9997600059616034242,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T18:46:39.572692Z","caller":"traceutil/trace.go:171","msg":"trace[181913881] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"719.630328ms","start":"2026-02-10T18:46:38.853046Z","end":"2026-02-10T18:46:39.572677Z","steps":["trace[181913881] 'process raft request' (duration: 719.537919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:39.572791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:38.853021Z","time spent":"719.716857ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:46:39.573388Z","caller":"traceutil/trace.go:171","msg":"trace[44901734] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:580; }","duration":"717.868508ms","start":"2026-02-10T18:46:38.85551Z","end":"2026-02-10T18:46:39.573379Z","steps":["trace[44901734] 'read index received' (duration: 717.863898ms)","trace[44901734] 'applied index is now lower than readState.Index' (duration: 3.86µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:46:39.57346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.946457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-10T18:46:39.573477Z","caller":"traceutil/trace.go:171","msg":"trace[299963013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:563; }","duration":"717.98161ms","start":"2026-02-10T18:46:38.85549Z","end":"2026-02-10T18:46:39.573471Z","steps":["trace[299963013] 'agreement among raft nodes before linearized reading' (duration: 717.948236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:39.57349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:38.85548Z","time spent":"718.007642ms","remote":"127.0.0.1:36464","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-10T18:46:40.073987Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9997600059616034245,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:46:40.575188Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9997600059616034245,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:46:40.864007Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.291485407s","expected-duration":"1s"} {"level":"info","ts":"2026-02-10T18:46:40.864401Z","caller":"traceutil/trace.go:171","msg":"trace[921683435] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"1.502620708s","start":"2026-02-10T18:46:39.36176Z","end":"2026-02-10T18:46:40.86438Z","steps":["trace[921683435] 'process raft request' (duration: 1.502381954s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:40.864518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:39.361736Z","time spent":"1.502716638s","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:46:40.873234Z","caller":"traceutil/trace.go:171","msg":"trace[1368213047] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:581; }","duration":"1.299790087s","start":"2026-02-10T18:46:39.573428Z","end":"2026-02-10T18:46:40.873218Z","steps":["trace[1368213047] 'read index received' (duration: 1.291437461s)","trace[1368213047] 'applied index is now lower than readState.Index' (duration: 8.351966ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:46:40.873324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.017754195s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-10T18:46:40.873345Z","caller":"traceutil/trace.go:171","msg":"trace[1349994307] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:565; }","duration":"2.017791219s","start":"2026-02-10T18:46:38.855546Z","end":"2026-02-10T18:46:40.873338Z","steps":["trace[1349994307] 'agreement among raft nodes before linearized reading' (duration: 2.017723012s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:40.873364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:38.855542Z","time spent":"2.017817111s","remote":"127.0.0.1:36464","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-10T18:46:40.873509Z","caller":"traceutil/trace.go:171","msg":"trace[589358720] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"1.297456839s","start":"2026-02-10T18:46:39.576045Z","end":"2026-02-10T18:46:40.873502Z","steps":["trace[589358720] 'process raft request' (duration: 1.297103784s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:40.873555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:39.576034Z","time spent":"1.297492013s","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:46:40.873636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.668567179s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:46:40.873652Z","caller":"traceutil/trace.go:171","msg":"trace[1708345139] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:565; }","duration":"1.66858395s","start":"2026-02-10T18:46:39.205063Z","end":"2026-02-10T18:46:40.873647Z","steps":["trace[1708345139] 'agreement among raft nodes before linearized reading' (duration: 1.668555437s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:40.87367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:39.205014Z","time spent":"1.668651027s","remote":"127.0.0.1:36214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:46:40.873776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.8976143s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.218\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-10T18:46:40.873799Z","caller":"traceutil/trace.go:171","msg":"trace[1041109317] range","detail":"{range_begin:/registry/masterleases/162.253.55.218; range_end:; response_count:1; response_revision:565; }","duration":"1.897667515s","start":"2026-02-10T18:46:38.976126Z","end":"2026-02-10T18:46:40.873793Z","steps":["trace[1041109317] 'agreement among raft nodes before linearized reading' (duration: 1.89761481s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:40.873818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:38.976109Z","time spent":"1.897704499s","remote":"127.0.0.1:36234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.218\" "} {"level":"warn","ts":"2026-02-10T18:46:40.873941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.018368657s","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-10T18:46:40.873963Z","caller":"traceutil/trace.go:171","msg":"trace[742654872] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:565; }","duration":"2.01840773s","start":"2026-02-10T18:46:38.855548Z","end":"2026-02-10T18:46:40.873956Z","steps":["trace[742654872] 'agreement among raft nodes before linearized reading' (duration: 2.018368887s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:40.874487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:38.855539Z","time spent":"2.018935094s","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-10T18:46:41.085287Z","caller":"traceutil/trace.go:171","msg":"trace[1627052456] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:582; }","duration":"207.175578ms","start":"2026-02-10T18:46:40.878093Z","end":"2026-02-10T18:46:41.085269Z","steps":["trace[1627052456] 'read index received' (duration: 157.628782ms)","trace[1627052456] 'applied index is now lower than readState.Index' (duration: 49.546116ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:46:41.085496Z","caller":"traceutil/trace.go:171","msg":"trace[1117036516] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"207.436174ms","start":"2026-02-10T18:46:40.87805Z","end":"2026-02-10T18:46:41.085486Z","steps":["trace[1117036516] 'process raft request' (duration: 207.072537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:41.085527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.414092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:46:41.085562Z","caller":"traceutil/trace.go:171","msg":"trace[16130387] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:567; }","duration":"207.469468ms","start":"2026-02-10T18:46:40.878081Z","end":"2026-02-10T18:46:41.08555Z","steps":["trace[16130387] 'agreement among raft nodes before linearized reading' (duration: 207.402671ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:41.085683Z","caller":"traceutil/trace.go:171","msg":"trace[1039058662] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"205.799749ms","start":"2026-02-10T18:46:40.879874Z","end":"2026-02-10T18:46:41.085674Z","steps":["trace[1039058662] 'process raft request' (duration: 205.361365ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:42.707977Z","caller":"traceutil/trace.go:171","msg":"trace[2086194639] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"172.705523ms","start":"2026-02-10T18:46:42.535255Z","end":"2026-02-10T18:46:42.707961Z","steps":["trace[2086194639] 'process raft request' (duration: 172.600122ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:43.542063Z","caller":"traceutil/trace.go:171","msg":"trace[1805194412] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"261.517137ms","start":"2026-02-10T18:46:43.280525Z","end":"2026-02-10T18:46:43.542042Z","steps":["trace[1805194412] 'process raft request' (duration: 261.393005ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:45.251592Z","caller":"traceutil/trace.go:171","msg":"trace[151991281] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"134.73815ms","start":"2026-02-10T18:46:45.116837Z","end":"2026-02-10T18:46:45.251575Z","steps":["trace[151991281] 'read index received' (duration: 51.469097ms)","trace[151991281] 'applied index is now lower than readState.Index' (duration: 83.268543ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:46:45.251663Z","caller":"traceutil/trace.go:171","msg":"trace[1768250246] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"168.860039ms","start":"2026-02-10T18:46:45.082797Z","end":"2026-02-10T18:46:45.251657Z","steps":["trace[1768250246] 'process raft request' (duration: 85.501916ms)","trace[1768250246] 'compare' (duration: 83.176854ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:46:45.251801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.956042ms","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-10T18:46:45.251843Z","caller":"traceutil/trace.go:171","msg":"trace[608802498] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:626; }","duration":"135.031399ms","start":"2026-02-10T18:46:45.116801Z","end":"2026-02-10T18:46:45.251832Z","steps":["trace[608802498] 'agreement among raft nodes before linearized reading' (duration: 134.912637ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:45.338383Z","caller":"traceutil/trace.go:171","msg":"trace[2043819053] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"189.605695ms","start":"2026-02-10T18:46:45.148763Z","end":"2026-02-10T18:46:45.338369Z","steps":["trace[2043819053] 'process raft request' (duration: 189.541949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:45.338652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.903918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:46:45.338672Z","caller":"traceutil/trace.go:171","msg":"trace[238389031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"131.932621ms","start":"2026-02-10T18:46:45.206733Z","end":"2026-02-10T18:46:45.338666Z","steps":["trace[238389031] 'agreement among raft nodes before linearized reading' (duration: 131.892007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:45.338717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.00696ms","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-10T18:46:45.338783Z","caller":"traceutil/trace.go:171","msg":"trace[882939251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:627; }","duration":"202.0997ms","start":"2026-02-10T18:46:45.136665Z","end":"2026-02-10T18:46:45.338764Z","steps":["trace[882939251] 'agreement among raft nodes before linearized reading' (duration: 201.847605ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:45.405489Z","caller":"traceutil/trace.go:171","msg":"trace[1946111656] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"149.739334ms","start":"2026-02-10T18:46:45.255723Z","end":"2026-02-10T18:46:45.405462Z","steps":["trace[1946111656] 'process raft request' (duration: 122.750636ms)","trace[1946111656] 'compare' (duration: 26.662865ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:46:45.66396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.543011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:46:45.664174Z","caller":"traceutil/trace.go:171","msg":"trace[2109899936] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:647; }","duration":"116.440458ms","start":"2026-02-10T18:46:45.547718Z","end":"2026-02-10T18:46:45.664158Z","steps":["trace[2109899936] 'read index received' (duration: 8.576645ms)","trace[2109899936] 'applied index is now lower than readState.Index' (duration: 107.862723ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:46:45.664227Z","caller":"traceutil/trace.go:171","msg":"trace[524346480] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"312.200835ms","start":"2026-02-10T18:46:45.351978Z","end":"2026-02-10T18:46:45.664179Z","steps":["trace[524346480] 'process raft request' (duration: 312.066842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:45.664358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:45.351972Z","time spent":"312.320288ms","remote":"127.0.0.1:36262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T18:46:45.664403Z","caller":"traceutil/trace.go:171","msg":"trace[1926173265] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"311.917867ms","start":"2026-02-10T18:46:45.352473Z","end":"2026-02-10T18:46:45.664391Z","steps":["trace[1926173265] 'process raft request' (duration: 311.627068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:45.664468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:45.352468Z","time spent":"311.965523ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:46:45.664644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.924756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:46:45.664674Z","caller":"traceutil/trace.go:171","msg":"trace[1214553721] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:631; }","duration":"116.978002ms","start":"2026-02-10T18:46:45.547687Z","end":"2026-02-10T18:46:45.664665Z","steps":["trace[1214553721] 'agreement among raft nodes before linearized reading' (duration: 116.926246ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:45.664707Z","caller":"traceutil/trace.go:171","msg":"trace[615720638] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"314.692403ms","start":"2026-02-10T18:46:45.349999Z","end":"2026-02-10T18:46:45.664691Z","steps":["trace[615720638] 'process raft request' (duration: 206.340681ms)","trace[615720638] 'compare' (duration: 107.432371ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:46:45.664781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:45.349989Z","time spent":"314.747009ms","remote":"127.0.0.1:36262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10353,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:46:46.029365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.496966ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:46:46.029542Z","caller":"traceutil/trace.go:171","msg":"trace[1584834854] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:651; }","duration":"282.043844ms","start":"2026-02-10T18:46:45.747488Z","end":"2026-02-10T18:46:46.029532Z","steps":["trace[1584834854] 'read index received' (duration: 165.26253ms)","trace[1584834854] 'applied index is now lower than readState.Index' (duration: 116.780794ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:46:46.029699Z","caller":"traceutil/trace.go:171","msg":"trace[1944659864] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"346.330167ms","start":"2026-02-10T18:46:45.683362Z","end":"2026-02-10T18:46:46.029692Z","steps":["trace[1944659864] 'process raft request' (duration: 229.452463ms)","trace[1944659864] 'compare' (duration: 116.413708ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:46:46.029748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:45.683341Z","time spent":"346.379262ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:46:46.029911Z","caller":"traceutil/trace.go:171","msg":"trace[1294011850] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"346.055449ms","start":"2026-02-10T18:46:45.683851Z","end":"2026-02-10T18:46:46.029906Z","steps":["trace[1294011850] 'process raft request' (duration: 345.604104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:46.029939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:45.683845Z","time spent":"346.079422ms","remote":"127.0.0.1:36262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9296,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:46:46.029999Z","caller":"traceutil/trace.go:171","msg":"trace[1635379045] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"344.847339ms","start":"2026-02-10T18:46:45.685146Z","end":"2026-02-10T18:46:46.029994Z","steps":["trace[1635379045] 'process raft request' (duration: 344.35514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:46.030023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:45.68514Z","time spent":"344.869622ms","remote":"127.0.0.1:36262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10362,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:46:46.030367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.876898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-10T18:46:46.030391Z","caller":"traceutil/trace.go:171","msg":"trace[1737589776] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:635; }","duration":"282.925902ms","start":"2026-02-10T18:46:45.747458Z","end":"2026-02-10T18:46:46.030383Z","steps":["trace[1737589776] 'agreement among raft nodes before linearized reading' (duration: 282.843174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:46.394025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.598797ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:46:46.394136Z","caller":"traceutil/trace.go:171","msg":"trace[511469571] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:654; }","duration":"242.656518ms","start":"2026-02-10T18:46:46.151467Z","end":"2026-02-10T18:46:46.394123Z","steps":["trace[511469571] 'read index received' (duration: 107.915017ms)","trace[511469571] 'applied index is now lower than readState.Index' (duration: 134.740801ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:46:46.395088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.614903ms","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-10T18:46:46.395152Z","caller":"traceutil/trace.go:171","msg":"trace[12184713] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:637; }","duration":"243.681909ms","start":"2026-02-10T18:46:46.15144Z","end":"2026-02-10T18:46:46.395122Z","steps":["trace[12184713] 'agreement among raft nodes before linearized reading' (duration: 243.529864ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:46.395662Z","caller":"traceutil/trace.go:171","msg":"trace[1554236685] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"354.173392ms","start":"2026-02-10T18:46:46.041472Z","end":"2026-02-10T18:46:46.395645Z","steps":["trace[1554236685] 'process raft request' (duration: 217.900639ms)","trace[1554236685] 'compare' (duration: 134.52716ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:46:46.395748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:46.041458Z","time spent":"354.231579ms","remote":"127.0.0.1:36262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:46:46.396182Z","caller":"traceutil/trace.go:171","msg":"trace[386114887] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"298.421746ms","start":"2026-02-10T18:46:46.097647Z","end":"2026-02-10T18:46:46.396069Z","steps":["trace[386114887] 'process raft request' (duration: 296.442189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:46.396951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.940755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:46:46.396989Z","caller":"traceutil/trace.go:171","msg":"trace[1999894219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"185.995401ms","start":"2026-02-10T18:46:46.210978Z","end":"2026-02-10T18:46:46.396973Z","steps":["trace[1999894219] 'agreement among raft nodes before linearized reading' (duration: 185.947297ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:48.174386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.309266ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:46:48.174488Z","caller":"traceutil/trace.go:171","msg":"trace[1412813843] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:637; }","duration":"266.419056ms","start":"2026-02-10T18:46:47.908051Z","end":"2026-02-10T18:46:48.17447Z","steps":["trace[1412813843] 'range keys from in-memory index tree' (duration: 266.296244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:48.174864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.637947425s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:46:48.17544Z","caller":"traceutil/trace.go:171","msg":"trace[48574843] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.395793437s","start":"2026-02-10T18:46:46.779634Z","end":"2026-02-10T18:46:48.175427Z","steps":["trace[48574843] 'process raft request' (duration: 1.395452933s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:48.175504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:46.779616Z","time spent":"1.395860254s","remote":"127.0.0.1:36262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T18:46:48.175689Z","caller":"traceutil/trace.go:171","msg":"trace[728047085] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"1.722645367s","start":"2026-02-10T18:46:46.453032Z","end":"2026-02-10T18:46:48.175677Z","steps":["trace[728047085] 'process raft request' (duration: 83.838446ms)","trace[728047085] 'compare' (duration: 1.636967379s)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:46:48.175733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:46.453016Z","time spent":"1.722696651s","remote":"127.0.0.1:36298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T18:46:48.175852Z","caller":"traceutil/trace.go:171","msg":"trace[781983757] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"1.627242382s","start":"2026-02-10T18:46:46.548601Z","end":"2026-02-10T18:46:48.175843Z","steps":["trace[781983757] 'read index received' (duration: 19.242µs)","trace[781983757] 'applied index is now lower than readState.Index' (duration: 1.62722197s)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:46:48.176257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.627650042s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-10T18:46:48.176288Z","caller":"traceutil/trace.go:171","msg":"trace[2035208762] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:639; }","duration":"1.627681225s","start":"2026-02-10T18:46:46.548597Z","end":"2026-02-10T18:46:48.176278Z","steps":["trace[2035208762] 'agreement among raft nodes before linearized reading' (duration: 1.627547612s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:48.176317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:46.548559Z","time spent":"1.627752142s","remote":"127.0.0.1:36262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24267,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"info","ts":"2026-02-10T18:46:48.262346Z","caller":"traceutil/trace.go:171","msg":"trace[1452432509] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"887.338038ms","start":"2026-02-10T18:46:47.374994Z","end":"2026-02-10T18:46:48.262332Z","steps":["trace[1452432509] 'process raft request' (duration: 887.047459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:48.262419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:47.374961Z","time spent":"887.422596ms","remote":"127.0.0.1:36262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T18:46:48.262584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.649419ms","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-10T18:46:48.263213Z","caller":"traceutil/trace.go:171","msg":"trace[1263790958] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:640; }","duration":"228.713925ms","start":"2026-02-10T18:46:48.033885Z","end":"2026-02-10T18:46:48.262599Z","steps":["trace[1263790958] 'agreement among raft nodes before linearized reading' (duration: 228.619506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:48.263778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.975755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-10T18:46:48.263899Z","caller":"traceutil/trace.go:171","msg":"trace[922547472] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:640; }","duration":"515.106508ms","start":"2026-02-10T18:46:47.748758Z","end":"2026-02-10T18:46:48.263865Z","steps":["trace[922547472] 'agreement among raft nodes before linearized reading' (duration: 514.866754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:48.263984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:47.748748Z","time spent":"515.227819ms","remote":"127.0.0.1:36262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9343,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-10T18:46:48.264189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.7567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:46:48.264265Z","caller":"traceutil/trace.go:171","msg":"trace[333969482] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"537.86195ms","start":"2026-02-10T18:46:47.726397Z","end":"2026-02-10T18:46:48.264259Z","steps":["trace[333969482] 'agreement among raft nodes before linearized reading' (duration: 537.773382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:48.264335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:47.726385Z","time spent":"537.944049ms","remote":"127.0.0.1:36214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:46:48.264508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.726756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-10T18:46:48.26458Z","caller":"traceutil/trace.go:171","msg":"trace[1898793431] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:640; }","duration":"559.821276ms","start":"2026-02-10T18:46:47.704752Z","end":"2026-02-10T18:46:48.264573Z","steps":["trace[1898793431] 'agreement among raft nodes before linearized reading' (duration: 559.713165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:48.264648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:47.704741Z","time spent":"559.901543ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-10T18:46:48.264831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.756177ms","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-10T18:46:48.264898Z","caller":"traceutil/trace.go:171","msg":"trace[1902863820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:640; }","duration":"591.87568ms","start":"2026-02-10T18:46:47.673015Z","end":"2026-02-10T18:46:48.264891Z","steps":["trace[1902863820] 'agreement among raft nodes before linearized reading' (duration: 591.77699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:48.264974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:47.672998Z","time spent":"591.969889ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T18:46:48.265137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.584788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-02-10T18:46:48.265212Z","caller":"traceutil/trace.go:171","msg":"trace[1729563399] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:640; }","duration":"717.692179ms","start":"2026-02-10T18:46:47.547512Z","end":"2026-02-10T18:46:48.265205Z","steps":["trace[1729563399] 'agreement among raft nodes before linearized reading' (duration: 717.585128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:48.265279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:47.547498Z","time spent":"717.772706ms","remote":"127.0.0.1:36262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10400,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-10T18:46:48.265888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.924178ms","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-10T18:46:48.265953Z","caller":"traceutil/trace.go:171","msg":"trace[658858110] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:640; }","duration":"855.017287ms","start":"2026-02-10T18:46:47.41093Z","end":"2026-02-10T18:46:48.265947Z","steps":["trace[658858110] 'agreement among raft nodes before linearized reading' (duration: 854.888995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:48.266023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:47.410915Z","time spent":"855.101395ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-10T18:46:48.266184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.118748723s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-10T18:46:48.26625Z","caller":"traceutil/trace.go:171","msg":"trace[903816449] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:640; }","duration":"1.118835942s","start":"2026-02-10T18:46:47.147407Z","end":"2026-02-10T18:46:48.266243Z","steps":["trace[903816449] 'agreement among raft nodes before linearized reading' (duration: 1.118725341s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:48.266322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:47.147395Z","time spent":"1.118920851s","remote":"127.0.0.1:36262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5200,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-02-10T18:46:48.266942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.1804204s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fdcqr\" ","response":"range_response_count:1 size:17918"} {"level":"info","ts":"2026-02-10T18:46:48.267007Z","caller":"traceutil/trace.go:171","msg":"trace[45806132] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fdcqr; range_end:; response_count:1; response_revision:640; }","duration":"1.18051989s","start":"2026-02-10T18:46:47.086481Z","end":"2026-02-10T18:46:48.267001Z","steps":["trace[45806132] 'agreement among raft nodes before linearized reading' (duration: 1.180380377s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:48.267082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:47.086464Z","time spent":"1.18060999s","remote":"127.0.0.1:36388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17941,"request content":"key:\"/registry/pods/kube-system/cilium-fdcqr\" "} {"level":"warn","ts":"2026-02-10T18:46:48.267273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.318750314s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-02-10T18:46:48.267346Z","caller":"traceutil/trace.go:171","msg":"trace[586536537] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:640; }","duration":"1.318850784s","start":"2026-02-10T18:46:46.948489Z","end":"2026-02-10T18:46:48.267339Z","steps":["trace[586536537] 'agreement among raft nodes before linearized reading' (duration: 1.318746514s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:48.267415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:46.948471Z","time spent":"1.318937373s","remote":"127.0.0.1:36262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13252,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-02-10T18:46:48.267575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.365915798s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-10T18:46:48.267985Z","caller":"traceutil/trace.go:171","msg":"trace[264622233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:640; }","duration":"1.366362012s","start":"2026-02-10T18:46:46.901617Z","end":"2026-02-10T18:46:48.267979Z","steps":["trace[264622233] 'agreement among raft nodes before linearized reading' (duration: 1.365921418s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:48.268061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:46.901599Z","time spent":"1.366453041s","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-10T18:46:48.526565Z","caller":"traceutil/trace.go:171","msg":"trace[571580999] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"156.358901ms","start":"2026-02-10T18:46:48.370188Z","end":"2026-02-10T18:46:48.526547Z","steps":["trace[571580999] 'process raft request' (duration: 117.791953ms)","trace[571580999] 'compare' (duration: 38.343516ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:46:48.873766Z","caller":"traceutil/trace.go:171","msg":"trace[468319057] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"488.465438ms","start":"2026-02-10T18:46:48.385286Z","end":"2026-02-10T18:46:48.873752Z","steps":["trace[468319057] 'process raft request' (duration: 488.441305ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:48.873843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:48.385282Z","time spent":"488.524893ms","remote":"127.0.0.1:36298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":739,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T18:46:48.874025Z","caller":"traceutil/trace.go:171","msg":"trace[1139044665] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"503.697722ms","start":"2026-02-10T18:46:48.370321Z","end":"2026-02-10T18:46:48.874019Z","steps":["trace[1139044665] 'process raft request' (duration: 427.648092ms)","trace[1139044665] 'compare' (duration: 75.165043ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:46:48.874059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:48.370316Z","time spent":"503.721144ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:46:48.874128Z","caller":"traceutil/trace.go:171","msg":"trace[341415062] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"503.764989ms","start":"2026-02-10T18:46:48.370359Z","end":"2026-02-10T18:46:48.874124Z","steps":["trace[341415062] 'process raft request' (duration: 503.008154ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:48.874152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:48.370356Z","time spent":"503.78297ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:46:48.874633Z","caller":"traceutil/trace.go:171","msg":"trace[2093051781] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"501.008216ms","start":"2026-02-10T18:46:48.37362Z","end":"2026-02-10T18:46:48.874628Z","steps":["trace[2093051781] 'process raft request' (duration: 499.865294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:48.87466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:48.373613Z","time spent":"501.032428ms","remote":"127.0.0.1:36262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:46:48.874846Z","caller":"traceutil/trace.go:171","msg":"trace[941618669] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"497.237793ms","start":"2026-02-10T18:46:48.377603Z","end":"2026-02-10T18:46:48.874841Z","steps":["trace[941618669] 'process raft request' (duration: 496.064778ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:48.874871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:48.377595Z","time spent":"497.261836ms","remote":"127.0.0.1:36388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18065,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:46:48.874923Z","caller":"traceutil/trace.go:171","msg":"trace[720438273] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"489.792148ms","start":"2026-02-10T18:46:48.385127Z","end":"2026-02-10T18:46:48.874919Z","steps":["trace[720438273] 'process raft request' (duration: 488.566647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:48.874945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:48.385112Z","time spent":"489.821011ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:46:48.875003Z","caller":"traceutil/trace.go:171","msg":"trace[36978901] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"489.789388ms","start":"2026-02-10T18:46:48.385209Z","end":"2026-02-10T18:46:48.874998Z","steps":["trace[36978901] 'process raft request' (duration: 488.499971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:48.875025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:46:48.385205Z","time spent":"489.80708ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:46:49.243646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.27935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.218\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-10T18:46:49.243694Z","caller":"traceutil/trace.go:171","msg":"trace[696095503] range","detail":"{range_begin:/registry/masterleases/162.253.55.218; range_end:; response_count:1; response_revision:649; }","duration":"252.354917ms","start":"2026-02-10T18:46:48.991327Z","end":"2026-02-10T18:46:49.243682Z","steps":["trace[696095503] 'range keys from in-memory index tree' (duration: 252.196182ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:49.243913Z","caller":"traceutil/trace.go:171","msg":"trace[170264416] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"224.845865ms","start":"2026-02-10T18:46:49.019059Z","end":"2026-02-10T18:46:49.243905Z","steps":["trace[170264416] 'process raft request' (duration: 223.06737ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:49.340478Z","caller":"traceutil/trace.go:171","msg":"trace[707795047] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:668; }","duration":"131.745115ms","start":"2026-02-10T18:46:49.208717Z","end":"2026-02-10T18:46:49.340462Z","steps":["trace[707795047] 'read index received' (duration: 33.417111ms)","trace[707795047] 'applied index is now lower than readState.Index' (duration: 98.327534ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:46:49.340729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.845515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:46:49.340761Z","caller":"traceutil/trace.go:171","msg":"trace[2142631011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:651; }","duration":"132.076478ms","start":"2026-02-10T18:46:49.208677Z","end":"2026-02-10T18:46:49.340753Z","steps":["trace[2142631011] 'agreement among raft nodes before linearized reading' (duration: 131.854036ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:49.340978Z","caller":"traceutil/trace.go:171","msg":"trace[924270078] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"280.999395ms","start":"2026-02-10T18:46:49.059972Z","end":"2026-02-10T18:46:49.340971Z","steps":["trace[924270078] 'process raft request' (duration: 280.313197ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:49.482777Z","caller":"traceutil/trace.go:171","msg":"trace[187459770] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:670; }","duration":"127.360354ms","start":"2026-02-10T18:46:49.3554Z","end":"2026-02-10T18:46:49.48276Z","steps":["trace[187459770] 'read index received' (duration: 32.707821ms)","trace[187459770] 'applied index is now lower than readState.Index' (duration: 94.651613ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:46:49.483008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.595787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:46:49.483028Z","caller":"traceutil/trace.go:171","msg":"trace[1756493512] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:651; }","duration":"127.655392ms","start":"2026-02-10T18:46:49.355365Z","end":"2026-02-10T18:46:49.48302Z","steps":["trace[1756493512] 'agreement among raft nodes before linearized reading' (duration: 127.612688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:46:49.714382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.466061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-10T18:46:49.714432Z","caller":"traceutil/trace.go:171","msg":"trace[837335280] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:654; }","duration":"129.550379ms","start":"2026-02-10T18:46:49.584868Z","end":"2026-02-10T18:46:49.714418Z","steps":["trace[837335280] 'range keys from in-memory index tree' (duration: 129.35558ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:54.014334Z","caller":"traceutil/trace.go:171","msg":"trace[1703558182] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"105.352231ms","start":"2026-02-10T18:46:53.908963Z","end":"2026-02-10T18:46:54.014315Z","steps":["trace[1703558182] 'process raft request' (duration: 105.234249ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:54.281439Z","caller":"traceutil/trace.go:171","msg":"trace[458143498] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"173.948562ms","start":"2026-02-10T18:46:54.107463Z","end":"2026-02-10T18:46:54.281411Z","steps":["trace[458143498] 'process raft request' (duration: 165.568267ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:54.323486Z","caller":"traceutil/trace.go:171","msg":"trace[2022101335] linearizableReadLoop","detail":"{readStateIndex:707; appliedIndex:701; }","duration":"116.431847ms","start":"2026-02-10T18:46:54.207032Z","end":"2026-02-10T18:46:54.323464Z","steps":["trace[2022101335] 'read index received' (duration: 66.006772ms)","trace[2022101335] 'applied index is now lower than readState.Index' (duration: 50.424105ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:46:54.323679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.629037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:46:54.32378Z","caller":"traceutil/trace.go:171","msg":"trace[616769819] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:686; }","duration":"116.76337ms","start":"2026-02-10T18:46:54.207004Z","end":"2026-02-10T18:46:54.323767Z","steps":["trace[616769819] 'agreement among raft nodes before linearized reading' (duration: 116.631787ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:54.324058Z","caller":"traceutil/trace.go:171","msg":"trace[1777303194] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"162.40583ms","start":"2026-02-10T18:46:54.161639Z","end":"2026-02-10T18:46:54.324045Z","steps":["trace[1777303194] 'process raft request' (duration: 161.558177ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:54.324191Z","caller":"traceutil/trace.go:171","msg":"trace[1277422052] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"162.365066ms","start":"2026-02-10T18:46:54.161733Z","end":"2026-02-10T18:46:54.324098Z","steps":["trace[1277422052] 'process raft request' (duration: 161.58327ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:54.324351Z","caller":"traceutil/trace.go:171","msg":"trace[1314746670] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"162.498918ms","start":"2026-02-10T18:46:54.161779Z","end":"2026-02-10T18:46:54.324278Z","steps":["trace[1314746670] 'process raft request' (duration: 161.575889ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:54.324558Z","caller":"traceutil/trace.go:171","msg":"trace[646082078] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"159.415118ms","start":"2026-02-10T18:46:54.16513Z","end":"2026-02-10T18:46:54.324545Z","steps":["trace[646082078] 'process raft request' (duration: 158.293169ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:46:54.324072Z","caller":"traceutil/trace.go:171","msg":"trace[1652385389] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"162.267496ms","start":"2026-02-10T18:46:54.161795Z","end":"2026-02-10T18:46:54.324062Z","steps":["trace[1652385389] 'process raft request' (duration: 161.597731ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:47:17.370423Z","caller":"traceutil/trace.go:171","msg":"trace[354148963] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"146.690677ms","start":"2026-02-10T18:47:17.223711Z","end":"2026-02-10T18:47:17.370401Z","steps":["trace[354148963] 'process raft request' (duration: 143.135974ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:47:21.394808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.814027ms","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-10T18:47:21.394878Z","caller":"traceutil/trace.go:171","msg":"trace[1722923065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:825; }","duration":"142.889474ms","start":"2026-02-10T18:47:21.25197Z","end":"2026-02-10T18:47:21.39486Z","steps":["trace[1722923065] 'range keys from in-memory index tree' (duration: 142.683106ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:47:21.833136Z","caller":"traceutil/trace.go:171","msg":"trace[1623225793] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"134.997519ms","start":"2026-02-10T18:47:21.698114Z","end":"2026-02-10T18:47:21.833112Z","steps":["trace[1623225793] 'process raft request' (duration: 134.840024ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:47:49.148711Z","caller":"traceutil/trace.go:171","msg":"trace[17435159] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"151.454437ms","start":"2026-02-10T18:47:48.997226Z","end":"2026-02-10T18:47:49.14868Z","steps":["trace[17435159] 'process raft request' (duration: 150.868714ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:47:49.787651Z","caller":"traceutil/trace.go:171","msg":"trace[454455969] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"146.237501ms","start":"2026-02-10T18:47:49.6414Z","end":"2026-02-10T18:47:49.787638Z","steps":["trace[454455969] 'process raft request' (duration: 141.268537ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:47:51.500578Z","caller":"traceutil/trace.go:171","msg":"trace[342665897] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:939; }","duration":"292.624902ms","start":"2026-02-10T18:47:51.207936Z","end":"2026-02-10T18:47:51.500561Z","steps":["trace[342665897] 'read index received' (duration: 292.48645ms)","trace[342665897] 'applied index is now lower than readState.Index' (duration: 137.922µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:47:51.500787Z","caller":"traceutil/trace.go:171","msg":"trace[1061289431] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"306.187752ms","start":"2026-02-10T18:47:51.194592Z","end":"2026-02-10T18:47:51.50078Z","steps":["trace[1061289431] 'process raft request' (duration: 305.874224ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:47:51.500848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:47:51.19458Z","time spent":"306.222506ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:47:51.500958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.020269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:47:51.500975Z","caller":"traceutil/trace.go:171","msg":"trace[105397030] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:907; }","duration":"293.050011ms","start":"2026-02-10T18:47:51.20792Z","end":"2026-02-10T18:47:51.50097Z","steps":["trace[105397030] 'agreement among raft nodes before linearized reading' (duration: 293.020839ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:47:51.988431Z","caller":"traceutil/trace.go:171","msg":"trace[1677362617] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"288.617736ms","start":"2026-02-10T18:47:51.699787Z","end":"2026-02-10T18:47:51.988405Z","steps":["trace[1677362617] 'process raft request' (duration: 288.32299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:47:51.989741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.472245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-10T18:47:51.989842Z","caller":"traceutil/trace.go:171","msg":"trace[1835694648] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:909; }","duration":"198.616108ms","start":"2026-02-10T18:47:51.791213Z","end":"2026-02-10T18:47:51.98983Z","steps":["trace[1835694648] 'agreement among raft nodes before linearized reading' (duration: 198.4144ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:47:51.989011Z","caller":"traceutil/trace.go:171","msg":"trace[1953176323] linearizableReadLoop","detail":"{readStateIndex:942; appliedIndex:942; }","duration":"197.745228ms","start":"2026-02-10T18:47:51.791251Z","end":"2026-02-10T18:47:51.988997Z","steps":["trace[1953176323] 'read index received' (duration: 197.725207ms)","trace[1953176323] 'applied index is now lower than readState.Index' (duration: 17.321µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:47:51.992104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.37186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-10T18:47:51.992192Z","caller":"traceutil/trace.go:171","msg":"trace[727133602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:909; }","duration":"117.48342ms","start":"2026-02-10T18:47:51.874695Z","end":"2026-02-10T18:47:51.992178Z","steps":["trace[727133602] 'agreement among raft nodes before linearized reading' (duration: 117.327486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:47:52.706913Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9997600059616035057,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T18:47:53.037774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.993861ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:47:53.037888Z","caller":"traceutil/trace.go:171","msg":"trace[525951254] linearizableReadLoop","detail":"{readStateIndex:945; appliedIndex:943; }","duration":"831.002461ms","start":"2026-02-10T18:47:52.206876Z","end":"2026-02-10T18:47:53.037878Z","steps":["trace[525951254] 'read index received' (duration: 698.862976ms)","trace[525951254] 'applied index is now lower than readState.Index' (duration: 132.138965ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:47:53.038011Z","caller":"traceutil/trace.go:171","msg":"trace[980990595] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"1.040284067s","start":"2026-02-10T18:47:51.99772Z","end":"2026-02-10T18:47:53.038005Z","steps":["trace[980990595] 'process raft request' (duration: 908.00833ms)","trace[980990595] 'compare' (duration: 131.810355ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:47:53.038042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:47:51.997706Z","time spent":"1.040319671s","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:47:53.038127Z","caller":"traceutil/trace.go:171","msg":"trace[2098911436] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"701.841858ms","start":"2026-02-10T18:47:52.336279Z","end":"2026-02-10T18:47:53.038121Z","steps":["trace[2098911436] 'process raft request' (duration: 701.562132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:47:53.038152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:47:52.336261Z","time spent":"701.877222ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:47:53.038214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.338562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:47:53.038227Z","caller":"traceutil/trace.go:171","msg":"trace[1246743027] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:912; }","duration":"831.351303ms","start":"2026-02-10T18:47:52.206871Z","end":"2026-02-10T18:47:53.038223Z","steps":["trace[1246743027] 'agreement among raft nodes before linearized reading' (duration: 831.328121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:47:53.03824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:47:52.206829Z","time spent":"831.408429ms","remote":"127.0.0.1:36214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:47:53.038309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.571357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:47:53.03832Z","caller":"traceutil/trace.go:171","msg":"trace[229576305] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:912; }","duration":"531.617501ms","start":"2026-02-10T18:47:52.506699Z","end":"2026-02-10T18:47:53.038317Z","steps":["trace[229576305] 'agreement among raft nodes before linearized reading' (duration: 531.598779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:47:53.038332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:47:52.506682Z","time spent":"531.647164ms","remote":"127.0.0.1:36482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-02-10T18:47:54.478017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.008992ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-02-10T18:47:54.478486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:47:53.741868Z","time spent":"736.613336ms","remote":"127.0.0.1:36298","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-10T18:47:54.478838Z","caller":"traceutil/trace.go:171","msg":"trace[391568274] linearizableReadLoop","detail":"{readStateIndex:950; appliedIndex:949; }","duration":"480.448825ms","start":"2026-02-10T18:47:53.998377Z","end":"2026-02-10T18:47:54.478826Z","steps":["trace[391568274] 'read index received' (duration: 304.630149ms)","trace[391568274] 'applied index is now lower than readState.Index' (duration: 175.816945ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:47:54.849655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.541079ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-10T18:47:54.849929Z","caller":"traceutil/trace.go:171","msg":"trace[905895606] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:950; }","duration":"370.977987ms","start":"2026-02-10T18:47:54.478917Z","end":"2026-02-10T18:47:54.849894Z","steps":["trace[905895606] 'read index received' (duration: 35.993µs)","trace[905895606] 'applied index is now lower than readState.Index' (duration: 370.941094ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:47:54.85021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.557122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-10T18:47:54.850309Z","caller":"traceutil/trace.go:171","msg":"trace[723700827] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:916; }","duration":"819.700704ms","start":"2026-02-10T18:47:54.030597Z","end":"2026-02-10T18:47:54.850298Z","steps":["trace[723700827] 'agreement among raft nodes before linearized reading' (duration: 819.445941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:47:54.850432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:47:54.030578Z","time spent":"819.840257ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-10T18:47:54.85086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.429597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:47:54.851103Z","caller":"traceutil/trace.go:171","msg":"trace[197964272] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:0; response_revision:916; }","duration":"483.697901ms","start":"2026-02-10T18:47:54.367393Z","end":"2026-02-10T18:47:54.851091Z","steps":["trace[197964272] 'agreement among raft nodes before linearized reading' (duration: 483.437587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:47:54.851213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:47:54.367378Z","time spent":"483.823303ms","remote":"127.0.0.1:36388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"warn","ts":"2026-02-10T18:47:54.851639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.436247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:47:54.852153Z","caller":"traceutil/trace.go:171","msg":"trace[246887670] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:916; }","duration":"642.986557ms","start":"2026-02-10T18:47:54.20915Z","end":"2026-02-10T18:47:54.852137Z","steps":["trace[246887670] 'agreement among raft nodes before linearized reading' (duration: 642.456039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:47:54.852285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:47:54.209135Z","time spent":"643.13683ms","remote":"127.0.0.1:36214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:47:54.850865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.455136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-10T18:47:54.852811Z","caller":"traceutil/trace.go:171","msg":"trace[867945008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:916; }","duration":"854.392193ms","start":"2026-02-10T18:47:53.998346Z","end":"2026-02-10T18:47:54.852739Z","steps":["trace[867945008] 'agreement among raft nodes before linearized reading' (duration: 480.594238ms)","trace[867945008] 'range keys from in-memory index tree' (duration: 371.745887ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:47:54.85314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:47:53.998334Z","time spent":"854.790299ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-10T18:47:54.855477Z","caller":"traceutil/trace.go:171","msg":"trace[1518501317] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"375.916148ms","start":"2026-02-10T18:47:54.479548Z","end":"2026-02-10T18:47:54.855464Z","steps":["trace[1518501317] 'process raft request' (duration: 370.278473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:47:54.855736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:47:54.479534Z","time spent":"376.15546ms","remote":"127.0.0.1:36298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":702,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T18:47:54.978165Z","caller":"traceutil/trace.go:171","msg":"trace[814518751] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"108.052737ms","start":"2026-02-10T18:47:54.870093Z","end":"2026-02-10T18:47:54.978146Z","steps":["trace[814518751] 'process raft request' (duration: 108.009243ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:47:54.978677Z","caller":"traceutil/trace.go:171","msg":"trace[2123201760] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"108.62842ms","start":"2026-02-10T18:47:54.870026Z","end":"2026-02-10T18:47:54.978655Z","steps":["trace[2123201760] 'process raft request' (duration: 107.967969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:47:55.353104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.543508ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:47:55.353465Z","caller":"traceutil/trace.go:171","msg":"trace[2091247833] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:955; }","duration":"430.231276ms","start":"2026-02-10T18:47:54.923182Z","end":"2026-02-10T18:47:55.353414Z","steps":["trace[2091247833] 'read index received' (duration: 57.143138ms)","trace[2091247833] 'applied index is now lower than readState.Index' (duration: 373.083818ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:47:55.353516Z","caller":"traceutil/trace.go:171","msg":"trace[398207927] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"432.958855ms","start":"2026-02-10T18:47:54.920499Z","end":"2026-02-10T18:47:55.353458Z","steps":["trace[398207927] 'process raft request' (duration: 323.986688ms)","trace[398207927] 'compare' (duration: 107.995768ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:47:55.353793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:47:54.920478Z","time spent":"433.152673ms","remote":"127.0.0.1:36368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":653,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:47:55.355205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.009348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1490"} {"level":"info","ts":"2026-02-10T18:47:55.355415Z","caller":"traceutil/trace.go:171","msg":"trace[1602270046] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:921; }","duration":"432.24092ms","start":"2026-02-10T18:47:54.923159Z","end":"2026-02-10T18:47:55.3554Z","steps":["trace[1602270046] 'agreement among raft nodes before linearized reading' (duration: 430.627142ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:47:55.355575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:47:54.923147Z","time spent":"432.384493ms","remote":"127.0.0.1:36388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":1513,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"warn","ts":"2026-02-10T18:47:55.356029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.778753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-10T18:47:55.356279Z","caller":"traceutil/trace.go:171","msg":"trace[78833881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:921; }","duration":"314.078151ms","start":"2026-02-10T18:47:55.042188Z","end":"2026-02-10T18:47:55.356266Z","steps":["trace[78833881] 'agreement among raft nodes before linearized reading' (duration: 313.765112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:47:55.356376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:47:55.042171Z","time spent":"314.188741ms","remote":"127.0.0.1:36464","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-10T18:47:55.356772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.517431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T18:47:55.356975Z","caller":"traceutil/trace.go:171","msg":"trace[1188560176] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:921; }","duration":"104.756502ms","start":"2026-02-10T18:47:55.252207Z","end":"2026-02-10T18:47:55.356963Z","steps":["trace[1188560176] 'agreement among raft nodes before linearized reading' (duration: 104.485428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:47:55.815347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.299034ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:47:55.815451Z","caller":"traceutil/trace.go:171","msg":"trace[894562215] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:958; }","duration":"401.963094ms","start":"2026-02-10T18:47:55.413471Z","end":"2026-02-10T18:47:55.815434Z","steps":["trace[894562215] 'read index received' (duration: 29.042021ms)","trace[894562215] 'applied index is now lower than readState.Index' (duration: 372.919873ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:47:55.815543Z","caller":"traceutil/trace.go:171","msg":"trace[261440906] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"404.830875ms","start":"2026-02-10T18:47:55.410697Z","end":"2026-02-10T18:47:55.815528Z","steps":["trace[261440906] 'process raft request' (duration: 150.284559ms)","trace[261440906] 'compare' (duration: 254.113977ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:47:55.815614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:47:55.410664Z","time spent":"404.920734ms","remote":"127.0.0.1:36298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":900,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T18:47:55.816136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.124045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T18:47:55.816408Z","caller":"traceutil/trace.go:171","msg":"trace[408696758] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:924; }","duration":"290.455085ms","start":"2026-02-10T18:47:55.525924Z","end":"2026-02-10T18:47:55.816379Z","steps":["trace[408696758] 'agreement among raft nodes before linearized reading' (duration: 290.06825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:47:55.817118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.635806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4912"} {"level":"warn","ts":"2026-02-10T18:47:55.817686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.230205ms","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-10T18:47:55.817728Z","caller":"traceutil/trace.go:171","msg":"trace[1776271812] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:924; }","duration":"260.298201ms","start":"2026-02-10T18:47:55.557418Z","end":"2026-02-10T18:47:55.817716Z","steps":["trace[1776271812] 'agreement among raft nodes before linearized reading' (duration: 260.216994ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:47:55.817927Z","caller":"traceutil/trace.go:171","msg":"trace[434156369] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:924; }","duration":"404.470683ms","start":"2026-02-10T18:47:55.413439Z","end":"2026-02-10T18:47:55.81791Z","steps":["trace[434156369] 'agreement among raft nodes before linearized reading' (duration: 403.540538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:47:55.818362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:47:55.413426Z","time spent":"404.919784ms","remote":"127.0.0.1:36386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4935,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-10T18:47:55.817557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.807ms","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-10T18:47:55.821196Z","caller":"traceutil/trace.go:171","msg":"trace[373110478] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:924; }","duration":"226.488086ms","start":"2026-02-10T18:47:55.594696Z","end":"2026-02-10T18:47:55.821184Z","steps":["trace[373110478] 'agreement among raft nodes before linearized reading' (duration: 222.7035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:47:55.817607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.927555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:47:55.821567Z","caller":"traceutil/trace.go:171","msg":"trace[1754832970] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:924; }","duration":"152.880316ms","start":"2026-02-10T18:47:55.66864Z","end":"2026-02-10T18:47:55.82152Z","steps":["trace[1754832970] 'agreement among raft nodes before linearized reading' (duration: 148.942757ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:47:55.972013Z","caller":"traceutil/trace.go:171","msg":"trace[251080233] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:960; }","duration":"141.146504ms","start":"2026-02-10T18:47:55.830842Z","end":"2026-02-10T18:47:55.971989Z","steps":["trace[251080233] 'read index received' (duration: 76.700031ms)","trace[251080233] 'applied index is now lower than readState.Index' (duration: 64.445483ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:47:55.972329Z","caller":"traceutil/trace.go:171","msg":"trace[114923184] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"147.676141ms","start":"2026-02-10T18:47:55.82456Z","end":"2026-02-10T18:47:55.972236Z","steps":["trace[114923184] 'process raft request' (duration: 130.532226ms)","trace[114923184] 'compare' (duration: 16.76481ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:47:55.972397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.499187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:47:55.97267Z","caller":"traceutil/trace.go:171","msg":"trace[1056043963] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:927; }","duration":"141.659652ms","start":"2026-02-10T18:47:55.830819Z","end":"2026-02-10T18:47:55.972478Z","steps":["trace[1056043963] 'agreement among raft nodes before linearized reading' (duration: 141.267186ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:47:55.973152Z","caller":"traceutil/trace.go:171","msg":"trace[2146275748] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"143.573846ms","start":"2026-02-10T18:47:55.829563Z","end":"2026-02-10T18:47:55.973136Z","steps":["trace[2146275748] 'process raft request' (duration: 142.377917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:47:56.241648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.986404ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:47:56.242164Z","caller":"traceutil/trace.go:171","msg":"trace[1503604603] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"264.833577ms","start":"2026-02-10T18:47:55.977309Z","end":"2026-02-10T18:47:56.242142Z","steps":["trace[1503604603] 'process raft request' (duration: 148.211926ms)","trace[1503604603] 'compare' (duration: 115.846961ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:47:57.405636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.414178ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:47:57.406897Z","caller":"traceutil/trace.go:171","msg":"trace[1673607105] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"1.154567338s","start":"2026-02-10T18:47:56.252318Z","end":"2026-02-10T18:47:57.406886Z","steps":["trace[1673607105] 'process raft request' (duration: 361.814557ms)","trace[1673607105] 'compare' (duration: 790.070906ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:47:57.407032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:47:56.252308Z","time spent":"1.154682329s","remote":"127.0.0.1:36388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:47:57.406848Z","caller":"traceutil/trace.go:171","msg":"trace[1575043655] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:963; }","duration":"1.150905554s","start":"2026-02-10T18:47:56.255923Z","end":"2026-02-10T18:47:57.406828Z","steps":["trace[1575043655] 'read index received' (duration: 358.136452ms)","trace[1575043655] 'applied index is now lower than readState.Index' (duration: 792.767572ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:47:57.407724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.151789684s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-08e10037-c94c-4554-af9f-8dc5c16033c0\" ","response":"range_response_count:1 size:3535"} {"level":"info","ts":"2026-02-10T18:47:57.413121Z","caller":"traceutil/trace.go:171","msg":"trace[1542374106] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-08e10037-c94c-4554-af9f-8dc5c16033c0; range_end:; response_count:1; response_revision:929; }","duration":"1.157199288s","start":"2026-02-10T18:47:56.25591Z","end":"2026-02-10T18:47:57.413109Z","steps":["trace[1542374106] 'agreement among raft nodes before linearized reading' (duration: 1.151770262s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:47:57.413759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:47:56.255905Z","time spent":"1.157843977s","remote":"127.0.0.1:36388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3558,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-08e10037-c94c-4554-af9f-8dc5c16033c0\" "} {"level":"warn","ts":"2026-02-10T18:47:57.408027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.442244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-02-10T18:47:57.41393Z","caller":"traceutil/trace.go:171","msg":"trace[1649689742] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:929; }","duration":"959.378066ms","start":"2026-02-10T18:47:56.454545Z","end":"2026-02-10T18:47:57.413924Z","steps":["trace[1649689742] 'agreement among raft nodes before linearized reading' (duration: 953.365288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:47:57.413971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:47:56.454531Z","time spent":"959.433121ms","remote":"127.0.0.1:36404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":920,"request content":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" "} {"level":"warn","ts":"2026-02-10T18:47:59.290654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.970813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:47:59.29076Z","caller":"traceutil/trace.go:171","msg":"trace[903117929] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"264.194186ms","start":"2026-02-10T18:47:59.026547Z","end":"2026-02-10T18:47:59.290741Z","steps":["trace[903117929] 'process raft request' (duration: 36.064768ms)","trace[903117929] 'compare' (duration: 227.859003ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:47:59.518968Z","caller":"traceutil/trace.go:171","msg":"trace[892273392] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"321.627271ms","start":"2026-02-10T18:47:59.197325Z","end":"2026-02-10T18:47:59.518953Z","steps":["trace[892273392] 'process raft request' (duration: 266.716725ms)","trace[892273392] 'compare' (duration: 54.695776ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:47:59.5192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:47:59.197303Z","time spent":"321.795476ms","remote":"127.0.0.1:36298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":871,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T18:47:59.518921Z","caller":"traceutil/trace.go:171","msg":"trace[1549643188] linearizableReadLoop","detail":"{readStateIndex:974; appliedIndex:973; }","duration":"312.259498ms","start":"2026-02-10T18:47:59.206638Z","end":"2026-02-10T18:47:59.518898Z","steps":["trace[1549643188] 'read index received' (duration: 257.32974ms)","trace[1549643188] 'applied index is now lower than readState.Index' (duration: 54.928688ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:47:59.519874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.198723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:47:59.520927Z","caller":"traceutil/trace.go:171","msg":"trace[938513733] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:938; }","duration":"314.324655ms","start":"2026-02-10T18:47:59.206587Z","end":"2026-02-10T18:47:59.520911Z","steps":["trace[938513733] 'agreement among raft nodes before linearized reading' (duration: 313.191352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:47:59.521036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:47:59.206567Z","time spent":"314.456388ms","remote":"127.0.0.1:36214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:47:59.520082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.093734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-10T18:47:59.521228Z","caller":"traceutil/trace.go:171","msg":"trace[150864969] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:938; }","duration":"228.233107ms","start":"2026-02-10T18:47:59.292914Z","end":"2026-02-10T18:47:59.521147Z","steps":["trace[150864969] 'agreement among raft nodes before linearized reading' (duration: 227.087483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:47:59.845782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.726969ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:47:59.84639Z","caller":"traceutil/trace.go:171","msg":"trace[664546424] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"314.661035ms","start":"2026-02-10T18:47:59.531711Z","end":"2026-02-10T18:47:59.846372Z","steps":["trace[664546424] 'process raft request' (duration: 314.528943ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:47:59.846546Z","caller":"traceutil/trace.go:171","msg":"trace[587003437] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"315.765615ms","start":"2026-02-10T18:47:59.530754Z","end":"2026-02-10T18:47:59.846519Z","steps":["trace[587003437] 'process raft request' (duration: 208.229233ms)","trace[587003437] 'compare' (duration: 106.63264ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:47:59.846697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:47:59.530743Z","time spent":"315.881876ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:47:59.846948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:47:59.531705Z","time spent":"314.871935ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:48:00.174007Z","caller":"traceutil/trace.go:171","msg":"trace[1777416080] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"141.402708ms","start":"2026-02-10T18:48:00.03258Z","end":"2026-02-10T18:48:00.173983Z","steps":["trace[1777416080] 'process raft request' (duration: 141.215051ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:48:01.694044Z","caller":"traceutil/trace.go:171","msg":"trace[1935931741] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"100.900202ms","start":"2026-02-10T18:48:01.593123Z","end":"2026-02-10T18:48:01.694023Z","steps":["trace[1935931741] 'process raft request' (duration: 100.721496ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:48:01.862754Z","caller":"traceutil/trace.go:171","msg":"trace[1149508179] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"164.108752ms","start":"2026-02-10T18:48:01.69863Z","end":"2026-02-10T18:48:01.862739Z","steps":["trace[1149508179] 'process raft request' (duration: 164.036936ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:48:01.876716Z","caller":"traceutil/trace.go:171","msg":"trace[1530092206] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"174.775144ms","start":"2026-02-10T18:48:01.701928Z","end":"2026-02-10T18:48:01.876703Z","steps":["trace[1530092206] 'process raft request' (duration: 174.563364ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:48:03.518072Z","caller":"traceutil/trace.go:171","msg":"trace[519440485] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"156.069291ms","start":"2026-02-10T18:48:03.361981Z","end":"2026-02-10T18:48:03.51805Z","steps":["trace[519440485] 'process raft request' (duration: 155.916847ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:04.38286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.297525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-10T18:48:04.383541Z","caller":"traceutil/trace.go:171","msg":"trace[1662906139] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:954; }","duration":"517.01718ms","start":"2026-02-10T18:48:03.866497Z","end":"2026-02-10T18:48:04.383514Z","steps":["trace[1662906139] 'range keys from in-memory index tree' (duration: 516.112849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:04.383783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:03.866476Z","time spent":"517.287095ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-10T18:48:04.384256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.029415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-10T18:48:04.38454Z","caller":"traceutil/trace.go:171","msg":"trace[244671661] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:954; }","duration":"483.333043ms","start":"2026-02-10T18:48:03.901184Z","end":"2026-02-10T18:48:04.384517Z","steps":["trace[244671661] 'range keys from in-memory index tree' (duration: 482.917015ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:04.384743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:03.90117Z","time spent":"483.555664ms","remote":"127.0.0.1:36464","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-10T18:48:04.385373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.358214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-10T18:48:04.385581Z","caller":"traceutil/trace.go:171","msg":"trace[433290804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:954; }","duration":"482.568683ms","start":"2026-02-10T18:48:03.902997Z","end":"2026-02-10T18:48:04.385566Z","steps":["trace[433290804] 'range keys from in-memory index tree' (duration: 482.232533ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:04.385788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:03.902965Z","time spent":"482.809105ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-10T18:48:04.440159Z","caller":"traceutil/trace.go:171","msg":"trace[2109084897] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"369.715837ms","start":"2026-02-10T18:48:04.070422Z","end":"2026-02-10T18:48:04.440138Z","steps":["trace[2109084897] 'read index received' (duration: 369.579965ms)","trace[2109084897] 'applied index is now lower than readState.Index' (duration: 134.822µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:48:04.440455Z","caller":"traceutil/trace.go:171","msg":"trace[1544079430] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"436.906644ms","start":"2026-02-10T18:48:04.003537Z","end":"2026-02-10T18:48:04.440444Z","steps":["trace[1544079430] 'process raft request' (duration: 436.468234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:04.441305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:04.003519Z","time spent":"437.719699ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:48:04.440738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.301071ms","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-10T18:48:04.441519Z","caller":"traceutil/trace.go:171","msg":"trace[1688050033] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:955; }","duration":"371.110414ms","start":"2026-02-10T18:48:04.070383Z","end":"2026-02-10T18:48:04.441493Z","steps":["trace[1688050033] 'agreement among raft nodes before linearized reading' (duration: 370.124204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:04.441688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:04.070368Z","time spent":"371.239186ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-10T18:48:04.644725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.611017ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-10T18:48:04.645291Z","caller":"traceutil/trace.go:171","msg":"trace[954762254] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"254.759552ms","start":"2026-02-10T18:48:04.390518Z","end":"2026-02-10T18:48:04.645277Z","steps":["trace[954762254] 'process raft request' (duration: 254.29755ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:04.763693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.339553ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:48:04.763904Z","caller":"traceutil/trace.go:171","msg":"trace[548877962] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"316.922081ms","start":"2026-02-10T18:48:04.446966Z","end":"2026-02-10T18:48:04.763888Z","steps":["trace[548877962] 'process raft request' (duration: 316.884168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:04.763966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:04.446944Z","time spent":"316.992167ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:48:04.76423Z","caller":"traceutil/trace.go:171","msg":"trace[1777308180] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"372.001085ms","start":"2026-02-10T18:48:04.392216Z","end":"2026-02-10T18:48:04.764217Z","steps":["trace[1777308180] 'process raft request' (duration: 253.054637ms)","trace[1777308180] 'compare' (duration: 117.817205ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:48:04.764287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:04.392204Z","time spent":"372.05336ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:48:04.764404Z","caller":"traceutil/trace.go:171","msg":"trace[740697940] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"370.441053ms","start":"2026-02-10T18:48:04.393953Z","end":"2026-02-10T18:48:04.764394Z","steps":["trace[740697940] 'process raft request' (duration: 369.842159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:04.76445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:04.393941Z","time spent":"370.483777ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:48:04.764651Z","caller":"traceutil/trace.go:171","msg":"trace[1014871129] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:991; }","duration":"324.221115ms","start":"2026-02-10T18:48:04.440416Z","end":"2026-02-10T18:48:04.764637Z","steps":["trace[1014871129] 'read index received' (duration: 29.032683ms)","trace[1014871129] 'applied index is now lower than readState.Index' (duration: 295.186612ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:48:04.764905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.845132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:48:04.764936Z","caller":"traceutil/trace.go:171","msg":"trace[1829300929] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:959; }","duration":"555.906347ms","start":"2026-02-10T18:48:04.209019Z","end":"2026-02-10T18:48:04.764926Z","steps":["trace[1829300929] 'agreement among raft nodes before linearized reading' (duration: 555.850732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:04.764964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:04.208996Z","time spent":"555.962172ms","remote":"127.0.0.1:36214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:48:04.76517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.157223ms","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-10T18:48:04.7652Z","caller":"traceutil/trace.go:171","msg":"trace[2079302651] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:959; }","duration":"578.184245ms","start":"2026-02-10T18:48:04.187005Z","end":"2026-02-10T18:48:04.76519Z","steps":["trace[2079302651] 'agreement among raft nodes before linearized reading' (duration: 578.086817ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:04.765225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:04.186954Z","time spent":"578.265182ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-10T18:48:04.765363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.902039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T18:48:04.76539Z","caller":"traceutil/trace.go:171","msg":"trace[1839444767] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:959; }","duration":"316.950494ms","start":"2026-02-10T18:48:04.44843Z","end":"2026-02-10T18:48:04.765381Z","steps":["trace[1839444767] 'agreement among raft nodes before linearized reading' (duration: 316.90557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:04.765416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:04.448422Z","time spent":"316.986847ms","remote":"127.0.0.1:36588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-10T18:48:04.765768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.856537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-08e10037-c94c-4554-af9f-8dc5c16033c0\" ","response":"range_response_count:1 size:3825"} {"level":"info","ts":"2026-02-10T18:48:04.765802Z","caller":"traceutil/trace.go:171","msg":"trace[226241482] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-08e10037-c94c-4554-af9f-8dc5c16033c0; range_end:; response_count:1; response_revision:959; }","duration":"212.912502ms","start":"2026-02-10T18:48:04.55288Z","end":"2026-02-10T18:48:04.765792Z","steps":["trace[226241482] 'agreement among raft nodes before linearized reading' (duration: 212.812343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:04.765981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.10532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/local-path-storage/helper-pod-create-pvc-08e10037-c94c-4554-af9f-8dc5c16033c0\" ","response":"range_response_count:1 size:2247"} {"level":"info","ts":"2026-02-10T18:48:04.766009Z","caller":"traceutil/trace.go:171","msg":"trace[493075181] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/local-path-storage/helper-pod-create-pvc-08e10037-c94c-4554-af9f-8dc5c16033c0; range_end:; response_count:1; response_revision:959; }","duration":"252.170326ms","start":"2026-02-10T18:48:04.51383Z","end":"2026-02-10T18:48:04.766Z","steps":["trace[493075181] 'agreement among raft nodes before linearized reading' (duration: 252.091979ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:05.769358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.46698ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:48:05.769452Z","caller":"traceutil/trace.go:171","msg":"trace[23058262] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:997; }","duration":"750.059904ms","start":"2026-02-10T18:48:05.019376Z","end":"2026-02-10T18:48:05.769436Z","steps":["trace[23058262] 'read index received' (duration: 484.372874ms)","trace[23058262] 'applied index is now lower than readState.Index' (duration: 265.68579ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:48:05.76963Z","caller":"traceutil/trace.go:171","msg":"trace[264962088] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"992.357441ms","start":"2026-02-10T18:48:04.777261Z","end":"2026-02-10T18:48:05.769619Z","steps":["trace[264962088] 'process raft request' (duration: 726.56212ms)","trace[264962088] 'compare' (duration: 265.358141ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:48:05.769702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:04.777249Z","time spent":"992.422797ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:48:05.769994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.620025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/test-pvc.1892f7158afaa525\" ","response":"range_response_count:1 size:915"} {"level":"info","ts":"2026-02-10T18:48:05.770033Z","caller":"traceutil/trace.go:171","msg":"trace[1624611330] range","detail":"{range_begin:/registry/events/default/test-pvc.1892f7158afaa525; range_end:; response_count:1; response_revision:961; }","duration":"750.68522ms","start":"2026-02-10T18:48:05.019337Z","end":"2026-02-10T18:48:05.770023Z","steps":["trace[1624611330] 'agreement among raft nodes before linearized reading' (duration: 750.56687ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:05.770063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:05.019322Z","time spent":"750.734245ms","remote":"127.0.0.1:36298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":938,"request content":"key:\"/registry/events/default/test-pvc.1892f7158afaa525\" "} {"level":"warn","ts":"2026-02-10T18:48:05.770235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.984737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-08e10037-c94c-4554-af9f-8dc5c16033c0\" ","response":"range_response_count:1 size:3825"} {"level":"info","ts":"2026-02-10T18:48:05.77027Z","caller":"traceutil/trace.go:171","msg":"trace[1635127738] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-08e10037-c94c-4554-af9f-8dc5c16033c0; range_end:; response_count:1; response_revision:961; }","duration":"651.054373ms","start":"2026-02-10T18:48:05.119206Z","end":"2026-02-10T18:48:05.77026Z","steps":["trace[1635127738] 'agreement among raft nodes before linearized reading' (duration: 650.972465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:05.770302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:05.119186Z","time spent":"651.109668ms","remote":"127.0.0.1:36388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3848,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-08e10037-c94c-4554-af9f-8dc5c16033c0\" "} {"level":"warn","ts":"2026-02-10T18:48:05.77054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.831468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1490"} {"level":"info","ts":"2026-02-10T18:48:05.770573Z","caller":"traceutil/trace.go:171","msg":"trace[1143366004] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:961; }","duration":"388.896744ms","start":"2026-02-10T18:48:05.381668Z","end":"2026-02-10T18:48:05.770565Z","steps":["trace[1143366004] 'agreement among raft nodes before linearized reading' (duration: 388.809816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:05.770599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:05.381649Z","time spent":"388.944288ms","remote":"127.0.0.1:36388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":1513,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"info","ts":"2026-02-10T18:48:05.974874Z","caller":"traceutil/trace.go:171","msg":"trace[683463320] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"194.279021ms","start":"2026-02-10T18:48:05.780561Z","end":"2026-02-10T18:48:05.97484Z","steps":["trace[683463320] 'process raft request' (duration: 177.301936ms)","trace[683463320] 'compare' (duration: 16.850794ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:48:06.484683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.793512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:48:06.48476Z","caller":"traceutil/trace.go:171","msg":"trace[1568127207] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:963; }","duration":"273.951296ms","start":"2026-02-10T18:48:06.210791Z","end":"2026-02-10T18:48:06.484742Z","steps":["trace[1568127207] 'range keys from in-memory index tree' (duration: 273.683632ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:48:06.989628Z","caller":"traceutil/trace.go:171","msg":"trace[1238961803] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1002; }","duration":"123.632529ms","start":"2026-02-10T18:48:06.865978Z","end":"2026-02-10T18:48:06.98961Z","steps":["trace[1238961803] 'read index received' (duration: 23.551303ms)","trace[1238961803] 'applied index is now lower than readState.Index' (duration: 100.075395ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:48:06.989782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.787533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-08e10037-c94c-4554-af9f-8dc5c16033c0\" ","response":"range_response_count:1 size:3827"} {"level":"info","ts":"2026-02-10T18:48:06.989807Z","caller":"traceutil/trace.go:171","msg":"trace[307375054] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-08e10037-c94c-4554-af9f-8dc5c16033c0; range_end:; response_count:1; response_revision:967; }","duration":"123.846458ms","start":"2026-02-10T18:48:06.865953Z","end":"2026-02-10T18:48:06.989799Z","steps":["trace[307375054] 'agreement among raft nodes before linearized reading' (duration: 123.722347ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:48:06.990016Z","caller":"traceutil/trace.go:171","msg":"trace[978048232] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"129.484101ms","start":"2026-02-10T18:48:06.860524Z","end":"2026-02-10T18:48:06.990008Z","steps":["trace[978048232] 'process raft request' (duration: 120.391384ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:48:07.218173Z","caller":"traceutil/trace.go:171","msg":"trace[509657935] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"141.492771ms","start":"2026-02-10T18:48:07.076661Z","end":"2026-02-10T18:48:07.218153Z","steps":["trace[509657935] 'process raft request' (duration: 66.799096ms)","trace[509657935] 'compare' (duration: 74.495727ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:48:08.964978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.910301ms","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-10T18:48:08.965078Z","caller":"traceutil/trace.go:171","msg":"trace[343614821] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:982; }","duration":"106.067136ms","start":"2026-02-10T18:48:08.858988Z","end":"2026-02-10T18:48:08.965055Z","steps":["trace[343614821] 'range keys from in-memory index tree' (duration: 105.662859ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:48:11.167932Z","caller":"traceutil/trace.go:171","msg":"trace[1024758969] linearizableReadLoop","detail":"{readStateIndex:1027; appliedIndex:1026; }","duration":"385.319973ms","start":"2026-02-10T18:48:10.782597Z","end":"2026-02-10T18:48:11.167917Z","steps":["trace[1024758969] 'read index received' (duration: 385.182681ms)","trace[1024758969] 'applied index is now lower than readState.Index' (duration: 136.782µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:48:11.168021Z","caller":"traceutil/trace.go:171","msg":"trace[785481917] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"433.625354ms","start":"2026-02-10T18:48:10.734372Z","end":"2026-02-10T18:48:11.167997Z","steps":["trace[785481917] 'process raft request' (duration: 433.450209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:11.168107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.463907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2476"} {"level":"info","ts":"2026-02-10T18:48:11.168127Z","caller":"traceutil/trace.go:171","msg":"trace[2123505904] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:988; }","duration":"385.564286ms","start":"2026-02-10T18:48:10.782557Z","end":"2026-02-10T18:48:11.168122Z","steps":["trace[2123505904] 'agreement among raft nodes before linearized reading' (duration: 385.434024ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:11.168145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:10.782539Z","time spent":"385.601839ms","remote":"127.0.0.1:36388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":2499,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"warn","ts":"2026-02-10T18:48:11.168148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:10.734356Z","time spent":"433.718653ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:48:11.168294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.967411ms","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-10T18:48:11.168307Z","caller":"traceutil/trace.go:171","msg":"trace[547901316] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:988; }","duration":"152.012385ms","start":"2026-02-10T18:48:11.016291Z","end":"2026-02-10T18:48:11.168303Z","steps":["trace[547901316] 'agreement among raft nodes before linearized reading' (duration: 151.971732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:11.168559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.762073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-10T18:48:11.168579Z","caller":"traceutil/trace.go:171","msg":"trace[617083593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:988; }","duration":"129.811828ms","start":"2026-02-10T18:48:11.038761Z","end":"2026-02-10T18:48:11.168573Z","steps":["trace[617083593] 'agreement among raft nodes before linearized reading' (duration: 129.736231ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:18.059303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.226331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:48:18.059592Z","caller":"traceutil/trace.go:171","msg":"trace[286655639] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1010; }","duration":"851.555581ms","start":"2026-02-10T18:48:17.208019Z","end":"2026-02-10T18:48:18.059575Z","steps":["trace[286655639] 'range keys from in-memory index tree' (duration: 851.080978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:18.059695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:17.208004Z","time spent":"851.680622ms","remote":"127.0.0.1:36214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:48:18.060555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.989947ms","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-10T18:48:18.060618Z","caller":"traceutil/trace.go:171","msg":"trace[2097682395] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1010; }","duration":"178.095776ms","start":"2026-02-10T18:48:17.882506Z","end":"2026-02-10T18:48:18.060602Z","steps":["trace[2097682395] 'range keys from in-memory index tree' (duration: 177.80822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:18.060744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.489882ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:48:18.060789Z","caller":"traceutil/trace.go:171","msg":"trace[1109041028] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1010; }","duration":"151.538966ms","start":"2026-02-10T18:48:17.909244Z","end":"2026-02-10T18:48:18.060783Z","steps":["trace[1109041028] 'range keys from in-memory index tree' (duration: 151.48315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:18.061541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.195455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-10T18:48:18.061646Z","caller":"traceutil/trace.go:171","msg":"trace[1920764214] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1010; }","duration":"846.329307ms","start":"2026-02-10T18:48:17.215305Z","end":"2026-02-10T18:48:18.061635Z","steps":["trace[1920764214] 'range keys from in-memory index tree' (duration: 844.530073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:18.061772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:17.215288Z","time spent":"846.47382ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-10T18:48:18.062027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.311825ms","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-10T18:48:18.06206Z","caller":"traceutil/trace.go:171","msg":"trace[1535800178] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1010; }","duration":"845.349518ms","start":"2026-02-10T18:48:17.216703Z","end":"2026-02-10T18:48:18.062052Z","steps":["trace[1535800178] 'range keys from in-memory index tree' (duration: 845.234518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:18.062083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:17.21667Z","time spent":"845.406833ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-10T18:48:18.064648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.128473ms","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-10T18:48:18.064711Z","caller":"traceutil/trace.go:171","msg":"trace[1695468454] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1010; }","duration":"813.219342ms","start":"2026-02-10T18:48:17.251483Z","end":"2026-02-10T18:48:18.064702Z","steps":["trace[1695468454] 'range keys from in-memory index tree' (duration: 813.044266ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:18.064773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:17.251478Z","time spent":"813.287217ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T18:48:18.064884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.274837ms","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-10T18:48:18.064924Z","caller":"traceutil/trace.go:171","msg":"trace[1830320629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1010; }","duration":"813.324741ms","start":"2026-02-10T18:48:17.251593Z","end":"2026-02-10T18:48:18.064918Z","steps":["trace[1830320629] 'range keys from in-memory index tree' (duration: 813.219401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:18.064951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:17.251391Z","time spent":"813.554812ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T18:48:18.06514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.042784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:48:18.065173Z","caller":"traceutil/trace.go:171","msg":"trace[965447277] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1010; }","duration":"593.097758ms","start":"2026-02-10T18:48:17.472064Z","end":"2026-02-10T18:48:18.065162Z","steps":["trace[965447277] 'count revisions from in-memory index tree' (duration: 592.995429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:48:18.0652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:48:17.472051Z","time spent":"593.141923ms","remote":"127.0.0.1:51096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "}