{"level":"warn","ts":"2026-02-06T00:42:49.723412Z","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-06T00:42:49.724964Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.233:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.233:2380","--initial-cluster=instance=https://199.204.45.233:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.233:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.233: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-06T00:42:49.725905Z","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-06T00:42:49.725963Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-02-06T00:42:49.726063Z","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-06T00:42:49.729961Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"]} {"level":"info","ts":"2026-02-06T00:42:49.730997Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.233: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-06T00:42:49.747798Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.639459ms"} {"level":"info","ts":"2026-02-06T00:42:49.785071Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"90a0cb2044183f95","cluster-id":"782fef34d5ea94f9"} {"level":"info","ts":"2026-02-06T00:42:49.785148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=()"} {"level":"info","ts":"2026-02-06T00:42:49.785204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 0"} {"level":"info","ts":"2026-02-06T00:42:49.785221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 90a0cb2044183f95 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-06T00:42:49.785228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 1"} {"level":"info","ts":"2026-02-06T00:42:49.785269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"warn","ts":"2026-02-06T00:42:49.796203Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-06T00:42:49.799896Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-06T00:42:49.808496Z","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-06T00:42:49.811438Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"90a0cb2044183f95","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-06T00:42:49.828994Z","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-06T00:42:49.829395Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"90a0cb2044183f95","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-06T00:42:49.829514Z","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-06T00:42:49.837634Z","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-06T00:42:49.837701Z","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-06T00:42:49.837991Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"90a0cb2044183f95","initial-advertise-peer-urls":["https://199.204.45.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-06T00:42:49.838067Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-06T00:42:49.82976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"info","ts":"2026-02-06T00:42:49.829812Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-02-06T00:42:49.838143Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-02-06T00:42:49.838829Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","added-peer-id":"90a0cb2044183f95","added-peer-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-02-06T00:42:50.086284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 is starting a new election at term 1"} {"level":"info","ts":"2026-02-06T00:42:50.086322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-06T00:42:50.086348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgPreVoteResp from 90a0cb2044183f95 at term 1"} {"level":"info","ts":"2026-02-06T00:42:50.08636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became candidate at term 2"} {"level":"info","ts":"2026-02-06T00:42:50.086365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgVoteResp from 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-02-06T00:42:50.086373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became leader at term 2"} {"level":"info","ts":"2026-02-06T00:42:50.086384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 90a0cb2044183f95 elected leader 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-02-06T00:42:50.089819Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T00:42:50.090932Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"90a0cb2044183f95","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.233:2379]}","request-path":"/0/members/90a0cb2044183f95/attributes","cluster-id":"782fef34d5ea94f9","publish-timeout":"7s"} {"level":"info","ts":"2026-02-06T00:42:50.091076Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-06T00:42:50.09128Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-06T00:42:50.091809Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-06T00:42:50.091868Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-06T00:42:50.099554Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.233:2379"} {"level":"info","ts":"2026-02-06T00:42:50.102514Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-06T00:42:50.104599Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T00:42:50.104657Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T00:42:50.104674Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T00:42:52.727084Z","caller":"traceutil/trace.go:171","msg":"trace[1858963300] transaction","detail":"{read_only:false; response_revision:67; number_of_response:1; }","duration":"167.238057ms","start":"2026-02-06T00:42:52.559829Z","end":"2026-02-06T00:42:52.727067Z","steps":["trace[1858963300] 'process raft request' (duration: 167.204066ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:42:52.727465Z","caller":"traceutil/trace.go:171","msg":"trace[1675416501] transaction","detail":"{read_only:false; response_revision:66; number_of_response:1; }","duration":"180.84068ms","start":"2026-02-06T00:42:52.546613Z","end":"2026-02-06T00:42:52.727453Z","steps":["trace[1675416501] 'process raft request' (duration: 84.535346ms)","trace[1675416501] 'compare' (duration: 95.782972ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:42:53.532984Z","caller":"traceutil/trace.go:171","msg":"trace[2101311223] transaction","detail":"{read_only:false; response_revision:105; number_of_response:1; }","duration":"102.179444ms","start":"2026-02-06T00:42:53.430784Z","end":"2026-02-06T00:42:53.532964Z","steps":["trace[2101311223] 'process raft request' (duration: 102.070342ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:42:54.003767Z","caller":"traceutil/trace.go:171","msg":"trace[207610581] transaction","detail":"{read_only:false; response_revision:121; number_of_response:1; }","duration":"155.443257ms","start":"2026-02-06T00:42:53.848304Z","end":"2026-02-06T00:42:54.003747Z","steps":["trace[207610581] 'process raft request' (duration: 58.406025ms)","trace[207610581] 'compare' (duration: 96.94885ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:42:54.235245Z","caller":"traceutil/trace.go:171","msg":"trace[375122444] linearizableReadLoop","detail":"{readStateIndex:129; appliedIndex:128; }","duration":"116.943424ms","start":"2026-02-06T00:42:54.118278Z","end":"2026-02-06T00:42:54.235221Z","steps":["trace[375122444] 'read index received' (duration: 116.743589ms)","trace[375122444] 'applied index is now lower than readState.Index' (duration: 198.855µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:42:54.235573Z","caller":"traceutil/trace.go:171","msg":"trace[625671244] transaction","detail":"{read_only:false; response_revision:125; number_of_response:1; }","duration":"137.720057ms","start":"2026-02-06T00:42:54.097828Z","end":"2026-02-06T00:42:54.235548Z","steps":["trace[625671244] 'process raft request' (duration: 137.260406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:54.235979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.348194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-06T00:42:54.236343Z","caller":"traceutil/trace.go:171","msg":"trace[983273753] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:125; }","duration":"118.072521ms","start":"2026-02-06T00:42:54.118254Z","end":"2026-02-06T00:42:54.236326Z","steps":["trace[983273753] 'agreement among raft nodes before linearized reading' (duration: 117.1726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:55.315983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.174243ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:42:55.316375Z","caller":"traceutil/trace.go:171","msg":"trace[1269277455] linearizableReadLoop","detail":"{readStateIndex:194; appliedIndex:193; }","duration":"197.057725ms","start":"2026-02-06T00:42:55.119303Z","end":"2026-02-06T00:42:55.316361Z","steps":["trace[1269277455] 'read index received' (duration: 89.028812ms)","trace[1269277455] 'applied index is now lower than readState.Index' (duration: 108.027523ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:42:55.316598Z","caller":"traceutil/trace.go:171","msg":"trace[1636235511] transaction","detail":"{read_only:false; response_revision:190; number_of_response:1; }","duration":"209.54249ms","start":"2026-02-06T00:42:55.107044Z","end":"2026-02-06T00:42:55.316586Z","steps":["trace[1636235511] 'process raft request' (duration: 101.467997ms)","trace[1636235511] 'compare' (duration: 107.07074ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:42:55.316677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.365092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:42:55.317216Z","caller":"traceutil/trace.go:171","msg":"trace[697557173] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:190; }","duration":"197.926515ms","start":"2026-02-06T00:42:55.119281Z","end":"2026-02-06T00:42:55.317208Z","steps":["trace[697557173] 'agreement among raft nodes before linearized reading' (duration: 197.344131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:55.546055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.350316ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:42:55.546155Z","caller":"traceutil/trace.go:171","msg":"trace[1624679164] transaction","detail":"{read_only:false; response_revision:195; number_of_response:1; }","duration":"147.358646ms","start":"2026-02-06T00:42:55.398778Z","end":"2026-02-06T00:42:55.546137Z","steps":["trace[1624679164] 'process raft request' (duration: 23.857286ms)","trace[1624679164] 'compare' (duration: 123.227673ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:43:00.716736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.473647ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:43:00.717293Z","caller":"traceutil/trace.go:171","msg":"trace[1735717370] linearizableReadLoop","detail":"{readStateIndex:322; appliedIndex:321; }","duration":"163.066358ms","start":"2026-02-06T00:43:00.554212Z","end":"2026-02-06T00:43:00.717278Z","steps":["trace[1735717370] 'read index received' (duration: 17.948626ms)","trace[1735717370] 'applied index is now lower than readState.Index' (duration: 145.116492ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:43:00.717457Z","caller":"traceutil/trace.go:171","msg":"trace[1436176353] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"189.831673ms","start":"2026-02-06T00:43:00.527617Z","end":"2026-02-06T00:43:00.717448Z","steps":["trace[1436176353] 'process raft request' (duration: 44.598678ms)","trace[1436176353] 'compare' (duration: 144.360754ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:43:00.717714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.533969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-06T00:43:00.717802Z","caller":"traceutil/trace.go:171","msg":"trace[1619166000] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:313; }","duration":"163.657922ms","start":"2026-02-06T00:43:00.554136Z","end":"2026-02-06T00:43:00.717794Z","steps":["trace[1619166000] 'agreement among raft nodes before linearized reading' (duration: 163.517129ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:00.718014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.092913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-06T00:43:00.718064Z","caller":"traceutil/trace.go:171","msg":"trace[1151053319] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:313; }","duration":"105.180425ms","start":"2026-02-06T00:43:00.612876Z","end":"2026-02-06T00:43:00.718057Z","steps":["trace[1151053319] 'agreement among raft nodes before linearized reading' (duration: 105.109524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:01.25382Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581739927616502764,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T00:43:01.740572Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.005092242s","expected-duration":"1s"} {"level":"info","ts":"2026-02-06T00:43:01.742592Z","caller":"traceutil/trace.go:171","msg":"trace[1766612484] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"1.017894766s","start":"2026-02-06T00:43:00.724673Z","end":"2026-02-06T00:43:01.742568Z","steps":["trace[1766612484] 'process raft request' (duration: 1.016216566s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:01.742696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:00.724658Z","time spent":"1.017986378s","remote":"127.0.0.1:39140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-06T00:43:01.773864Z","caller":"traceutil/trace.go:171","msg":"trace[1784714081] linearizableReadLoop","detail":"{readStateIndex:324; appliedIndex:322; }","duration":"1.020405655s","start":"2026-02-06T00:43:00.753393Z","end":"2026-02-06T00:43:01.773798Z","steps":["trace[1784714081] 'read index received' (duration: 987.450263ms)","trace[1784714081] 'applied index is now lower than readState.Index' (duration: 32.954302ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:43:01.773944Z","caller":"traceutil/trace.go:171","msg":"trace[559757370] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"1.049111497s","start":"2026-02-06T00:43:00.7248Z","end":"2026-02-06T00:43:01.773911Z","steps":["trace[559757370] 'process raft request' (duration: 1.048899812s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:01.774051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.020646011s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-02-06T00:43:01.774062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:00.724795Z","time spent":"1.049208109s","remote":"127.0.0.1:39178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-06T00:43:01.774088Z","caller":"traceutil/trace.go:171","msg":"trace[780093525] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:315; }","duration":"1.020728972s","start":"2026-02-06T00:43:00.753347Z","end":"2026-02-06T00:43:01.774076Z","steps":["trace[780093525] 'agreement among raft nodes before linearized reading' (duration: 1.02061728s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:01.774123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:00.753301Z","time spent":"1.020815054s","remote":"127.0.0.1:39178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-02-06T00:43:01.774252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.239278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-02-06T00:43:01.774471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.303855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7237"} {"level":"info","ts":"2026-02-06T00:43:01.774519Z","caller":"traceutil/trace.go:171","msg":"trace[1159955830] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:315; }","duration":"865.358977ms","start":"2026-02-06T00:43:00.909149Z","end":"2026-02-06T00:43:01.774508Z","steps":["trace[1159955830] 'agreement among raft nodes before linearized reading' (duration: 865.255414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:01.774554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:00.909129Z","time spent":"865.419039ms","remote":"127.0.0.1:39162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7260,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-02-06T00:43:01.774725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.041583ms","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-06T00:43:01.7748Z","caller":"traceutil/trace.go:171","msg":"trace[66597689] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:315; }","duration":"522.120134ms","start":"2026-02-06T00:43:01.252671Z","end":"2026-02-06T00:43:01.774792Z","steps":["trace[66597689] 'agreement among raft nodes before linearized reading' (duration: 522.030432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:01.776728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.952867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-06T00:43:01.776775Z","caller":"traceutil/trace.go:171","msg":"trace[998099995] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:315; }","duration":"623.037089ms","start":"2026-02-06T00:43:01.153727Z","end":"2026-02-06T00:43:01.776764Z","steps":["trace[998099995] 'agreement among raft nodes before linearized reading' (duration: 622.954017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:01.776815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:01.153715Z","time spent":"623.09016ms","remote":"127.0.0.1:39178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-02-06T00:43:01.776972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.451645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-06T00:43:01.777005Z","caller":"traceutil/trace.go:171","msg":"trace[1123774715] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:315; }","duration":"673.507866ms","start":"2026-02-06T00:43:01.103489Z","end":"2026-02-06T00:43:01.776997Z","steps":["trace[1123774715] 'agreement among raft nodes before linearized reading' (duration: 673.453924ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:01.777031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:01.103478Z","time spent":"673.547317ms","remote":"127.0.0.1:39178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-02-06T00:43:01.77715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.396539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-06T00:43:01.777182Z","caller":"traceutil/trace.go:171","msg":"trace[361584543] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:315; }","duration":"723.452111ms","start":"2026-02-06T00:43:01.053722Z","end":"2026-02-06T00:43:01.777174Z","steps":["trace[361584543] 'agreement among raft nodes before linearized reading' (duration: 723.40265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:01.777207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:01.053657Z","time spent":"723.544473ms","remote":"127.0.0.1:39178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-02-06T00:43:01.777351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.224411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-06T00:43:01.777379Z","caller":"traceutil/trace.go:171","msg":"trace[244794789] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:315; }","duration":"773.312524ms","start":"2026-02-06T00:43:01.004058Z","end":"2026-02-06T00:43:01.777371Z","steps":["trace[244794789] 'agreement among raft nodes before linearized reading' (duration: 773.231112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:01.777411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:01.004048Z","time spent":"773.357124ms","remote":"127.0.0.1:39178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-02-06T00:43:01.777525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.991622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-02-06T00:43:01.777555Z","caller":"traceutil/trace.go:171","msg":"trace[1828330406] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:315; }","duration":"823.053914ms","start":"2026-02-06T00:43:00.954494Z","end":"2026-02-06T00:43:01.777548Z","steps":["trace[1828330406] 'agreement among raft nodes before linearized reading' (duration: 823.008103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:01.777579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:00.954483Z","time spent":"823.091404ms","remote":"127.0.0.1:39178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-02-06T00:43:01.778121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.193768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4597"} {"level":"info","ts":"2026-02-06T00:43:01.77816Z","caller":"traceutil/trace.go:171","msg":"trace[1328004286] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:315; }","duration":"869.246949ms","start":"2026-02-06T00:43:00.908905Z","end":"2026-02-06T00:43:01.778151Z","steps":["trace[1328004286] 'agreement among raft nodes before linearized reading' (duration: 869.178838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:01.778187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:00.908899Z","time spent":"869.28167ms","remote":"127.0.0.1:39162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4620,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-02-06T00:43:01.778311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.220589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5270"} {"level":"info","ts":"2026-02-06T00:43:01.778343Z","caller":"traceutil/trace.go:171","msg":"trace[1682174252] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:315; }","duration":"869.26286ms","start":"2026-02-06T00:43:00.909073Z","end":"2026-02-06T00:43:01.778336Z","steps":["trace[1682174252] 'agreement among raft nodes before linearized reading' (duration: 869.214338ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:01.778372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:00.909068Z","time spent":"869.29866ms","remote":"127.0.0.1:39162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5293,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-02-06T00:43:01.778489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.428714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-06T00:43:01.77852Z","caller":"traceutil/trace.go:171","msg":"trace[1923695182] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:315; }","duration":"869.474805ms","start":"2026-02-06T00:43:00.909038Z","end":"2026-02-06T00:43:01.778513Z","steps":["trace[1923695182] 'agreement among raft nodes before linearized reading' (duration: 869.426274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:01.778546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:00.909032Z","time spent":"869.506655ms","remote":"127.0.0.1:39388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-02-06T00:43:01.778685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.737291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4068"} {"level":"info","ts":"2026-02-06T00:43:01.778718Z","caller":"traceutil/trace.go:171","msg":"trace[2071047859] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:315; }","duration":"869.780732ms","start":"2026-02-06T00:43:00.908929Z","end":"2026-02-06T00:43:01.77871Z","steps":["trace[2071047859] 'agreement among raft nodes before linearized reading' (duration: 869.713561ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:01.778743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:00.908925Z","time spent":"869.813203ms","remote":"127.0.0.1:39162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4091,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-02-06T00:43:01.77927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.118592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-06T00:43:01.779312Z","caller":"traceutil/trace.go:171","msg":"trace[734083809] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:315; }","duration":"876.186944ms","start":"2026-02-06T00:43:00.903116Z","end":"2026-02-06T00:43:01.779303Z","steps":["trace[734083809] 'agreement among raft nodes before linearized reading' (duration: 876.120313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:01.77934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:00.903103Z","time spent":"876.229815ms","remote":"127.0.0.1:39178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-02-06T00:43:01.779473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.584291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6688"} {"level":"info","ts":"2026-02-06T00:43:01.779512Z","caller":"traceutil/trace.go:171","msg":"trace[2116919842] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:315; }","duration":"870.644482ms","start":"2026-02-06T00:43:00.90886Z","end":"2026-02-06T00:43:01.779504Z","steps":["trace[2116919842] 'agreement among raft nodes before linearized reading' (duration: 870.5665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:01.779539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:00.90885Z","time spent":"870.682794ms","remote":"127.0.0.1:39162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6711,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-02-06T00:43:01.779758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.813155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-06T00:43:01.779782Z","caller":"traceutil/trace.go:171","msg":"trace[188966236] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:315; }","duration":"926.865386ms","start":"2026-02-06T00:43:00.852911Z","end":"2026-02-06T00:43:01.779777Z","steps":["trace[188966236] 'agreement among raft nodes before linearized reading' (duration: 926.819725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:01.779801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:00.852901Z","time spent":"926.895427ms","remote":"127.0.0.1:39178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"info","ts":"2026-02-06T00:43:01.774275Z","caller":"traceutil/trace.go:171","msg":"trace[1807001404] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:315; }","duration":"971.2828ms","start":"2026-02-06T00:43:00.802984Z","end":"2026-02-06T00:43:01.774267Z","steps":["trace[1807001404] 'agreement among raft nodes before linearized reading' (duration: 971.242979ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:01.781564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:00.802973Z","time spent":"978.562832ms","remote":"127.0.0.1:39178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"info","ts":"2026-02-06T00:43:02.151408Z","caller":"traceutil/trace.go:171","msg":"trace[1269427560] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"311.020918ms","start":"2026-02-06T00:43:01.840349Z","end":"2026-02-06T00:43:02.15137Z","steps":["trace[1269427560] 'process raft request' (duration: 225.253873ms)","trace[1269427560] 'compare' (duration: 85.59165ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:43:02.151743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.041992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-06T00:43:02.151791Z","caller":"traceutil/trace.go:171","msg":"trace[911730466] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:333; }","duration":"293.130903ms","start":"2026-02-06T00:43:01.858651Z","end":"2026-02-06T00:43:02.151782Z","steps":["trace[911730466] 'agreement among raft nodes before linearized reading' (duration: 292.99364ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:02.152112Z","caller":"traceutil/trace.go:171","msg":"trace[1385481174] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"293.534563ms","start":"2026-02-06T00:43:01.858566Z","end":"2026-02-06T00:43:02.152101Z","steps":["trace[1385481174] 'process raft request' (duration: 292.742414ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:02.152295Z","caller":"traceutil/trace.go:171","msg":"trace[950985171] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"291.362451ms","start":"2026-02-06T00:43:01.860924Z","end":"2026-02-06T00:43:02.152287Z","steps":["trace[950985171] 'process raft request' (duration: 290.489861ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:02.152403Z","caller":"traceutil/trace.go:171","msg":"trace[1397660518] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"286.687751ms","start":"2026-02-06T00:43:01.865708Z","end":"2026-02-06T00:43:02.152396Z","steps":["trace[1397660518] 'process raft request' (duration: 285.759239ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:02.152527Z","caller":"traceutil/trace.go:171","msg":"trace[140570542] transaction","detail":"{read_only:false; number_of_response:1; response_revision:333; }","duration":"286.756503ms","start":"2026-02-06T00:43:01.865761Z","end":"2026-02-06T00:43:02.152517Z","steps":["trace[140570542] 'process raft request' (duration: 285.741769ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:02.152643Z","caller":"traceutil/trace.go:171","msg":"trace[1305931107] transaction","detail":"{read_only:false; number_of_response:1; response_revision:333; }","duration":"286.796974ms","start":"2026-02-06T00:43:01.865838Z","end":"2026-02-06T00:43:02.152635Z","steps":["trace[1305931107] 'process raft request' (duration: 285.712168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:02.152643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:01.84033Z","time spent":"311.350226ms","remote":"127.0.0.1:39162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6805,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:43:02.151601Z","caller":"traceutil/trace.go:171","msg":"trace[1443084494] linearizableReadLoop","detail":"{readStateIndex:340; appliedIndex:335; }","duration":"292.898598ms","start":"2026-02-06T00:43:01.858684Z","end":"2026-02-06T00:43:02.151582Z","steps":["trace[1443084494] 'read index received' (duration: 7.492867ms)","trace[1443084494] 'applied index is now lower than readState.Index' (duration: 285.404771ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:43:02.174245Z","caller":"traceutil/trace.go:171","msg":"trace[1416667123] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"279.940951ms","start":"2026-02-06T00:43:01.894292Z","end":"2026-02-06T00:43:02.174233Z","steps":["trace[1416667123] 'process raft request' (duration: 279.9183ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:02.174433Z","caller":"traceutil/trace.go:171","msg":"trace[76396381] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"300.848397ms","start":"2026-02-06T00:43:01.873579Z","end":"2026-02-06T00:43:02.174427Z","steps":["trace[76396381] 'process raft request' (duration: 300.505899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:02.174487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:01.873566Z","time spent":"300.886077ms","remote":"127.0.0.1:39162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3355,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-06T00:43:02.174561Z","caller":"traceutil/trace.go:171","msg":"trace[641320786] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"294.605929ms","start":"2026-02-06T00:43:01.879949Z","end":"2026-02-06T00:43:02.174554Z","steps":["trace[641320786] 'process raft request' (duration: 294.206719ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:02.174684Z","caller":"traceutil/trace.go:171","msg":"trace[145078058] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"294.677881ms","start":"2026-02-06T00:43:01.880001Z","end":"2026-02-06T00:43:02.174679Z","steps":["trace[145078058] 'process raft request' (duration: 294.180219ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:02.175077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.586244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5270"} {"level":"info","ts":"2026-02-06T00:43:02.17511Z","caller":"traceutil/trace.go:171","msg":"trace[1411968376] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:337; }","duration":"309.635556ms","start":"2026-02-06T00:43:01.865466Z","end":"2026-02-06T00:43:02.175101Z","steps":["trace[1411968376] 'agreement among raft nodes before linearized reading' (duration: 309.575984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:02.175133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:01.865459Z","time spent":"309.669916ms","remote":"127.0.0.1:39162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5293,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"info","ts":"2026-02-06T00:43:02.323316Z","caller":"traceutil/trace.go:171","msg":"trace[1844393904] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"139.509919ms","start":"2026-02-06T00:43:02.18379Z","end":"2026-02-06T00:43:02.3233Z","steps":["trace[1844393904] 'process raft request' (duration: 67.938781ms)","trace[1844393904] 'compare' (duration: 71.023875ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:43:02.323686Z","caller":"traceutil/trace.go:171","msg":"trace[414307620] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"131.12362ms","start":"2026-02-06T00:43:02.192549Z","end":"2026-02-06T00:43:02.323673Z","steps":["trace[414307620] 'process raft request' (duration: 130.417963ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:02.324104Z","caller":"traceutil/trace.go:171","msg":"trace[29361620] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"138.967297ms","start":"2026-02-06T00:43:02.18512Z","end":"2026-02-06T00:43:02.324087Z","steps":["trace[29361620] 'process raft request' (duration: 137.720007ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:02.324445Z","caller":"traceutil/trace.go:171","msg":"trace[965559729] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"137.187614ms","start":"2026-02-06T00:43:02.187246Z","end":"2026-02-06T00:43:02.324434Z","steps":["trace[965559729] 'process raft request' (duration: 135.629537ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:02.324986Z","caller":"traceutil/trace.go:171","msg":"trace[1602324146] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"137.667546ms","start":"2026-02-06T00:43:02.187307Z","end":"2026-02-06T00:43:02.324975Z","steps":["trace[1602324146] 'process raft request' (duration: 135.592607ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:02.325455Z","caller":"traceutil/trace.go:171","msg":"trace[1359527492] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"136.412256ms","start":"2026-02-06T00:43:02.189031Z","end":"2026-02-06T00:43:02.325443Z","steps":["trace[1359527492] 'process raft request' (duration: 133.887026ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:02.32605Z","caller":"traceutil/trace.go:171","msg":"trace[1870830804] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"100.457803ms","start":"2026-02-06T00:43:02.22558Z","end":"2026-02-06T00:43:02.326038Z","steps":["trace[1870830804] 'process raft request' (duration: 97.411811ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:02.326166Z","caller":"traceutil/trace.go:171","msg":"trace[1655674391] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"100.516755ms","start":"2026-02-06T00:43:02.22564Z","end":"2026-02-06T00:43:02.326157Z","steps":["trace[1655674391] 'process raft request' (duration: 97.37135ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:02.326298Z","caller":"traceutil/trace.go:171","msg":"trace[1709680187] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"100.518864ms","start":"2026-02-06T00:43:02.225771Z","end":"2026-02-06T00:43:02.32629Z","steps":["trace[1709680187] 'process raft request' (duration: 97.254867ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:02.331914Z","caller":"traceutil/trace.go:171","msg":"trace[1589528487] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"142.762856ms","start":"2026-02-06T00:43:02.189138Z","end":"2026-02-06T00:43:02.331901Z","steps":["trace[1589528487] 'process raft request' (duration: 133.808174ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:02.571676Z","caller":"traceutil/trace.go:171","msg":"trace[334673] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"228.676765ms","start":"2026-02-06T00:43:02.34297Z","end":"2026-02-06T00:43:02.571647Z","steps":["trace[334673] 'process raft request' (duration: 206.463328ms)","trace[334673] 'compare' (duration: 21.876989ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:43:02.572318Z","caller":"traceutil/trace.go:171","msg":"trace[997244357] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"221.696779ms","start":"2026-02-06T00:43:02.350609Z","end":"2026-02-06T00:43:02.572306Z","steps":["trace[997244357] 'process raft request' (duration: 220.863749ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:02.574747Z","caller":"traceutil/trace.go:171","msg":"trace[1094525659] linearizableReadLoop","detail":"{readStateIndex:365; appliedIndex:362; }","duration":"222.810756ms","start":"2026-02-06T00:43:02.351914Z","end":"2026-02-06T00:43:02.574725Z","steps":["trace[1094525659] 'read index received' (duration: 38.746539ms)","trace[1094525659] 'applied index is now lower than readState.Index' (duration: 184.063166ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:43:02.575179Z","caller":"traceutil/trace.go:171","msg":"trace[582520958] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"224.515096ms","start":"2026-02-06T00:43:02.35065Z","end":"2026-02-06T00:43:02.575165Z","steps":["trace[582520958] 'process raft request' (duration: 220.87276ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:02.575281Z","caller":"traceutil/trace.go:171","msg":"trace[567744959] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"218.217787ms","start":"2026-02-06T00:43:02.357055Z","end":"2026-02-06T00:43:02.575273Z","steps":["trace[567744959] 'process raft request' (duration: 214.500548ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:02.575361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.42968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-sktc5\" ","response":"range_response_count:1 size:3416"} {"level":"info","ts":"2026-02-06T00:43:02.577183Z","caller":"traceutil/trace.go:171","msg":"trace[1912068348] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-sktc5; range_end:; response_count:1; response_revision:355; }","duration":"225.271593ms","start":"2026-02-06T00:43:02.351895Z","end":"2026-02-06T00:43:02.577166Z","steps":["trace[1912068348] 'agreement among raft nodes before linearized reading' (duration: 223.419359ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:02.986035Z","caller":"traceutil/trace.go:171","msg":"trace[1965855627] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"403.00307ms","start":"2026-02-06T00:43:02.582991Z","end":"2026-02-06T00:43:02.985994Z","steps":["trace[1965855627] 'process raft request' (duration: 348.055276ms)","trace[1965855627] 'compare' (duration: 54.712628ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:43:02.986184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:02.582982Z","time spent":"403.131774ms","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-06T00:43:02.986256Z","caller":"traceutil/trace.go:171","msg":"trace[127519538] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"392.07175ms","start":"2026-02-06T00:43:02.59416Z","end":"2026-02-06T00:43:02.986232Z","steps":["trace[127519538] 'process raft request' (duration: 392.035469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:02.9864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:02.594154Z","time spent":"392.172134ms","remote":"127.0.0.1:39258","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-06T00:43:02.986633Z","caller":"traceutil/trace.go:171","msg":"trace[571944336] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"399.507838ms","start":"2026-02-06T00:43:02.587115Z","end":"2026-02-06T00:43:02.986623Z","steps":["trace[571944336] 'process raft request' (duration: 398.898043ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:02.986653Z","caller":"traceutil/trace.go:171","msg":"trace[315094819] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"400.686885ms","start":"2026-02-06T00:43:02.585955Z","end":"2026-02-06T00:43:02.986641Z","steps":["trace[315094819] 'process raft request' (duration: 399.964258ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:02.986689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:02.587107Z","time spent":"399.550018ms","remote":"127.0.0.1:39162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4406,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:43:02.986704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:02.585946Z","time spent":"400.733466ms","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-06T00:43:02.986825Z","caller":"traceutil/trace.go:171","msg":"trace[1627299737] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"393.565366ms","start":"2026-02-06T00:43:02.59325Z","end":"2026-02-06T00:43:02.986816Z","steps":["trace[1627299737] 'process raft request' (duration: 392.806658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:02.98688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:02.593232Z","time spent":"393.619497ms","remote":"127.0.0.1:39162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3979,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:43:02.986905Z","caller":"traceutil/trace.go:171","msg":"trace[1711819211] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"400.90078ms","start":"2026-02-06T00:43:02.585995Z","end":"2026-02-06T00:43:02.986896Z","steps":["trace[1711819211] 'process raft request' (duration: 399.961178ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:02.986993Z","caller":"traceutil/trace.go:171","msg":"trace[458656724] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"393.538215ms","start":"2026-02-06T00:43:02.593446Z","end":"2026-02-06T00:43:02.986984Z","steps":["trace[458656724] 'process raft request' (duration: 392.720856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:02.987041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:02.593441Z","time spent":"393.574557ms","remote":"127.0.0.1:39258","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-06T00:43:02.986969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:02.585991Z","time spent":"400.938471ms","remote":"127.0.0.1:39258","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-06T00:43:03.040262Z","caller":"traceutil/trace.go:171","msg":"trace[1500472311] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"365.274335ms","start":"2026-02-06T00:43:02.674963Z","end":"2026-02-06T00:43:03.040237Z","steps":["trace[1500472311] 'process raft request' (duration: 364.985009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:03.040575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:02.674936Z","time spent":"365.520171ms","remote":"127.0.0.1:39482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3782,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:43:03.19766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.033706ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:43:03.19775Z","caller":"traceutil/trace.go:171","msg":"trace[676390638] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"147.039338ms","start":"2026-02-06T00:43:03.050697Z","end":"2026-02-06T00:43:03.197737Z","steps":["trace[676390638] 'process raft request' (duration: 37.864538ms)","trace[676390638] 'compare' (duration: 108.926804ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:43:04.038482Z","caller":"traceutil/trace.go:171","msg":"trace[218539233] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"181.347562ms","start":"2026-02-06T00:43:03.857112Z","end":"2026-02-06T00:43:04.03846Z","steps":["trace[218539233] 'process raft request' (duration: 181.196518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:07.763665Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581739927616502870,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-06T00:43:07.920416Z","caller":"traceutil/trace.go:171","msg":"trace[1132245375] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"891.435177ms","start":"2026-02-06T00:43:07.028958Z","end":"2026-02-06T00:43:07.920393Z","steps":["trace[1132245375] 'process raft request' (duration: 891.292954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:07.920547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:07.028951Z","time spent":"891.525889ms","remote":"127.0.0.1:39258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:43:08.505151Z","caller":"traceutil/trace.go:171","msg":"trace[1870182094] linearizableReadLoop","detail":"{readStateIndex:396; appliedIndex:394; }","duration":"1.241662285s","start":"2026-02-06T00:43:07.263463Z","end":"2026-02-06T00:43:08.505125Z","steps":["trace[1870182094] 'read index received' (duration: 657.459796ms)","trace[1870182094] 'applied index is now lower than readState.Index' (duration: 584.201789ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:43:08.505428Z","caller":"traceutil/trace.go:171","msg":"trace[1374013761] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"1.474430477s","start":"2026-02-06T00:43:07.030989Z","end":"2026-02-06T00:43:08.50542Z","steps":["trace[1374013761] 'process raft request' (duration: 1.473988066s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:08.505498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:07.030981Z","time spent":"1.474467188s","remote":"127.0.0.1:39258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:43:08.505591Z","caller":"traceutil/trace.go:171","msg":"trace[760585450] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"1.06017013s","start":"2026-02-06T00:43:07.445415Z","end":"2026-02-06T00:43:08.505586Z","steps":["trace[760585450] 'process raft request' (duration: 1.059651618s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:08.505613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:07.445398Z","time spent":"1.06020323s","remote":"127.0.0.1:39258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:43:08.505715Z","caller":"traceutil/trace.go:171","msg":"trace[2140675537] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"721.429524ms","start":"2026-02-06T00:43:07.784281Z","end":"2026-02-06T00:43:08.505711Z","steps":["trace[2140675537] 'process raft request' (duration: 720.810639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:08.505741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:07.784262Z","time spent":"721.463374ms","remote":"127.0.0.1:39148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4042,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:43:08.505815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.242347981s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6820"} {"level":"info","ts":"2026-02-06T00:43:08.505832Z","caller":"traceutil/trace.go:171","msg":"trace[1375631005] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:384; }","duration":"1.242395003s","start":"2026-02-06T00:43:07.263431Z","end":"2026-02-06T00:43:08.505826Z","steps":["trace[1375631005] 'agreement among raft nodes before linearized reading' (duration: 1.242359342s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:08.505846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:07.263417Z","time spent":"1.242424983s","remote":"127.0.0.1:39162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6843,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-02-06T00:43:08.505916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.206816129s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:43:08.505927Z","caller":"traceutil/trace.go:171","msg":"trace[1457950291] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:384; }","duration":"1.206841109s","start":"2026-02-06T00:43:07.299082Z","end":"2026-02-06T00:43:08.505923Z","steps":["trace[1457950291] 'agreement among raft nodes before linearized reading' (duration: 1.206822628s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:08.505938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:07.299075Z","time spent":"1.20686001s","remote":"127.0.0.1:38964","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-06T00:43:08.50608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.061562ms","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-06T00:43:08.506093Z","caller":"traceutil/trace.go:171","msg":"trace[1135352651] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:384; }","duration":"145.107942ms","start":"2026-02-06T00:43:08.360981Z","end":"2026-02-06T00:43:08.506089Z","steps":["trace[1135352651] 'agreement among raft nodes before linearized reading' (duration: 145.082741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:08.506154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.126875ms","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-06T00:43:08.506165Z","caller":"traceutil/trace.go:171","msg":"trace[647079970] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:384; }","duration":"151.174097ms","start":"2026-02-06T00:43:08.354987Z","end":"2026-02-06T00:43:08.506161Z","steps":["trace[647079970] 'agreement among raft nodes before linearized reading' (duration: 151.153556ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:13.024046Z","caller":"traceutil/trace.go:171","msg":"trace[2075196181] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"135.951115ms","start":"2026-02-06T00:43:12.88808Z","end":"2026-02-06T00:43:13.024031Z","steps":["trace[2075196181] 'process raft request' (duration: 135.908314ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:13.024055Z","caller":"traceutil/trace.go:171","msg":"trace[781747180] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"136.191271ms","start":"2026-02-06T00:43:12.887826Z","end":"2026-02-06T00:43:13.024017Z","steps":["trace[781747180] 'process raft request' (duration: 80.380437ms)","trace[781747180] 'compare' (duration: 55.681191ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:43:27.104068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.557649ms","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-06T00:43:27.104123Z","caller":"traceutil/trace.go:171","msg":"trace[936419152] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:495; }","duration":"356.64612ms","start":"2026-02-06T00:43:26.747466Z","end":"2026-02-06T00:43:27.104112Z","steps":["trace[936419152] 'range keys from in-memory index tree' (duration: 356.484727ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:27.104147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:26.747438Z","time spent":"356.704562ms","remote":"127.0.0.1:39258","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-06T00:43:27.798572Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581739927616503098,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-06T00:43:27.88562Z","caller":"traceutil/trace.go:171","msg":"trace[7074501] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:512; }","duration":"588.021529ms","start":"2026-02-06T00:43:27.297584Z","end":"2026-02-06T00:43:27.885606Z","steps":["trace[7074501] 'read index received' (duration: 587.900056ms)","trace[7074501] 'applied index is now lower than readState.Index' (duration: 120.983µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:43:27.885709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.112231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:43:27.885726Z","caller":"traceutil/trace.go:171","msg":"trace[945774977] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:496; }","duration":"588.169252ms","start":"2026-02-06T00:43:27.29755Z","end":"2026-02-06T00:43:27.88572Z","steps":["trace[945774977] 'agreement among raft nodes before linearized reading' (duration: 588.109701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:27.885743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:27.297512Z","time spent":"588.227634ms","remote":"127.0.0.1:38954","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-06T00:43:27.885937Z","caller":"traceutil/trace.go:171","msg":"trace[2125112657] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"778.18352ms","start":"2026-02-06T00:43:27.107748Z","end":"2026-02-06T00:43:27.885932Z","steps":["trace[2125112657] 'process raft request' (duration: 777.772901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:27.885992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:27.107733Z","time spent":"778.216531ms","remote":"127.0.0.1:39258","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-06T00:43:34.398416Z","caller":"traceutil/trace.go:171","msg":"trace[321627126] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:531; }","duration":"198.182162ms","start":"2026-02-06T00:43:34.200213Z","end":"2026-02-06T00:43:34.398395Z","steps":["trace[321627126] 'read index received' (duration: 74.148669ms)","trace[321627126] 'applied index is now lower than readState.Index' (duration: 124.032323ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:43:34.398807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.425727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6534"} {"level":"info","ts":"2026-02-06T00:43:34.398856Z","caller":"traceutil/trace.go:171","msg":"trace[602388082] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:514; }","duration":"198.663442ms","start":"2026-02-06T00:43:34.200181Z","end":"2026-02-06T00:43:34.398844Z","steps":["trace[602388082] 'agreement among raft nodes before linearized reading' (duration: 198.408486ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:34.398702Z","caller":"traceutil/trace.go:171","msg":"trace[744947780] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"200.266591ms","start":"2026-02-06T00:43:34.198394Z","end":"2026-02-06T00:43:34.398661Z","steps":["trace[744947780] 'process raft request' (duration: 128.963789ms)","trace[744947780] 'compare' (duration: 70.950034ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:43:34.560727Z","caller":"traceutil/trace.go:171","msg":"trace[1108661921] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"144.816655ms","start":"2026-02-06T00:43:34.415893Z","end":"2026-02-06T00:43:34.56071Z","steps":["trace[1108661921] 'process raft request' (duration: 144.277602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:35.175573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.643278ms","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-06T00:43:35.175628Z","caller":"traceutil/trace.go:171","msg":"trace[1236743829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:515; }","duration":"498.739211ms","start":"2026-02-06T00:43:34.676873Z","end":"2026-02-06T00:43:35.175613Z","steps":["trace[1236743829] 'range keys from in-memory index tree' (duration: 498.491716ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:35.175655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:34.676853Z","time spent":"498.796543ms","remote":"127.0.0.1:39258","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-06T00:43:35.175905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.692729ms","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-06T00:43:35.175935Z","caller":"traceutil/trace.go:171","msg":"trace[88465877] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:515; }","duration":"495.779301ms","start":"2026-02-06T00:43:34.680147Z","end":"2026-02-06T00:43:35.175926Z","steps":["trace[88465877] 'range keys from in-memory index tree' (duration: 495.584036ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:35.175961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:34.680127Z","time spent":"495.827012ms","remote":"127.0.0.1:39258","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-06T00:43:36.123035Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581739927616503148,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T00:43:36.274056Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.093837508s","expected-duration":"1s"} {"level":"info","ts":"2026-02-06T00:43:36.274524Z","caller":"traceutil/trace.go:171","msg":"trace[1249670735] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"1.09434795s","start":"2026-02-06T00:43:35.180136Z","end":"2026-02-06T00:43:36.274484Z","steps":["trace[1249670735] 'process raft request' (duration: 1.094114885s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:36.274756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:35.180115Z","time spent":"1.094513084s","remote":"127.0.0.1:39258","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-06T00:43:37.297015Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.022826194s","expected-duration":"1s"} {"level":"info","ts":"2026-02-06T00:43:37.299503Z","caller":"traceutil/trace.go:171","msg":"trace[394457079] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:533; }","duration":"1.676661705s","start":"2026-02-06T00:43:35.622816Z","end":"2026-02-06T00:43:37.299477Z","steps":["trace[394457079] 'read index received' (duration: 651.481825ms)","trace[394457079] 'applied index is now lower than readState.Index' (duration: 1.02517884s)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:43:37.299832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.676969312s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-06T00:43:37.299864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.286286304s","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-06T00:43:37.299911Z","caller":"traceutil/trace.go:171","msg":"trace[71897095] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:517; }","duration":"1.286384586s","start":"2026-02-06T00:43:36.013514Z","end":"2026-02-06T00:43:37.299899Z","steps":["trace[71897095] 'agreement among raft nodes before linearized reading' (duration: 1.286211472s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:37.299957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:36.013495Z","time spent":"1.286451158s","remote":"127.0.0.1:39258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-06T00:43:37.299964Z","caller":"traceutil/trace.go:171","msg":"trace[1871013300] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"2.119225483s","start":"2026-02-06T00:43:35.180725Z","end":"2026-02-06T00:43:37.29995Z","steps":["trace[1871013300] 'process raft request' (duration: 2.116536939s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:37.300149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:35.180701Z","time spent":"2.119355626s","remote":"127.0.0.1:39258","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-06T00:43:37.300214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.236196ms","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-06T00:43:37.300247Z","caller":"traceutil/trace.go:171","msg":"trace[74022361] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:517; }","duration":"892.319868ms","start":"2026-02-06T00:43:36.407917Z","end":"2026-02-06T00:43:37.300236Z","steps":["trace[74022361] 'agreement among raft nodes before linearized reading' (duration: 892.235386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:37.300274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:36.407891Z","time spent":"892.37657ms","remote":"127.0.0.1:39258","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-06T00:43:37.300366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.724088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:43:37.300393Z","caller":"traceutil/trace.go:171","msg":"trace[1371154662] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:517; }","duration":"994.818849ms","start":"2026-02-06T00:43:36.305566Z","end":"2026-02-06T00:43:37.300385Z","steps":["trace[1371154662] 'agreement among raft nodes before linearized reading' (duration: 994.765948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:37.300418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:36.305537Z","time spent":"994.875401ms","remote":"127.0.0.1:38954","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-06T00:43:37.299905Z","caller":"traceutil/trace.go:171","msg":"trace[1660469944] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:517; }","duration":"1.677122435s","start":"2026-02-06T00:43:35.622752Z","end":"2026-02-06T00:43:37.299874Z","steps":["trace[1660469944] 'agreement among raft nodes before linearized reading' (duration: 1.676934131s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:37.300551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:35.622722Z","time spent":"1.677821112s","remote":"127.0.0.1:38994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.233\" "} {"level":"warn","ts":"2026-02-06T00:43:40.009576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.630441ms","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-06T00:43:40.010283Z","caller":"traceutil/trace.go:171","msg":"trace[1735216295] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:540; }","duration":"188.356388ms","start":"2026-02-06T00:43:39.82191Z","end":"2026-02-06T00:43:40.010267Z","steps":["trace[1735216295] 'range keys from in-memory index tree' (duration: 187.615221ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:47.00905Z","caller":"traceutil/trace.go:171","msg":"trace[877535440] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:651; }","duration":"651.660828ms","start":"2026-02-06T00:43:46.357374Z","end":"2026-02-06T00:43:47.009034Z","steps":["trace[877535440] 'read index received' (duration: 45.940249ms)","trace[877535440] 'applied index is now lower than readState.Index' (duration: 605.719979ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:43:47.009201Z","caller":"traceutil/trace.go:171","msg":"trace[1700716582] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"657.820665ms","start":"2026-02-06T00:43:46.351374Z","end":"2026-02-06T00:43:47.009194Z","steps":["trace[1700716582] 'process raft request' (duration: 603.409704ms)","trace[1700716582] 'compare' (duration: 53.901899ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:43:47.009292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:46.351364Z","time spent":"657.852006ms","remote":"127.0.0.1:54642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2323,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:43:47.009357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.865024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:43:47.009409Z","caller":"traceutil/trace.go:171","msg":"trace[317876670] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:630; }","duration":"651.969026ms","start":"2026-02-06T00:43:46.35743Z","end":"2026-02-06T00:43:47.009399Z","steps":["trace[317876670] 'agreement among raft nodes before linearized reading' (duration: 651.857133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:47.009432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:46.357424Z","time spent":"652.002077ms","remote":"127.0.0.1:54704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-02-06T00:43:47.00955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.079528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:43:47.009563Z","caller":"traceutil/trace.go:171","msg":"trace[1203198634] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:630; }","duration":"652.10454ms","start":"2026-02-06T00:43:46.357454Z","end":"2026-02-06T00:43:47.009559Z","steps":["trace[1203198634] 'agreement among raft nodes before linearized reading' (duration: 652.082729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:47.009575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:46.35745Z","time spent":"652.12214ms","remote":"127.0.0.1:54704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 "} {"level":"warn","ts":"2026-02-06T00:43:47.009643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.117077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-wljnh.189181996cf9c904\" ","response":"range_response_count:1 size:795"} {"level":"info","ts":"2026-02-06T00:43:47.009665Z","caller":"traceutil/trace.go:171","msg":"trace[47524654] range","detail":"{range_begin:/registry/events/kube-system/cilium-wljnh.189181996cf9c904; range_end:; response_count:1; response_revision:630; }","duration":"517.154538ms","start":"2026-02-06T00:43:46.492504Z","end":"2026-02-06T00:43:47.009658Z","steps":["trace[47524654] 'agreement among raft nodes before linearized reading' (duration: 517.006975ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:47.00968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:46.492491Z","time spent":"517.186159ms","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":818,"request content":"key:\"/registry/events/kube-system/cilium-wljnh.189181996cf9c904\" "} {"level":"warn","ts":"2026-02-06T00:43:47.009729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.355566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:43:47.009745Z","caller":"traceutil/trace.go:171","msg":"trace[1164969318] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:630; }","duration":"652.400386ms","start":"2026-02-06T00:43:46.35734Z","end":"2026-02-06T00:43:47.009741Z","steps":["trace[1164969318] 'agreement among raft nodes before linearized reading' (duration: 652.368105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:47.009758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:46.357304Z","time spent":"652.451117ms","remote":"127.0.0.1:54704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 "} {"level":"info","ts":"2026-02-06T00:43:55.086185Z","caller":"traceutil/trace.go:171","msg":"trace[1557538422] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"640.421452ms","start":"2026-02-06T00:43:54.445734Z","end":"2026-02-06T00:43:55.086156Z","steps":["trace[1557538422] 'process raft request' (duration: 640.238588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:55.086385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:54.445695Z","time spent":"640.579066ms","remote":"127.0.0.1:39258","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-06T00:43:56.489547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.477367ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:43:56.489697Z","caller":"traceutil/trace.go:171","msg":"trace[1123196156] linearizableReadLoop","detail":"{readStateIndex:724; appliedIndex:722; }","duration":"1.218602538s","start":"2026-02-06T00:43:55.271081Z","end":"2026-02-06T00:43:56.489683Z","steps":["trace[1123196156] 'read index received' (duration: 388.867664ms)","trace[1123196156] 'applied index is now lower than readState.Index' (duration: 829.733914ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:43:56.489882Z","caller":"traceutil/trace.go:171","msg":"trace[2087986019] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"1.775124859s","start":"2026-02-06T00:43:54.714743Z","end":"2026-02-06T00:43:56.489868Z","steps":["trace[2087986019] 'process raft request' (duration: 945.262863ms)","trace[2087986019] 'compare' (duration: 828.882893ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:43:56.489951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:54.714729Z","time spent":"1.775192111s","remote":"127.0.0.1:39258","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-06T00:43:56.490174Z","caller":"traceutil/trace.go:171","msg":"trace[949776811] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"1.749326317s","start":"2026-02-06T00:43:54.740839Z","end":"2026-02-06T00:43:56.490165Z","steps":["trace[949776811] 'process raft request' (duration: 1.748793154s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:56.490223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:54.74083Z","time spent":"1.749369188s","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":815,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-06T00:43:56.491108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.220026381s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:43:56.491143Z","caller":"traceutil/trace.go:171","msg":"trace[933196585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:700; }","duration":"1.220085313s","start":"2026-02-06T00:43:55.271045Z","end":"2026-02-06T00:43:56.49113Z","steps":["trace[933196585] 'agreement among raft nodes before linearized reading' (duration: 1.220030482s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:56.49117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:55.27103Z","time spent":"1.220133604s","remote":"127.0.0.1:38954","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-06T00:43:56.491291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.191863632s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:43:56.491325Z","caller":"traceutil/trace.go:171","msg":"trace[1357220489] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:700; }","duration":"1.191910613s","start":"2026-02-06T00:43:55.299404Z","end":"2026-02-06T00:43:56.491314Z","steps":["trace[1357220489] 'agreement among raft nodes before linearized reading' (duration: 1.191838992s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:56.49135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:55.299397Z","time spent":"1.191946875s","remote":"127.0.0.1:38964","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-06T00:43:56.491639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.870165ms","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-06T00:43:56.491668Z","caller":"traceutil/trace.go:171","msg":"trace[1652332170] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:700; }","duration":"251.922186ms","start":"2026-02-06T00:43:56.239738Z","end":"2026-02-06T00:43:56.49166Z","steps":["trace[1652332170] 'agreement among raft nodes before linearized reading' (duration: 251.828474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:56.491745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.777974ms","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-06T00:43:56.49177Z","caller":"traceutil/trace.go:171","msg":"trace[103870145] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:700; }","duration":"324.826726ms","start":"2026-02-06T00:43:56.166936Z","end":"2026-02-06T00:43:56.491763Z","steps":["trace[103870145] 'agreement among raft nodes before linearized reading' (duration: 324.784905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:56.491795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:56.166921Z","time spent":"324.865776ms","remote":"127.0.0.1:39400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-02-06T00:43:56.49197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.239633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-06T00:43:56.491997Z","caller":"traceutil/trace.go:171","msg":"trace[1733654867] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:700; }","duration":"867.316474ms","start":"2026-02-06T00:43:55.624673Z","end":"2026-02-06T00:43:56.491989Z","steps":["trace[1733654867] 'agreement among raft nodes before linearized reading' (duration: 867.240582ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:56.492026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:55.624651Z","time spent":"867.367896ms","remote":"127.0.0.1:38994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.233\" "} {"level":"warn","ts":"2026-02-06T00:43:56.492192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.071855937s","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-06T00:43:56.492222Z","caller":"traceutil/trace.go:171","msg":"trace[1662727726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:700; }","duration":"1.071902078s","start":"2026-02-06T00:43:55.420309Z","end":"2026-02-06T00:43:56.492211Z","steps":["trace[1662727726] 'agreement among raft nodes before linearized reading' (duration: 1.071826016s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:56.492247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:55.420299Z","time spent":"1.071941038s","remote":"127.0.0.1:39258","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-06T00:43:56.492304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.078540215s","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-06T00:43:56.492333Z","caller":"traceutil/trace.go:171","msg":"trace[1916515768] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:700; }","duration":"1.078570656s","start":"2026-02-06T00:43:55.413754Z","end":"2026-02-06T00:43:56.492325Z","steps":["trace[1916515768] 'agreement among raft nodes before linearized reading' (duration: 1.078491204s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:56.492362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:55.413739Z","time spent":"1.078615107s","remote":"127.0.0.1:39258","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-06T00:43:56.495212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.085980171s","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-06T00:43:56.495276Z","caller":"traceutil/trace.go:171","msg":"trace[1416989511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:700; }","duration":"1.086025443s","start":"2026-02-06T00:43:55.409208Z","end":"2026-02-06T00:43:56.495233Z","steps":["trace[1416989511] 'agreement among raft nodes before linearized reading' (duration: 1.083265938s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:56.495312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:55.4092Z","time spent":"1.086106095s","remote":"127.0.0.1:39258","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-06T00:43:57.007213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.086944ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-06T00:43:57.007449Z","caller":"traceutil/trace.go:171","msg":"trace[606306173] linearizableReadLoop","detail":"{readStateIndex:727; appliedIndex:724; }","duration":"506.399353ms","start":"2026-02-06T00:43:56.501036Z","end":"2026-02-06T00:43:57.007436Z","steps":["trace[606306173] 'read index received' (duration: 320.091503ms)","trace[606306173] 'applied index is now lower than readState.Index' (duration: 186.30725ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:43:57.007508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.464714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:43:57.007523Z","caller":"traceutil/trace.go:171","msg":"trace[33174947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:704; }","duration":"506.498016ms","start":"2026-02-06T00:43:56.50102Z","end":"2026-02-06T00:43:57.007518Z","steps":["trace[33174947] 'agreement among raft nodes before linearized reading' (duration: 506.449645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:57.007542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:56.501015Z","time spent":"506.523606ms","remote":"127.0.0.1:38954","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-06T00:43:57.00766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:56.495678Z","time spent":"511.980855ms","remote":"127.0.0.1:38994","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-06T00:43:57.007798Z","caller":"traceutil/trace.go:171","msg":"trace[1880194429] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"506.988667ms","start":"2026-02-06T00:43:56.500803Z","end":"2026-02-06T00:43:57.007791Z","steps":["trace[1880194429] 'process raft request' (duration: 506.487435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:57.00785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:56.50079Z","time spent":"507.025178ms","remote":"127.0.0.1:39258","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-06T00:43:57.007939Z","caller":"traceutil/trace.go:171","msg":"trace[1879197303] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"506.996158ms","start":"2026-02-06T00:43:56.500937Z","end":"2026-02-06T00:43:57.007934Z","steps":["trace[1879197303] 'process raft request' (duration: 506.435174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:57.00797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:56.500929Z","time spent":"507.023568ms","remote":"127.0.0.1:39258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:43:57.00804Z","caller":"traceutil/trace.go:171","msg":"trace[2092059267] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"504.627801ms","start":"2026-02-06T00:43:56.503407Z","end":"2026-02-06T00:43:57.008034Z","steps":["trace[2092059267] 'process raft request' (duration: 503.987526ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:57.009078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:56.503397Z","time spent":"504.655031ms","remote":"127.0.0.1:39258","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-06T00:43:57.009248Z","caller":"traceutil/trace.go:171","msg":"trace[1907529780] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"504.928178ms","start":"2026-02-06T00:43:56.504312Z","end":"2026-02-06T00:43:57.00924Z","steps":["trace[1907529780] 'process raft request' (duration: 503.102204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:57.009307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:56.504305Z","time spent":"504.98026ms","remote":"127.0.0.1:39258","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-06T00:43:57.292476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.636032ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:43:57.292577Z","caller":"traceutil/trace.go:171","msg":"trace[1711438168] linearizableReadLoop","detail":"{readStateIndex:730; appliedIndex:729; }","duration":"281.661161ms","start":"2026-02-06T00:43:57.0109Z","end":"2026-02-06T00:43:57.292561Z","steps":["trace[1711438168] 'read index received' (duration: 160.807925ms)","trace[1711438168] 'applied index is now lower than readState.Index' (duration: 120.852006ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:43:57.292605Z","caller":"traceutil/trace.go:171","msg":"trace[1904757416] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"281.938878ms","start":"2026-02-06T00:43:57.010634Z","end":"2026-02-06T00:43:57.292573Z","steps":["trace[1904757416] 'process raft request' (duration: 161.134782ms)","trace[1904757416] 'compare' (duration: 120.54782ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:43:57.292703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.798375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:43:57.29273Z","caller":"traceutil/trace.go:171","msg":"trace[531276959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:705; }","duration":"281.843006ms","start":"2026-02-06T00:43:57.010879Z","end":"2026-02-06T00:43:57.292722Z","steps":["trace[531276959] 'agreement among raft nodes before linearized reading' (duration: 281.766874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:57.2929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.301743ms","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-06T00:43:57.292924Z","caller":"traceutil/trace.go:171","msg":"trace[803844251] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:705; }","duration":"203.369884ms","start":"2026-02-06T00:43:57.089548Z","end":"2026-02-06T00:43:57.292918Z","steps":["trace[803844251] 'agreement among raft nodes before linearized reading' (duration: 203.272872ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:43:59.167928Z","caller":"traceutil/trace.go:171","msg":"trace[1481584912] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"149.335612ms","start":"2026-02-06T00:43:59.018576Z","end":"2026-02-06T00:43:59.167912Z","steps":["trace[1481584912] 'process raft request' (duration: 149.23ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:59.869656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.96688ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:43:59.869785Z","caller":"traceutil/trace.go:171","msg":"trace[318027680] linearizableReadLoop","detail":"{readStateIndex:735; appliedIndex:732; }","duration":"571.042226ms","start":"2026-02-06T00:43:59.298733Z","end":"2026-02-06T00:43:59.869775Z","steps":["trace[318027680] 'read index received' (duration: 304.907243ms)","trace[318027680] 'applied index is now lower than readState.Index' (duration: 266.134343ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:43:59.869912Z","caller":"traceutil/trace.go:171","msg":"trace[1335751328] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"851.0859ms","start":"2026-02-06T00:43:59.018819Z","end":"2026-02-06T00:43:59.869905Z","steps":["trace[1335751328] 'process raft request' (duration: 584.813143ms)","trace[1335751328] 'compare' (duration: 265.730344ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:43:59.869952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:59.018813Z","time spent":"851.118051ms","remote":"127.0.0.1:39258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:43:59.870091Z","caller":"traceutil/trace.go:171","msg":"trace[1117146012] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"850.482815ms","start":"2026-02-06T00:43:59.019602Z","end":"2026-02-06T00:43:59.870085Z","steps":["trace[1117146012] 'process raft request' (duration: 850.115366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:59.870135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:59.019595Z","time spent":"850.521866ms","remote":"127.0.0.1:39258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:43:59.870215Z","caller":"traceutil/trace.go:171","msg":"trace[2008739641] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"850.010454ms","start":"2026-02-06T00:43:59.020199Z","end":"2026-02-06T00:43:59.87021Z","steps":["trace[2008739641] 'process raft request' (duration: 849.551943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:59.870243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:59.020193Z","time spent":"850.035065ms","remote":"127.0.0.1:39258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:43:59.87033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.59996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:43:59.870349Z","caller":"traceutil/trace.go:171","msg":"trace[549963543] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:710; }","duration":"571.64171ms","start":"2026-02-06T00:43:59.298699Z","end":"2026-02-06T00:43:59.870341Z","steps":["trace[549963543] 'agreement among raft nodes before linearized reading' (duration: 571.61322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:59.870365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:59.298683Z","time spent":"571.678392ms","remote":"127.0.0.1:38954","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-06T00:43:59.922396Z","caller":"traceutil/trace.go:171","msg":"trace[2015144014] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"521.570483ms","start":"2026-02-06T00:43:59.400809Z","end":"2026-02-06T00:43:59.92238Z","steps":["trace[2015144014] 'process raft request' (duration: 521.317947ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:59.922492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:59.400793Z","time spent":"521.648354ms","remote":"127.0.0.1:39258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:43:59.922684Z","caller":"traceutil/trace.go:171","msg":"trace[556099046] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"398.555255ms","start":"2026-02-06T00:43:59.524114Z","end":"2026-02-06T00:43:59.92267Z","steps":["trace[556099046] 'process raft request' (duration: 398.098574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:59.922722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:59.524099Z","time spent":"398.603175ms","remote":"127.0.0.1:39148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4769,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:43:59.92286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.389769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-06T00:43:59.922883Z","caller":"traceutil/trace.go:171","msg":"trace[1148640265] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:712; }","duration":"575.449111ms","start":"2026-02-06T00:43:59.347427Z","end":"2026-02-06T00:43:59.922876Z","steps":["trace[1148640265] 'agreement among raft nodes before linearized reading' (duration: 575.371499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:59.922905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:59.347416Z","time spent":"575.482482ms","remote":"127.0.0.1:39258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-06T00:43:59.923002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.549255ms","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-06T00:43:59.923022Z","caller":"traceutil/trace.go:171","msg":"trace[878236110] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:712; }","duration":"616.569827ms","start":"2026-02-06T00:43:59.306446Z","end":"2026-02-06T00:43:59.923016Z","steps":["trace[878236110] 'agreement among raft nodes before linearized reading' (duration: 616.531346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:43:59.92304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:43:59.306439Z","time spent":"616.594887ms","remote":"127.0.0.1:39438","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":"info","ts":"2026-02-06T00:44:07.305375Z","caller":"traceutil/trace.go:171","msg":"trace[91093912] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"105.116713ms","start":"2026-02-06T00:44:07.200247Z","end":"2026-02-06T00:44:07.305364Z","steps":["trace[91093912] 'process raft request' (duration: 104.855237ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:44:08.009844Z","caller":"traceutil/trace.go:171","msg":"trace[120933437] linearizableReadLoop","detail":"{readStateIndex:764; appliedIndex:763; }","duration":"107.160542ms","start":"2026-02-06T00:44:07.902666Z","end":"2026-02-06T00:44:08.009827Z","steps":["trace[120933437] 'read index received' (duration: 107.032929ms)","trace[120933437] 'applied index is now lower than readState.Index' (duration: 126.933µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:44:08.010036Z","caller":"traceutil/trace.go:171","msg":"trace[1274414576] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"108.227618ms","start":"2026-02-06T00:44:07.901802Z","end":"2026-02-06T00:44:08.01003Z","steps":["trace[1274414576] 'process raft request' (duration: 107.939311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:08.010229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.537631ms","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-06T00:44:08.010248Z","caller":"traceutil/trace.go:171","msg":"trace[855168441] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:737; }","duration":"107.585443ms","start":"2026-02-06T00:44:07.902656Z","end":"2026-02-06T00:44:08.010241Z","steps":["trace[855168441] 'agreement among raft nodes before linearized reading' (duration: 107.48999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:08.010341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.648634ms","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-06T00:44:08.010354Z","caller":"traceutil/trace.go:171","msg":"trace[1427336070] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:737; }","duration":"107.666834ms","start":"2026-02-06T00:44:07.902682Z","end":"2026-02-06T00:44:08.010349Z","steps":["trace[1427336070] 'agreement among raft nodes before linearized reading' (duration: 107.629843ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:44:12.154985Z","caller":"traceutil/trace.go:171","msg":"trace[703855174] linearizableReadLoop","detail":"{readStateIndex:775; appliedIndex:774; }","duration":"106.981367ms","start":"2026-02-06T00:44:12.047979Z","end":"2026-02-06T00:44:12.154961Z","steps":["trace[703855174] 'read index received' (duration: 90.027135ms)","trace[703855174] 'applied index is now lower than readState.Index' (duration: 16.953202ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:44:12.155127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.071048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-06T00:44:12.155132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.146061ms","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-06T00:44:12.15515Z","caller":"traceutil/trace.go:171","msg":"trace[1677002767] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:747; }","duration":"101.102219ms","start":"2026-02-06T00:44:12.054039Z","end":"2026-02-06T00:44:12.155141Z","steps":["trace[1677002767] 'agreement among raft nodes before linearized reading' (duration: 101.016017ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:44:12.15516Z","caller":"traceutil/trace.go:171","msg":"trace[728569677] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:747; }","duration":"107.204153ms","start":"2026-02-06T00:44:12.047948Z","end":"2026-02-06T00:44:12.155152Z","steps":["trace[728569677] 'agreement among raft nodes before linearized reading' (duration: 107.0954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:12.155437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.344561ms","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-06T00:44:12.155457Z","caller":"traceutil/trace.go:171","msg":"trace[1445808465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:747; }","duration":"100.374501ms","start":"2026-02-06T00:44:12.055077Z","end":"2026-02-06T00:44:12.155451Z","steps":["trace[1445808465] 'agreement among raft nodes before linearized reading' (duration: 100.305469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:12.155529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.044353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-06T00:44:12.155543Z","caller":"traceutil/trace.go:171","msg":"trace[1905173096] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:747; }","duration":"100.065664ms","start":"2026-02-06T00:44:12.055473Z","end":"2026-02-06T00:44:12.155539Z","steps":["trace[1905173096] 'agreement among raft nodes before linearized reading' (duration: 100.021093ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:44:14.445072Z","caller":"traceutil/trace.go:171","msg":"trace[651814944] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"274.494892ms","start":"2026-02-06T00:44:14.170555Z","end":"2026-02-06T00:44:14.44505Z","steps":["trace[651814944] 'process raft request' (duration: 274.309417ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:44:14.458519Z","caller":"traceutil/trace.go:171","msg":"trace[572580994] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"285.37079ms","start":"2026-02-06T00:44:14.173126Z","end":"2026-02-06T00:44:14.458497Z","steps":["trace[572580994] 'process raft request' (duration: 285.314748ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:44:14.459197Z","caller":"traceutil/trace.go:171","msg":"trace[1885502200] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"288.255768ms","start":"2026-02-06T00:44:14.17093Z","end":"2026-02-06T00:44:14.459186Z","steps":["trace[1885502200] 'process raft request' (duration: 287.352177ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:44:14.459716Z","caller":"traceutil/trace.go:171","msg":"trace[285305769] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"287.819527ms","start":"2026-02-06T00:44:14.171888Z","end":"2026-02-06T00:44:14.459708Z","steps":["trace[285305769] 'process raft request' (duration: 286.520147ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:44:16.756569Z","caller":"traceutil/trace.go:171","msg":"trace[1276306600] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:787; }","duration":"291.934405ms","start":"2026-02-06T00:44:16.464606Z","end":"2026-02-06T00:44:16.75654Z","steps":["trace[1276306600] 'read index received' (duration: 291.804652ms)","trace[1276306600] 'applied index is now lower than readState.Index' (duration: 128.833µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:44:16.756786Z","caller":"traceutil/trace.go:171","msg":"trace[1080894627] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"306.104452ms","start":"2026-02-06T00:44:16.450674Z","end":"2026-02-06T00:44:16.756779Z","steps":["trace[1080894627] 'process raft request' (duration: 305.778054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:16.756862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:16.450662Z","time spent":"306.147872ms","remote":"127.0.0.1:39258","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-06T00:44:16.757091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.482258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-06T00:44:16.757118Z","caller":"traceutil/trace.go:171","msg":"trace[696286180] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:759; }","duration":"292.52969ms","start":"2026-02-06T00:44:16.464581Z","end":"2026-02-06T00:44:16.757111Z","steps":["trace[696286180] 'agreement among raft nodes before linearized reading' (duration: 292.463638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:16.757065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.13069ms","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-06T00:44:16.757224Z","caller":"traceutil/trace.go:171","msg":"trace[604703903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:759; }","duration":"292.297574ms","start":"2026-02-06T00:44:16.464912Z","end":"2026-02-06T00:44:16.75721Z","steps":["trace[604703903] 'agreement among raft nodes before linearized reading' (duration: 291.959366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:16.757286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.411527ms","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-06T00:44:16.757311Z","caller":"traceutil/trace.go:171","msg":"trace[248671436] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:759; }","duration":"292.444527ms","start":"2026-02-06T00:44:16.464861Z","end":"2026-02-06T00:44:16.757306Z","steps":["trace[248671436] 'agreement among raft nodes before linearized reading' (duration: 292.387646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:17.43105Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581739927616503665,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-06T00:44:17.685604Z","caller":"traceutil/trace.go:171","msg":"trace[1550815410] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"924.953411ms","start":"2026-02-06T00:44:16.760628Z","end":"2026-02-06T00:44:17.685582Z","steps":["trace[1550815410] 'process raft request' (duration: 924.815958ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:17.685757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:16.760609Z","time spent":"925.063113ms","remote":"127.0.0.1:39258","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-06T00:44:17.690389Z","caller":"traceutil/trace.go:171","msg":"trace[1961374018] linearizableReadLoop","detail":"{readStateIndex:792; appliedIndex:789; }","duration":"760.116392ms","start":"2026-02-06T00:44:16.93019Z","end":"2026-02-06T00:44:17.690306Z","steps":["trace[1961374018] 'read index received' (duration: 756.042456ms)","trace[1961374018] 'applied index is now lower than readState.Index' (duration: 4.072986ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:44:17.690691Z","caller":"traceutil/trace.go:171","msg":"trace[1416331078] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"837.457047ms","start":"2026-02-06T00:44:16.853202Z","end":"2026-02-06T00:44:17.690659Z","steps":["trace[1416331078] 'process raft request' (duration: 837.068798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:17.690802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:16.853185Z","time spent":"837.561249ms","remote":"127.0.0.1:39258","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-06T00:44:17.690917Z","caller":"traceutil/trace.go:171","msg":"trace[1720677578] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"929.441858ms","start":"2026-02-06T00:44:16.761462Z","end":"2026-02-06T00:44:17.690904Z","steps":["trace[1720677578] 'process raft request' (duration: 928.663289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:17.691001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:16.761454Z","time spent":"929.490339ms","remote":"127.0.0.1:39258","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-06T00:44:17.691106Z","caller":"traceutil/trace.go:171","msg":"trace[1842943855] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"928.614698ms","start":"2026-02-06T00:44:16.762482Z","end":"2026-02-06T00:44:17.691096Z","steps":["trace[1842943855] 'process raft request' (duration: 927.756198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:17.691159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:16.762451Z","time spent":"928.68168ms","remote":"127.0.0.1:39258","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-06T00:44:17.691255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.815032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:44:17.691303Z","caller":"traceutil/trace.go:171","msg":"trace[2091582683] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:763; }","duration":"390.896904ms","start":"2026-02-06T00:44:17.300397Z","end":"2026-02-06T00:44:17.691294Z","steps":["trace[2091582683] 'agreement among raft nodes before linearized reading' (duration: 390.830742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:17.691354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:17.300378Z","time spent":"390.970145ms","remote":"127.0.0.1:38954","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-06T00:44:17.691842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.358742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T00:44:17.6919Z","caller":"traceutil/trace.go:171","msg":"trace[74151550] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:763; }","duration":"761.70486ms","start":"2026-02-06T00:44:16.930184Z","end":"2026-02-06T00:44:17.691889Z","steps":["trace[74151550] 'agreement among raft nodes before linearized reading' (duration: 761.310521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:17.691938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:16.930132Z","time spent":"761.794492ms","remote":"127.0.0.1:39178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":40,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-02-06T00:44:17.695007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.534156ms","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-06T00:44:17.695151Z","caller":"traceutil/trace.go:171","msg":"trace[1218587737] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:763; }","duration":"350.588057ms","start":"2026-02-06T00:44:17.344458Z","end":"2026-02-06T00:44:17.695046Z","steps":["trace[1218587737] 'agreement among raft nodes before linearized reading' (duration: 347.79153ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:17.695255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:17.344406Z","time spent":"350.779931ms","remote":"127.0.0.1:39258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-06T00:44:19.123301Z","caller":"traceutil/trace.go:171","msg":"trace[1965240546] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"356.771653ms","start":"2026-02-06T00:44:18.766504Z","end":"2026-02-06T00:44:19.123276Z","steps":["trace[1965240546] 'process raft request' (duration: 356.587399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:19.123454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:18.766488Z","time spent":"356.883586ms","remote":"127.0.0.1:39258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:44:20.053138Z","caller":"traceutil/trace.go:171","msg":"trace[491181284] linearizableReadLoop","detail":"{readStateIndex:795; appliedIndex:794; }","duration":"354.94407ms","start":"2026-02-06T00:44:19.698169Z","end":"2026-02-06T00:44:20.053113Z","steps":["trace[491181284] 'read index received' (duration: 354.750155ms)","trace[491181284] 'applied index is now lower than readState.Index' (duration: 193.035µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:44:20.053618Z","caller":"traceutil/trace.go:171","msg":"trace[707875178] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"360.204855ms","start":"2026-02-06T00:44:19.6934Z","end":"2026-02-06T00:44:20.053604Z","steps":["trace[707875178] 'process raft request' (duration: 359.58274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:20.053952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:19.693377Z","time spent":"360.497392ms","remote":"127.0.0.1:39258","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-06T00:44:20.054173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.880051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-06T00:44:20.054606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.010972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:44:20.054797Z","caller":"traceutil/trace.go:171","msg":"trace[1255943082] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:766; }","duration":"229.208747ms","start":"2026-02-06T00:44:19.82555Z","end":"2026-02-06T00:44:20.054759Z","steps":["trace[1255943082] 'agreement among raft nodes before linearized reading' (duration: 229.020033ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:44:20.055044Z","caller":"traceutil/trace.go:171","msg":"trace[1424296735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:766; }","duration":"352.978344ms","start":"2026-02-06T00:44:19.701253Z","end":"2026-02-06T00:44:20.054231Z","steps":["trace[1424296735] 'agreement among raft nodes before linearized reading' (duration: 352.483582ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:20.055451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.271655ms","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-06T00:44:20.055655Z","caller":"traceutil/trace.go:171","msg":"trace[747476025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:766; }","duration":"357.4768ms","start":"2026-02-06T00:44:19.698154Z","end":"2026-02-06T00:44:20.055631Z","steps":["trace[747476025] 'agreement among raft nodes before linearized reading' (duration: 357.199333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:20.055798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:19.698146Z","time spent":"357.637703ms","remote":"127.0.0.1:39258","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-06T00:44:20.055681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.998529ms","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-06T00:44:20.056153Z","caller":"traceutil/trace.go:171","msg":"trace[997232809] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:766; }","duration":"255.387068ms","start":"2026-02-06T00:44:19.800648Z","end":"2026-02-06T00:44:20.056035Z","steps":["trace[997232809] 'agreement among raft nodes before linearized reading' (duration: 254.915647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:20.055531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:19.701247Z","time spent":"353.851904ms","remote":"127.0.0.1:39258","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-06T00:44:21.019398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.004853ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:44:21.021504Z","caller":"traceutil/trace.go:171","msg":"trace[1800998788] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:795; }","duration":"721.397002ms","start":"2026-02-06T00:44:20.300082Z","end":"2026-02-06T00:44:21.02148Z","steps":["trace[1800998788] 'read index received' (duration: 463.250538ms)","trace[1800998788] 'applied index is now lower than readState.Index' (duration: 258.145404ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:44:21.021654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.538795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:44:21.021681Z","caller":"traceutil/trace.go:171","msg":"trace[28183006] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:770; }","duration":"721.636298ms","start":"2026-02-06T00:44:20.300039Z","end":"2026-02-06T00:44:21.021676Z","steps":["trace[28183006] 'agreement among raft nodes before linearized reading' (duration: 721.541456ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:21.021703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:20.300019Z","time spent":"721.678889ms","remote":"127.0.0.1:38954","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-06T00:44:21.022078Z","caller":"traceutil/trace.go:171","msg":"trace[2142782642] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"960.569666ms","start":"2026-02-06T00:44:20.061499Z","end":"2026-02-06T00:44:21.022069Z","steps":["trace[2142782642] 'process raft request' (duration: 701.812767ms)","trace[2142782642] 'compare' (duration: 255.907131ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:44:21.022412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:20.061477Z","time spent":"960.624417ms","remote":"127.0.0.1:39258","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-06T00:44:21.030509Z","caller":"traceutil/trace.go:171","msg":"trace[1848670197] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"967.412648ms","start":"2026-02-06T00:44:20.063086Z","end":"2026-02-06T00:44:21.030498Z","steps":["trace[1848670197] 'process raft request' (duration: 958.342883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:21.030577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:20.06308Z","time spent":"967.454369ms","remote":"127.0.0.1:39258","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-06T00:44:21.030836Z","caller":"traceutil/trace.go:171","msg":"trace[1060466098] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"968.287539ms","start":"2026-02-06T00:44:20.062536Z","end":"2026-02-06T00:44:21.030823Z","steps":["trace[1060466098] 'process raft request' (duration: 958.774823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:21.031806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:20.062526Z","time spent":"969.248172ms","remote":"127.0.0.1:39258","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-06T00:44:21.03299Z","caller":"traceutil/trace.go:171","msg":"trace[195896952] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"565.931465ms","start":"2026-02-06T00:44:20.467048Z","end":"2026-02-06T00:44:21.03298Z","steps":["trace[195896952] 'process raft request' (duration: 554.399951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:21.03308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:20.467015Z","time spent":"566.002397ms","remote":"127.0.0.1:39258","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-06T00:44:21.386188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.89617ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-06T00:44:21.386321Z","caller":"traceutil/trace.go:171","msg":"trace[1139814559] linearizableReadLoop","detail":"{readStateIndex:800; appliedIndex:799; }","duration":"223.513623ms","start":"2026-02-06T00:44:21.162789Z","end":"2026-02-06T00:44:21.386302Z","steps":["trace[1139814559] 'read index received' (duration: 56.042µs)","trace[1139814559] 'applied index is now lower than readState.Index' (duration: 223.456031ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:44:21.386466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.871035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T00:44:21.386495Z","caller":"traceutil/trace.go:171","msg":"trace[407249340] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:770; }","duration":"106.951377ms","start":"2026-02-06T00:44:21.279537Z","end":"2026-02-06T00:44:21.386488Z","steps":["trace[407249340] 'agreement among raft nodes before linearized reading' (duration: 106.882795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:21.386974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.191708ms","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-06T00:44:21.386995Z","caller":"traceutil/trace.go:171","msg":"trace[832396476] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:770; }","duration":"224.25606ms","start":"2026-02-06T00:44:21.162733Z","end":"2026-02-06T00:44:21.386989Z","steps":["trace[832396476] 'agreement among raft nodes before linearized reading' (duration: 223.654106ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:44:33.811709Z","caller":"traceutil/trace.go:171","msg":"trace[944833349] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"356.343523ms","start":"2026-02-06T00:44:33.455299Z","end":"2026-02-06T00:44:33.811642Z","steps":["trace[944833349] 'process raft request' (duration: 356.191039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:33.811858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:33.455288Z","time spent":"356.491176ms","remote":"127.0.0.1:39258","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-06T00:44:35.697536Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581739927616503895,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T00:44:36.198471Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581739927616503895,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T00:44:36.698716Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581739927616503895,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T00:44:37.199785Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581739927616503895,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-06T00:44:37.272693Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.102754823s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-06T00:44:37.297162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000817424s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-06T00:44:37.301508Z","caller":"traceutil/trace.go:171","msg":"trace[1367135111] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.005195518s","start":"2026-02-06T00:44:35.296299Z","end":"2026-02-06T00:44:37.301494Z","steps":["trace[1367135111] 'agreement among raft nodes before linearized reading' (duration: 2.000832495s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:37.29849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.030289844s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-06T00:44:37.29894Z","caller":"traceutil/trace.go:171","msg":"trace[1635177941] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"2.129056476s","start":"2026-02-06T00:44:35.169863Z","end":"2026-02-06T00:44:37.298919Z","steps":["trace[1635177941] 'process raft request' (duration: 2.102953577s)","trace[1635177941] 'store kv pair into bolt db' {req_type:put; key:/registry/leases/kube-system/kube-controller-manager; req_size:486; } (duration: 18.747204ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:44:37.299195Z","caller":"traceutil/trace.go:171","msg":"trace[1837949143] linearizableReadLoop","detail":"{readStateIndex:891; appliedIndex:890; }","duration":"2.102623349s","start":"2026-02-06T00:44:35.196544Z","end":"2026-02-06T00:44:37.299167Z","steps":["trace[1837949143] 'read index received' (duration: 2.076238443s)","trace[1837949143] 'applied index is now lower than readState.Index' (duration: 26.384286ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:44:37.299256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.102709641s","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-06T00:44:37.303155Z","caller":"traceutil/trace.go:171","msg":"trace[1230577841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:857; }","duration":"2.106602384s","start":"2026-02-06T00:44:35.196517Z","end":"2026-02-06T00:44:37.30312Z","steps":["trace[1230577841] 'agreement among raft nodes before linearized reading' (duration: 2.102689981s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:37.303226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:35.196505Z","time spent":"2.106696596s","remote":"127.0.0.1:39258","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-06T00:44:37.30336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:35.296289Z","time spent":"2.007066512s","remote":"127.0.0.1:38964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/06 00:44:37 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-02-06T00:44:37.303425Z","caller":"traceutil/trace.go:171","msg":"trace[613181877] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.035295832s","start":"2026-02-06T00:44:35.268125Z","end":"2026-02-06T00:44:37.30342Z","steps":["trace[613181877] 'agreement among raft nodes before linearized reading' (duration: 2.030311533s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:37.303441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:35.268112Z","time spent":"2.035325033s","remote":"127.0.0.1:38954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/06 00:44:37 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-06T00:44:37.303508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:35.169854Z","time spent":"2.133616424s","remote":"127.0.0.1:39258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:44:37.598747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.552794ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:44:37.599108Z","caller":"traceutil/trace.go:171","msg":"trace[1353117985] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"285.242237ms","start":"2026-02-06T00:44:37.313855Z","end":"2026-02-06T00:44:37.599097Z","steps":["trace[1353117985] 'process raft request' (duration: 284.96042ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:44:37.59913Z","caller":"traceutil/trace.go:171","msg":"trace[1704969081] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"300.284633ms","start":"2026-02-06T00:44:37.298824Z","end":"2026-02-06T00:44:37.599109Z","steps":["trace[1704969081] 'process raft request' (duration: 81.324809ms)","trace[1704969081] 'compare' (duration: 218.403791ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:44:37.599215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:37.298791Z","time spent":"300.378886ms","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-06T00:44:37.599242Z","caller":"traceutil/trace.go:171","msg":"trace[424285815] linearizableReadLoop","detail":"{readStateIndex:892; appliedIndex:891; }","duration":"295.664554ms","start":"2026-02-06T00:44:37.30357Z","end":"2026-02-06T00:44:37.599235Z","steps":["trace[424285815] 'read index received' (duration: 76.585137ms)","trace[424285815] 'applied index is now lower than readState.Index' (duration: 219.078327ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:44:37.599399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.473542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:44:37.59942Z","caller":"traceutil/trace.go:171","msg":"trace[443886788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:859; }","duration":"285.512903ms","start":"2026-02-06T00:44:37.313899Z","end":"2026-02-06T00:44:37.599412Z","steps":["trace[443886788] 'agreement among raft nodes before linearized reading' (duration: 285.476612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:37.599439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.402026682s","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-06T00:44:37.599458Z","caller":"traceutil/trace.go:171","msg":"trace[900365182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:859; }","duration":"2.402060572s","start":"2026-02-06T00:44:35.197392Z","end":"2026-02-06T00:44:37.599452Z","steps":["trace[900365182] 'agreement among raft nodes before linearized reading' (duration: 2.40197312s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:37.599473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:35.197387Z","time spent":"2.402082283s","remote":"127.0.0.1:39258","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-06T00:44:37.599581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.470049392s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-06T00:44:37.599594Z","caller":"traceutil/trace.go:171","msg":"trace[143715892] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:859; }","duration":"1.470083733s","start":"2026-02-06T00:44:36.129506Z","end":"2026-02-06T00:44:37.59959Z","steps":["trace[143715892] 'agreement among raft nodes before linearized reading' (duration: 1.470045333s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:37.599606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:36.129494Z","time spent":"1.470109054s","remote":"127.0.0.1:39258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-06T00:44:37.59969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.973473876s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-06T00:44:37.599712Z","caller":"traceutil/trace.go:171","msg":"trace[950502318] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:859; }","duration":"1.973527306s","start":"2026-02-06T00:44:35.626179Z","end":"2026-02-06T00:44:37.599706Z","steps":["trace[950502318] 'agreement among raft nodes before linearized reading' (duration: 1.973446264s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:37.59973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:35.626167Z","time spent":"1.973557218s","remote":"127.0.0.1:38994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.233\" "} {"level":"warn","ts":"2026-02-06T00:44:37.599778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.784577644s","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-06T00:44:37.599795Z","caller":"traceutil/trace.go:171","msg":"trace[1694420830] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:859; }","duration":"1.784613825s","start":"2026-02-06T00:44:35.815175Z","end":"2026-02-06T00:44:37.599789Z","steps":["trace[1694420830] 'agreement among raft nodes before linearized reading' (duration: 1.784571854s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:37.599808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:35.815162Z","time spent":"1.784643136s","remote":"127.0.0.1:39258","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-06T00:44:37.950291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.047952ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:44:37.950391Z","caller":"traceutil/trace.go:171","msg":"trace[123299403] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"342.447504ms","start":"2026-02-06T00:44:37.607926Z","end":"2026-02-06T00:44:37.950373Z","steps":["trace[123299403] 'process raft request' (duration: 221.227908ms)","trace[123299403] 'compare' (duration: 120.933919ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:44:37.950449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:37.607918Z","time spent":"342.503315ms","remote":"127.0.0.1:39258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:44:37.953791Z","caller":"traceutil/trace.go:171","msg":"trace[159378342] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"339.460953ms","start":"2026-02-06T00:44:37.614317Z","end":"2026-02-06T00:44:37.953778Z","steps":["trace[159378342] 'process raft request' (duration: 339.394511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:37.953933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:37.614308Z","time spent":"339.566975ms","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-06T00:44:37.954157Z","caller":"traceutil/trace.go:171","msg":"trace[1920512078] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"345.860854ms","start":"2026-02-06T00:44:37.608289Z","end":"2026-02-06T00:44:37.95415Z","steps":["trace[1920512078] 'process raft request' (duration: 345.24876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:37.954192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:37.608284Z","time spent":"345.889855ms","remote":"127.0.0.1:39258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:44:37.95419Z","caller":"traceutil/trace.go:171","msg":"trace[1895697007] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"345.841105ms","start":"2026-02-06T00:44:37.608315Z","end":"2026-02-06T00:44:37.954156Z","steps":["trace[1895697007] 'process raft request' (duration: 345.358993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:37.954261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:37.608084Z","time spent":"346.176602ms","remote":"127.0.0.1:38994","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-06T00:44:37.954268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:37.608312Z","time spent":"345.904556ms","remote":"127.0.0.1:39258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:44:37.95775Z","caller":"traceutil/trace.go:171","msg":"trace[1180234154] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"123.524931ms","start":"2026-02-06T00:44:37.834217Z","end":"2026-02-06T00:44:37.957742Z","steps":["trace[1180234154] 'process raft request' (duration: 123.469199ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:44:39.964477Z","caller":"traceutil/trace.go:171","msg":"trace[1465955645] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"643.038475ms","start":"2026-02-06T00:44:39.321431Z","end":"2026-02-06T00:44:39.96447Z","steps":["trace[1465955645] 'process raft request' (duration: 642.786879ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:39.964566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:39.321421Z","time spent":"643.097096ms","remote":"127.0.0.1:39258","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-06T00:44:39.964338Z","caller":"traceutil/trace.go:171","msg":"trace[24810193] linearizableReadLoop","detail":"{readStateIndex:901; appliedIndex:900; }","duration":"357.381247ms","start":"2026-02-06T00:44:39.606943Z","end":"2026-02-06T00:44:39.964324Z","steps":["trace[24810193] 'read index received' (duration: 357.221254ms)","trace[24810193] 'applied index is now lower than readState.Index' (duration: 159.223µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:44:39.965308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.352671ms","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-06T00:44:39.965359Z","caller":"traceutil/trace.go:171","msg":"trace[1075450297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:866; }","duration":"358.444463ms","start":"2026-02-06T00:44:39.606907Z","end":"2026-02-06T00:44:39.965352Z","steps":["trace[1075450297] 'agreement among raft nodes before linearized reading' (duration: 357.846729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:39.965378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:39.606891Z","time spent":"358.482374ms","remote":"127.0.0.1:39258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-06T00:44:40.120545Z","caller":"traceutil/trace.go:171","msg":"trace[763657720] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"142.528151ms","start":"2026-02-06T00:44:39.977987Z","end":"2026-02-06T00:44:40.120515Z","steps":["trace[763657720] 'process raft request' (duration: 139.669473ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:44:40.216548Z","caller":"traceutil/trace.go:171","msg":"trace[1890516254] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"238.130279ms","start":"2026-02-06T00:44:39.978399Z","end":"2026-02-06T00:44:40.216529Z","steps":["trace[1890516254] 'process raft request' (duration: 238.003116ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:44:40.21672Z","caller":"traceutil/trace.go:171","msg":"trace[445989264] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"238.236112ms","start":"2026-02-06T00:44:39.978477Z","end":"2026-02-06T00:44:40.216713Z","steps":["trace[445989264] 'process raft request' (duration: 238.027167ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:44:40.216837Z","caller":"traceutil/trace.go:171","msg":"trace[2042359313] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"238.20213ms","start":"2026-02-06T00:44:39.978617Z","end":"2026-02-06T00:44:40.216819Z","steps":["trace[2042359313] 'process raft request' (duration: 238.071617ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:44:44.223405Z","caller":"traceutil/trace.go:171","msg":"trace[1732107635] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"246.869196ms","start":"2026-02-06T00:44:43.976522Z","end":"2026-02-06T00:44:44.223391Z","steps":["trace[1732107635] 'process raft request' (duration: 246.523958ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:44:44.373168Z","caller":"traceutil/trace.go:171","msg":"trace[628330486] linearizableReadLoop","detail":"{readStateIndex:914; appliedIndex:913; }","duration":"113.281657ms","start":"2026-02-06T00:44:44.259868Z","end":"2026-02-06T00:44:44.37315Z","steps":["trace[628330486] 'read index received' (duration: 98.786474ms)","trace[628330486] 'applied index is now lower than readState.Index' (duration: 14.494403ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:44:44.37343Z","caller":"traceutil/trace.go:171","msg":"trace[1936432710] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"147.255833ms","start":"2026-02-06T00:44:44.226165Z","end":"2026-02-06T00:44:44.373421Z","steps":["trace[1936432710] 'process raft request' (duration: 132.538854ms)","trace[1936432710] 'compare' (duration: 14.369971ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:44:44.373615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.732828ms","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-06T00:44:44.373639Z","caller":"traceutil/trace.go:171","msg":"trace[1620402140] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:878; }","duration":"113.786949ms","start":"2026-02-06T00:44:44.259843Z","end":"2026-02-06T00:44:44.37363Z","steps":["trace[1620402140] 'agreement among raft nodes before linearized reading' (duration: 113.701107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:44.373752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.39641ms","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-06T00:44:44.373769Z","caller":"traceutil/trace.go:171","msg":"trace[1651040982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:878; }","duration":"113.422341ms","start":"2026-02-06T00:44:44.260342Z","end":"2026-02-06T00:44:44.373764Z","steps":["trace[1651040982] 'agreement among raft nodes before linearized reading' (duration: 113.37196ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:44.373982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.298663ms","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-06T00:44:44.374Z","caller":"traceutil/trace.go:171","msg":"trace[1463235006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:878; }","duration":"109.325234ms","start":"2026-02-06T00:44:44.26467Z","end":"2026-02-06T00:44:44.373995Z","steps":["trace[1463235006] 'agreement among raft nodes before linearized reading' (duration: 109.277992ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:44:44.55034Z","caller":"traceutil/trace.go:171","msg":"trace[1588592412] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"167.013581ms","start":"2026-02-06T00:44:44.383312Z","end":"2026-02-06T00:44:44.550326Z","steps":["trace[1588592412] 'process raft request' (duration: 166.988471ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:44:44.550874Z","caller":"traceutil/trace.go:171","msg":"trace[1184387269] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"169.432619ms","start":"2026-02-06T00:44:44.381434Z","end":"2026-02-06T00:44:44.550867Z","steps":["trace[1184387269] 'process raft request' (duration: 168.785844ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:45.605646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.473109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T00:44:45.605752Z","caller":"traceutil/trace.go:171","msg":"trace[601429968] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:881; }","duration":"666.604112ms","start":"2026-02-06T00:44:44.939125Z","end":"2026-02-06T00:44:45.605729Z","steps":["trace[601429968] 'count revisions from in-memory index tree' (duration: 666.395757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:45.605803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:44.939111Z","time spent":"666.679934ms","remote":"127.0.0.1:39388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-02-06T00:44:46.580324Z","caller":"traceutil/trace.go:171","msg":"trace[236841321] linearizableReadLoop","detail":"{readStateIndex:920; appliedIndex:919; }","duration":"274.955923ms","start":"2026-02-06T00:44:46.305348Z","end":"2026-02-06T00:44:46.580304Z","steps":["trace[236841321] 'read index received' (duration: 274.818ms)","trace[236841321] 'applied index is now lower than readState.Index' (duration: 130.003µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:44:46.580422Z","caller":"traceutil/trace.go:171","msg":"trace[691812754] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"314.08488ms","start":"2026-02-06T00:44:46.26633Z","end":"2026-02-06T00:44:46.580415Z","steps":["trace[691812754] 'process raft request' (duration: 313.825404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:46.580504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:46.266307Z","time spent":"314.132772ms","remote":"127.0.0.1:39258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:44:46.580665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.873468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-06T00:44:46.580747Z","caller":"traceutil/trace.go:171","msg":"trace[1558013622] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:883; }","duration":"199.431291ms","start":"2026-02-06T00:44:46.381299Z","end":"2026-02-06T00:44:46.58073Z","steps":["trace[1558013622] 'agreement among raft nodes before linearized reading' (duration: 199.264077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:46.58099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.523363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-06T00:44:46.581007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.69417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:44:46.581012Z","caller":"traceutil/trace.go:171","msg":"trace[1022855919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:883; }","duration":"121.576154ms","start":"2026-02-06T00:44:46.459427Z","end":"2026-02-06T00:44:46.581003Z","steps":["trace[1022855919] 'agreement among raft nodes before linearized reading' (duration: 121.522743ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:44:46.581031Z","caller":"traceutil/trace.go:171","msg":"trace[811102125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:883; }","duration":"275.756012ms","start":"2026-02-06T00:44:46.305268Z","end":"2026-02-06T00:44:46.581024Z","steps":["trace[811102125] 'agreement among raft nodes before linearized reading' (duration: 275.718161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:47.029567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.922945ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:44:47.029699Z","caller":"traceutil/trace.go:171","msg":"trace[1119207185] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"440.90656ms","start":"2026-02-06T00:44:46.588765Z","end":"2026-02-06T00:44:47.029671Z","steps":["trace[1119207185] 'process raft request' (duration: 116.775241ms)","trace[1119207185] 'compare' (duration: 323.280579ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:44:47.029789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:46.588755Z","time spent":"440.981172ms","remote":"127.0.0.1:39258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:44:47.409571Z","caller":"traceutil/trace.go:171","msg":"trace[820380585] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"820.569776ms","start":"2026-02-06T00:44:46.588978Z","end":"2026-02-06T00:44:47.409548Z","steps":["trace[820380585] 'process raft request' (duration: 820.471723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:47.409733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:46.588973Z","time spent":"820.663559ms","remote":"127.0.0.1:39258","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-06T00:44:47.40971Z","caller":"traceutil/trace.go:171","msg":"trace[590830758] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"820.661328ms","start":"2026-02-06T00:44:46.58894Z","end":"2026-02-06T00:44:47.409601Z","steps":["trace[590830758] 'process raft request' (duration: 795.499961ms)","trace[590830758] 'compare' (duration: 24.357108ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:44:47.409932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:46.588934Z","time spent":"820.881854ms","remote":"127.0.0.1:39258","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-06T00:44:47.410315Z","caller":"traceutil/trace.go:171","msg":"trace[1584420394] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"820.676228ms","start":"2026-02-06T00:44:46.589624Z","end":"2026-02-06T00:44:47.4103Z","steps":["trace[1584420394] 'process raft request' (duration: 819.88918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:47.410379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:46.589618Z","time spent":"820.72767ms","remote":"127.0.0.1:39258","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-06T00:44:48.82616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.142273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-06T00:44:48.826253Z","caller":"traceutil/trace.go:171","msg":"trace[1273594633] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:888; }","duration":"239.250115ms","start":"2026-02-06T00:44:48.586976Z","end":"2026-02-06T00:44:48.826226Z","steps":["trace[1273594633] 'range keys from in-memory index tree' (duration: 238.952389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:48.82747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.392273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T00:44:48.827503Z","caller":"traceutil/trace.go:171","msg":"trace[1233502422] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:888; }","duration":"216.476755ms","start":"2026-02-06T00:44:48.611018Z","end":"2026-02-06T00:44:48.827495Z","steps":["trace[1233502422] 'count revisions from in-memory index tree' (duration: 216.332542ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:44:49.196983Z","caller":"traceutil/trace.go:171","msg":"trace[1537945384] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"159.896433ms","start":"2026-02-06T00:44:49.037052Z","end":"2026-02-06T00:44:49.196948Z","steps":["trace[1537945384] 'process raft request' (duration: 159.630706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:50.437451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.483876ms","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-06T00:44:50.437619Z","caller":"traceutil/trace.go:171","msg":"trace[1850380616] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:891; }","duration":"619.707062ms","start":"2026-02-06T00:44:49.817888Z","end":"2026-02-06T00:44:50.437595Z","steps":["trace[1850380616] 'range keys from in-memory index tree' (duration: 619.448545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:50.438249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.520144ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:44:50.43838Z","caller":"traceutil/trace.go:171","msg":"trace[1098088338] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"1.012992761s","start":"2026-02-06T00:44:49.425373Z","end":"2026-02-06T00:44:50.438366Z","steps":["trace[1098088338] 'process raft request' (duration: 1.01294637s)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:44:50.438387Z","caller":"traceutil/trace.go:171","msg":"trace[596053433] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"1.013031341s","start":"2026-02-06T00:44:49.425346Z","end":"2026-02-06T00:44:50.438377Z","steps":["trace[596053433] 'process raft request' (duration: 797.316114ms)","trace[596053433] 'compare' (duration: 215.254737ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:44:50.438418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:49.42537Z","time spent":"1.013030932s","remote":"127.0.0.1:39258","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-06T00:44:50.43847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:49.425338Z","time spent":"1.013073503s","remote":"127.0.0.1:39258","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-06T00:44:51.326177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.243238ms","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-06T00:44:51.32625Z","caller":"traceutil/trace.go:171","msg":"trace[294226683] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:893; }","duration":"401.388371ms","start":"2026-02-06T00:44:50.924845Z","end":"2026-02-06T00:44:51.326233Z","steps":["trace[294226683] 'range keys from in-memory index tree' (duration: 401.097315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:51.326272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:50.924821Z","time spent":"401.445093ms","remote":"127.0.0.1:39258","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-06T00:44:51.326583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.607588ms","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-06T00:44:51.326599Z","caller":"traceutil/trace.go:171","msg":"trace[833015796] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:893; }","duration":"114.66579ms","start":"2026-02-06T00:44:51.211928Z","end":"2026-02-06T00:44:51.326594Z","steps":["trace[833015796] 'range keys from in-memory index tree' (duration: 114.516287ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:44:54.677304Z","caller":"traceutil/trace.go:171","msg":"trace[1165941897] linearizableReadLoop","detail":"{readStateIndex:941; appliedIndex:940; }","duration":"199.472342ms","start":"2026-02-06T00:44:54.477817Z","end":"2026-02-06T00:44:54.677289Z","steps":["trace[1165941897] 'read index received' (duration: 199.348829ms)","trace[1165941897] 'applied index is now lower than readState.Index' (duration: 122.863µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:44:54.677478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.643076ms","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-06T00:44:54.677495Z","caller":"traceutil/trace.go:171","msg":"trace[1900453890] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:903; }","duration":"199.690998ms","start":"2026-02-06T00:44:54.477799Z","end":"2026-02-06T00:44:54.67749Z","steps":["trace[1900453890] 'agreement among raft nodes before linearized reading' (duration: 199.580625ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:44:54.677608Z","caller":"traceutil/trace.go:171","msg":"trace[856767972] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"211.926407ms","start":"2026-02-06T00:44:54.465663Z","end":"2026-02-06T00:44:54.677589Z","steps":["trace[856767972] 'process raft request' (duration: 211.542608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:55.403135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.966292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:44:55.403273Z","caller":"traceutil/trace.go:171","msg":"trace[1384976211] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"721.404033ms","start":"2026-02-06T00:44:54.681858Z","end":"2026-02-06T00:44:55.403262Z","steps":["trace[1384976211] 'process raft request' (duration: 121.269087ms)","trace[1384976211] 'compare' (duration: 598.727093ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:44:55.403304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:54.681843Z","time spent":"721.446444ms","remote":"127.0.0.1:39258","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-06T00:44:55.710514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.881156ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:44:55.71077Z","caller":"traceutil/trace.go:171","msg":"trace[1158462153] linearizableReadLoop","detail":"{readStateIndex:944; appliedIndex:942; }","duration":"140.826901ms","start":"2026-02-06T00:44:55.569924Z","end":"2026-02-06T00:44:55.710751Z","steps":["trace[1158462153] 'read index received' (duration: 112.275803ms)","trace[1158462153] 'applied index is now lower than readState.Index' (duration: 28.549918ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:44:55.711112Z","caller":"traceutil/trace.go:171","msg":"trace[980218060] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"278.64416ms","start":"2026-02-06T00:44:55.432452Z","end":"2026-02-06T00:44:55.711096Z","steps":["trace[980218060] 'process raft request' (duration: 101.099948ms)","trace[980218060] 'compare' (duration: 176.746623ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:44:55.714103Z","caller":"traceutil/trace.go:171","msg":"trace[1139811274] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"274.946442ms","start":"2026-02-06T00:44:55.439141Z","end":"2026-02-06T00:44:55.714087Z","steps":["trace[1139811274] 'process raft request' (duration: 271.531471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:55.714211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.274052ms","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-06T00:44:55.71423Z","caller":"traceutil/trace.go:171","msg":"trace[170943207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:906; }","duration":"144.339434ms","start":"2026-02-06T00:44:55.569883Z","end":"2026-02-06T00:44:55.714223Z","steps":["trace[170943207] 'agreement among raft nodes before linearized reading' (duration: 144.286283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:56.462964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:55.716301Z","time spent":"746.658482ms","remote":"127.0.0.1:38994","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-06T00:44:57.33352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.399435ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:44:57.333616Z","caller":"traceutil/trace.go:171","msg":"trace[1282029459] linearizableReadLoop","detail":"{readStateIndex:946; appliedIndex:944; }","duration":"1.346147101s","start":"2026-02-06T00:44:55.987452Z","end":"2026-02-06T00:44:57.333599Z","steps":["trace[1282029459] 'read index received' (duration: 455.695479ms)","trace[1282029459] 'applied index is now lower than readState.Index' (duration: 890.450412ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:44:57.333797Z","caller":"traceutil/trace.go:171","msg":"trace[1245263254] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"1.601612251s","start":"2026-02-06T00:44:55.732176Z","end":"2026-02-06T00:44:57.333788Z","steps":["trace[1245263254] 'process raft request' (duration: 981.847548ms)","trace[1245263254] 'compare' (duration: 619.160039ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:44:57.333836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:55.73215Z","time spent":"1.601665793s","remote":"127.0.0.1:39258","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-06T00:44:57.333992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.34653482s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T00:44:57.334011Z","caller":"traceutil/trace.go:171","msg":"trace[390576124] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:907; }","duration":"1.346576491s","start":"2026-02-06T00:44:55.987425Z","end":"2026-02-06T00:44:57.334002Z","steps":["trace[390576124] 'agreement among raft nodes before linearized reading' (duration: 1.34653374s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:57.334027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:55.987411Z","time spent":"1.346611802s","remote":"127.0.0.1:39258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-02-06T00:44:57.336536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.109890577s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T00:44:57.336564Z","caller":"traceutil/trace.go:171","msg":"trace[301582636] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:908; }","duration":"1.109955889s","start":"2026-02-06T00:44:56.226599Z","end":"2026-02-06T00:44:57.336555Z","steps":["trace[301582636] 'agreement among raft nodes before linearized reading' (duration: 1.109881167s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:57.336585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:56.226545Z","time spent":"1.110035291s","remote":"127.0.0.1:39324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-02-06T00:44:57.336687Z","caller":"traceutil/trace.go:171","msg":"trace[1216226264] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"853.739711ms","start":"2026-02-06T00:44:56.48294Z","end":"2026-02-06T00:44:57.33668Z","steps":["trace[1216226264] 'process raft request' (duration: 853.477945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:57.336722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:56.482908Z","time spent":"853.791122ms","remote":"127.0.0.1:38994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:44:57.336904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.340408ms","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-06T00:44:57.33692Z","caller":"traceutil/trace.go:171","msg":"trace[1703842952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:908; }","duration":"634.375588ms","start":"2026-02-06T00:44:56.702537Z","end":"2026-02-06T00:44:57.336913Z","steps":["trace[1703842952] 'agreement among raft nodes before linearized reading' (duration: 634.289626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:57.336934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:56.702525Z","time spent":"634.405909ms","remote":"127.0.0.1:39258","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-06T00:44:57.337026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.027329199s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:44:57.337038Z","caller":"traceutil/trace.go:171","msg":"trace[1823417196] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:908; }","duration":"1.02737707s","start":"2026-02-06T00:44:56.309657Z","end":"2026-02-06T00:44:57.337034Z","steps":["trace[1823417196] 'agreement among raft nodes before linearized reading' (duration: 1.02735577s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:57.337049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:56.309641Z","time spent":"1.02740502s","remote":"127.0.0.1:38954","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-06T00:44:58.01694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.478015ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:44:58.01701Z","caller":"traceutil/trace.go:171","msg":"trace[501999726] linearizableReadLoop","detail":"{readStateIndex:948; appliedIndex:947; }","duration":"664.661ms","start":"2026-02-06T00:44:57.352336Z","end":"2026-02-06T00:44:58.016997Z","steps":["trace[501999726] 'read index received' (duration: 113.172895ms)","trace[501999726] 'applied index is now lower than readState.Index' (duration: 551.487195ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:44:58.017142Z","caller":"traceutil/trace.go:171","msg":"trace[1069299489] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"664.938536ms","start":"2026-02-06T00:44:57.352195Z","end":"2026-02-06T00:44:58.017133Z","steps":["trace[1069299489] 'process raft request' (duration: 113.212575ms)","trace[1069299489] 'compare' (duration: 551.163638ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:44:58.017182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:57.352182Z","time spent":"664.978767ms","remote":"127.0.0.1:39258","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-06T00:44:58.017309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.969017ms","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-06T00:44:58.017355Z","caller":"traceutil/trace.go:171","msg":"trace[1636681996] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:909; }","duration":"665.022928ms","start":"2026-02-06T00:44:57.352326Z","end":"2026-02-06T00:44:58.017349Z","steps":["trace[1636681996] 'agreement among raft nodes before linearized reading' (duration: 664.955557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:58.017382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:57.352321Z","time spent":"665.048549ms","remote":"127.0.0.1:39262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-02-06T00:44:58.017481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.257756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:44:58.017498Z","caller":"traceutil/trace.go:171","msg":"trace[1607574475] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:909; }","duration":"663.273537ms","start":"2026-02-06T00:44:57.354219Z","end":"2026-02-06T00:44:58.017492Z","steps":["trace[1607574475] 'agreement among raft nodes before linearized reading' (duration: 663.248136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:58.017512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:57.354202Z","time spent":"663.307308ms","remote":"127.0.0.1:38954","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-06T00:44:58.017974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.112276ms","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-06T00:44:58.017998Z","caller":"traceutil/trace.go:171","msg":"trace[1765629630] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:909; }","duration":"283.159877ms","start":"2026-02-06T00:44:57.734831Z","end":"2026-02-06T00:44:58.017991Z","steps":["trace[1765629630] 'agreement among raft nodes before linearized reading' (duration: 282.795659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:58.018122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.063691ms","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-06T00:44:58.018152Z","caller":"traceutil/trace.go:171","msg":"trace[1975676661] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:909; }","duration":"296.140023ms","start":"2026-02-06T00:44:57.721993Z","end":"2026-02-06T00:44:58.018133Z","steps":["trace[1975676661] 'agreement among raft nodes before linearized reading' (duration: 296.089951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:58.018295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.900348ms","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-06T00:44:58.018312Z","caller":"traceutil/trace.go:171","msg":"trace[686789135] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:909; }","duration":"583.97265ms","start":"2026-02-06T00:44:57.434334Z","end":"2026-02-06T00:44:58.018307Z","steps":["trace[686789135] 'agreement among raft nodes before linearized reading' (duration: 583.922229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:58.018328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:57.434305Z","time spent":"584.018611ms","remote":"127.0.0.1:39258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-06T00:44:58.381309Z","caller":"traceutil/trace.go:171","msg":"trace[624720745] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"357.416915ms","start":"2026-02-06T00:44:58.02387Z","end":"2026-02-06T00:44:58.381287Z","steps":["trace[624720745] 'process raft request' (duration: 349.351025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:58.381497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:58.023856Z","time spent":"357.558819ms","remote":"127.0.0.1:39258","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-06T00:44:58.3863Z","caller":"traceutil/trace.go:171","msg":"trace[1984238794] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:948; }","duration":"142.785712ms","start":"2026-02-06T00:44:58.243496Z","end":"2026-02-06T00:44:58.386282Z","steps":["trace[1984238794] 'read index received' (duration: 129.736595ms)","trace[1984238794] 'applied index is now lower than readState.Index' (duration: 13.048397ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:44:58.386418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.899495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T00:44:58.386442Z","caller":"traceutil/trace.go:171","msg":"trace[1359419706] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:912; }","duration":"142.969887ms","start":"2026-02-06T00:44:58.243464Z","end":"2026-02-06T00:44:58.386433Z","steps":["trace[1359419706] 'agreement among raft nodes before linearized reading' (duration: 142.885755ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:44:58.386489Z","caller":"traceutil/trace.go:171","msg":"trace[586468546] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"362.394693ms","start":"2026-02-06T00:44:58.024073Z","end":"2026-02-06T00:44:58.386468Z","steps":["trace[586468546] 'process raft request' (duration: 362.093426ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:44:58.386606Z","caller":"traceutil/trace.go:171","msg":"trace[2006347938] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"360.662393ms","start":"2026-02-06T00:44:58.025936Z","end":"2026-02-06T00:44:58.386599Z","steps":["trace[2006347938] 'process raft request' (duration: 360.319854ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:58.386612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:58.024067Z","time spent":"362.475155ms","remote":"127.0.0.1:39258","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-06T00:44:58.386667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:58.025928Z","time spent":"360.696133ms","remote":"127.0.0.1:39258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:44:58.708003Z","caller":"traceutil/trace.go:171","msg":"trace[1432821114] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"133.787ms","start":"2026-02-06T00:44:58.5742Z","end":"2026-02-06T00:44:58.707987Z","steps":["trace[1432821114] 'process raft request' (duration: 133.694098ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:44:59.670922Z","caller":"traceutil/trace.go:171","msg":"trace[1293679367] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:952; }","duration":"322.881122ms","start":"2026-02-06T00:44:59.348007Z","end":"2026-02-06T00:44:59.670888Z","steps":["trace[1293679367] 'read index received' (duration: 322.597825ms)","trace[1293679367] 'applied index is now lower than readState.Index' (duration: 281.887µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:44:59.671018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.990636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:44:59.671039Z","caller":"traceutil/trace.go:171","msg":"trace[2019424339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:914; }","duration":"323.040837ms","start":"2026-02-06T00:44:59.347992Z","end":"2026-02-06T00:44:59.671033Z","steps":["trace[2019424339] 'agreement among raft nodes before linearized reading' (duration: 322.985645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:59.671061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:59.347985Z","time spent":"323.070667ms","remote":"127.0.0.1:38954","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-06T00:44:59.671095Z","caller":"traceutil/trace.go:171","msg":"trace[1170314447] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"330.226326ms","start":"2026-02-06T00:44:59.340851Z","end":"2026-02-06T00:44:59.671077Z","steps":["trace[1170314447] 'process raft request' (duration: 329.877137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:44:59.671185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:44:59.340807Z","time spent":"330.328428ms","remote":"127.0.0.1:39258","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-06T00:45:00.156044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.637067ms","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-06T00:45:00.156152Z","caller":"traceutil/trace.go:171","msg":"trace[257281686] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:914; }","duration":"339.75644ms","start":"2026-02-06T00:44:59.81638Z","end":"2026-02-06T00:45:00.156137Z","steps":["trace[257281686] 'range keys from in-memory index tree' (duration: 339.616686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:00.156346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.45508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T00:45:00.156375Z","caller":"traceutil/trace.go:171","msg":"trace[262609609] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:914; }","duration":"293.488571ms","start":"2026-02-06T00:44:59.862878Z","end":"2026-02-06T00:45:00.156366Z","steps":["trace[262609609] 'count revisions from in-memory index tree' (duration: 293.404519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:00.156698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.327162ms","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-06T00:45:00.156728Z","caller":"traceutil/trace.go:171","msg":"trace[591871859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:914; }","duration":"134.395334ms","start":"2026-02-06T00:45:00.022324Z","end":"2026-02-06T00:45:00.156719Z","steps":["trace[591871859] 'range keys from in-memory index tree' (duration: 134.179509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:02.445484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.85437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:45:02.445728Z","caller":"traceutil/trace.go:171","msg":"trace[2054139418] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:934; }","duration":"139.130916ms","start":"2026-02-06T00:45:02.306584Z","end":"2026-02-06T00:45:02.445715Z","steps":["trace[2054139418] 'count revisions from in-memory index tree' (duration: 138.805629ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:45:04.788256Z","caller":"traceutil/trace.go:171","msg":"trace[1214103093] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"430.020644ms","start":"2026-02-06T00:45:04.358226Z","end":"2026-02-06T00:45:04.788246Z","steps":["trace[1214103093] 'process raft request' (duration: 429.690377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:04.790095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:45:04.358217Z","time spent":"431.843578ms","remote":"127.0.0.1:39258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-06T00:45:04.788115Z","caller":"traceutil/trace.go:171","msg":"trace[853862843] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"321.256744ms","start":"2026-02-06T00:45:04.46684Z","end":"2026-02-06T00:45:04.788097Z","steps":["trace[853862843] 'read index received' (duration: 320.489346ms)","trace[853862843] 'applied index is now lower than readState.Index' (duration: 766.698µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:45:04.790035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.174969ms","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-06T00:45:04.790324Z","caller":"traceutil/trace.go:171","msg":"trace[1696309440] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:951; }","duration":"323.507297ms","start":"2026-02-06T00:45:04.466805Z","end":"2026-02-06T00:45:04.790313Z","steps":["trace[1696309440] 'agreement among raft nodes before linearized reading' (duration: 323.050896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:04.790347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:45:04.466787Z","time spent":"323.554808ms","remote":"127.0.0.1:39258","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-06T00:45:04.790437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.663947ms","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-06T00:45:04.790469Z","caller":"traceutil/trace.go:171","msg":"trace[1856604594] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:951; }","duration":"322.694907ms","start":"2026-02-06T00:45:04.467756Z","end":"2026-02-06T00:45:04.790451Z","steps":["trace[1856604594] 'agreement among raft nodes before linearized reading' (duration: 322.647676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:04.790481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:45:04.46775Z","time spent":"322.727769ms","remote":"127.0.0.1:39258","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-06T00:45:04.7906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.460603ms","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-06T00:45:04.790619Z","caller":"traceutil/trace.go:171","msg":"trace[814145465] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:951; }","duration":"322.489873ms","start":"2026-02-06T00:45:04.468124Z","end":"2026-02-06T00:45:04.790614Z","steps":["trace[814145465] 'agreement among raft nodes before linearized reading' (duration: 322.439872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:04.790633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:45:04.468118Z","time spent":"322.511104ms","remote":"127.0.0.1:39258","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":"info","ts":"2026-02-06T00:45:04.905007Z","caller":"traceutil/trace.go:171","msg":"trace[156945796] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"111.477435ms","start":"2026-02-06T00:45:04.793511Z","end":"2026-02-06T00:45:04.904988Z","steps":["trace[156945796] 'process raft request' (duration: 103.008065ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:45:04.909627Z","caller":"traceutil/trace.go:171","msg":"trace[772354843] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"114.660199ms","start":"2026-02-06T00:45:04.794955Z","end":"2026-02-06T00:45:04.909615Z","steps":["trace[772354843] 'process raft request' (duration: 114.460945ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:45:04.90977Z","caller":"traceutil/trace.go:171","msg":"trace[2026423445] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:991; }","duration":"111.452484ms","start":"2026-02-06T00:45:04.79831Z","end":"2026-02-06T00:45:04.909763Z","steps":["trace[2026423445] 'read index received' (duration: 98.218042ms)","trace[2026423445] 'applied index is now lower than readState.Index' (duration: 13.233782ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:45:04.909821Z","caller":"traceutil/trace.go:171","msg":"trace[1256025217] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"113.773449ms","start":"2026-02-06T00:45:04.796042Z","end":"2026-02-06T00:45:04.909816Z","steps":["trace[1256025217] 'process raft request' (duration: 113.447121ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:45:04.911006Z","caller":"traceutil/trace.go:171","msg":"trace[1717104214] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"108.452403ms","start":"2026-02-06T00:45:04.801457Z","end":"2026-02-06T00:45:04.90991Z","steps":["trace[1717104214] 'process raft request' (duration: 108.048584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:04.911279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.956269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-06T00:45:04.911313Z","caller":"traceutil/trace.go:171","msg":"trace[1170917125] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:955; }","duration":"113.007461ms","start":"2026-02-06T00:45:04.798297Z","end":"2026-02-06T00:45:04.911305Z","steps":["trace[1170917125] 'agreement among raft nodes before linearized reading' (duration: 111.492525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:11.183889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.357117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:45:11.184076Z","caller":"traceutil/trace.go:171","msg":"trace[425472501] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"228.763056ms","start":"2026-02-06T00:45:10.955292Z","end":"2026-02-06T00:45:11.184055Z","steps":["trace[425472501] 'process raft request' (duration: 228.688644ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:45:11.184634Z","caller":"traceutil/trace.go:171","msg":"trace[874648002] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"231.110771ms","start":"2026-02-06T00:45:10.95351Z","end":"2026-02-06T00:45:11.184621Z","steps":["trace[874648002] 'process raft request' (duration: 32.946085ms)","trace[874648002] 'compare' (duration: 197.200604ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:45:17.935274Z","caller":"traceutil/trace.go:171","msg":"trace[77645199] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"167.393781ms","start":"2026-02-06T00:45:17.767857Z","end":"2026-02-06T00:45:17.935251Z","steps":["trace[77645199] 'process raft request' (duration: 167.237758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:18.266157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.765425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-06T00:45:18.266246Z","caller":"traceutil/trace.go:171","msg":"trace[1069773477] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1032; }","duration":"239.904568ms","start":"2026-02-06T00:45:18.026319Z","end":"2026-02-06T00:45:18.266224Z","steps":["trace[1069773477] 'range keys from in-memory index tree' (duration: 239.511149ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:45:18.402709Z","caller":"traceutil/trace.go:171","msg":"trace[1448744955] linearizableReadLoop","detail":"{readStateIndex:1076; appliedIndex:1075; }","duration":"102.303099ms","start":"2026-02-06T00:45:18.300387Z","end":"2026-02-06T00:45:18.40269Z","steps":["trace[1448744955] 'read index received' (duration: 102.211387ms)","trace[1448744955] 'applied index is now lower than readState.Index' (duration: 90.992µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:45:18.402807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.408731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:45:18.402829Z","caller":"traceutil/trace.go:171","msg":"trace[1739335835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1033; }","duration":"102.492693ms","start":"2026-02-06T00:45:18.300328Z","end":"2026-02-06T00:45:18.402821Z","steps":["trace[1739335835] 'agreement among raft nodes before linearized reading' (duration: 102.440632ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:45:18.403065Z","caller":"traceutil/trace.go:171","msg":"trace[1455473553] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"132.047569ms","start":"2026-02-06T00:45:18.271009Z","end":"2026-02-06T00:45:18.403056Z","steps":["trace[1455473553] 'process raft request' (duration: 131.538057ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:45:19.216091Z","caller":"traceutil/trace.go:171","msg":"trace[1742757168] linearizableReadLoop","detail":"{readStateIndex:1078; appliedIndex:1077; }","duration":"233.749844ms","start":"2026-02-06T00:45:18.982316Z","end":"2026-02-06T00:45:19.216066Z","steps":["trace[1742757168] 'read index received' (duration: 233.540909ms)","trace[1742757168] 'applied index is now lower than readState.Index' (duration: 208.155µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:45:19.21643Z","caller":"traceutil/trace.go:171","msg":"trace[808855771] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"366.97773ms","start":"2026-02-06T00:45:18.849426Z","end":"2026-02-06T00:45:19.216404Z","steps":["trace[808855771] 'process raft request' (duration: 366.494929ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:45:19.217686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:45:18.849365Z","time spent":"368.22267ms","remote":"127.0.0.1:39258","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-06T00:45:19.216642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.298786ms","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-06T00:45:19.21803Z","caller":"traceutil/trace.go:171","msg":"trace[1136213318] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1035; }","duration":"235.74224ms","start":"2026-02-06T00:45:18.982264Z","end":"2026-02-06T00:45:19.218007Z","steps":["trace[1136213318] 'agreement among raft nodes before linearized reading' (duration: 234.237725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:48:28.604385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.048516ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:48:28.60511Z","caller":"traceutil/trace.go:171","msg":"trace[1786465753] linearizableReadLoop","detail":"{readStateIndex:1918; appliedIndex:1917; }","duration":"308.275436ms","start":"2026-02-06T00:48:28.296824Z","end":"2026-02-06T00:48:28.605099Z","steps":["trace[1786465753] 'read index received' (duration: 29.35µs)","trace[1786465753] 'applied index is now lower than readState.Index' (duration: 308.245186ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:48:28.605289Z","caller":"traceutil/trace.go:171","msg":"trace[665176489] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"344.992514ms","start":"2026-02-06T00:48:28.260286Z","end":"2026-02-06T00:48:28.605279Z","steps":["trace[665176489] 'process raft request' (duration: 32.003707ms)","trace[665176489] 'compare' (duration: 311.912322ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:48:28.605452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:48:28.260265Z","time spent":"345.146727ms","remote":"127.0.0.1:39258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:48:28.60573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.903271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:48:28.606495Z","caller":"traceutil/trace.go:171","msg":"trace[1983978151] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1836; }","duration":"309.68536ms","start":"2026-02-06T00:48:28.296799Z","end":"2026-02-06T00:48:28.606484Z","steps":["trace[1983978151] 'agreement among raft nodes before linearized reading' (duration: 308.911121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:48:28.606635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:48:28.296786Z","time spent":"309.826103ms","remote":"127.0.0.1:38954","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-06T00:48:28.606003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.875149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-06T00:48:28.606902Z","caller":"traceutil/trace.go:171","msg":"trace[418772601] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1836; }","duration":"193.677698ms","start":"2026-02-06T00:48:28.413072Z","end":"2026-02-06T00:48:28.606749Z","steps":["trace[418772601] 'agreement among raft nodes before linearized reading' (duration: 192.584282ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:48:28.740946Z","caller":"traceutil/trace.go:171","msg":"trace[611989666] linearizableReadLoop","detail":"{readStateIndex:1919; appliedIndex:1918; }","duration":"102.80478ms","start":"2026-02-06T00:48:28.63812Z","end":"2026-02-06T00:48:28.740925Z","steps":["trace[611989666] 'read index received' (duration: 101.447148ms)","trace[611989666] 'applied index is now lower than readState.Index' (duration: 1.356572ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:48:28.741537Z","caller":"traceutil/trace.go:171","msg":"trace[1667922147] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"131.565699ms","start":"2026-02-06T00:48:28.609956Z","end":"2026-02-06T00:48:28.741522Z","steps":["trace[1667922147] 'process raft request' (duration: 129.778617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:48:28.742148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.958527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T00:48:28.742345Z","caller":"traceutil/trace.go:171","msg":"trace[1136844423] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1837; }","duration":"104.246703ms","start":"2026-02-06T00:48:28.638083Z","end":"2026-02-06T00:48:28.74233Z","steps":["trace[1136844423] 'agreement among raft nodes before linearized reading' (duration: 103.951446ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:48:35.797202Z","caller":"traceutil/trace.go:171","msg":"trace[666902767] linearizableReadLoop","detail":"{readStateIndex:1953; appliedIndex:1952; }","duration":"155.176837ms","start":"2026-02-06T00:48:35.641936Z","end":"2026-02-06T00:48:35.797113Z","steps":["trace[666902767] 'read index received' (duration: 154.759678ms)","trace[666902767] 'applied index is now lower than readState.Index' (duration: 416.329µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:48:35.798011Z","caller":"traceutil/trace.go:171","msg":"trace[1935225026] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"161.256161ms","start":"2026-02-06T00:48:35.636737Z","end":"2026-02-06T00:48:35.797993Z","steps":["trace[1935225026] 'process raft request' (duration: 159.91371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:48:35.798556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.604062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-06T00:48:35.798604Z","caller":"traceutil/trace.go:171","msg":"trace[606640574] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:1870; }","duration":"156.680293ms","start":"2026-02-06T00:48:35.641913Z","end":"2026-02-06T00:48:35.798593Z","steps":["trace[606640574] 'agreement among raft nodes before linearized reading' (duration: 156.353086ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:48:35.859584Z","caller":"traceutil/trace.go:171","msg":"trace[1731593956] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"204.929323ms","start":"2026-02-06T00:48:35.654637Z","end":"2026-02-06T00:48:35.859566Z","steps":["trace[1731593956] 'process raft request' (duration: 204.826801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:48:35.859993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.186394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T00:48:35.860014Z","caller":"traceutil/trace.go:171","msg":"trace[2015944468] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1871; }","duration":"187.239716ms","start":"2026-02-06T00:48:35.672768Z","end":"2026-02-06T00:48:35.860008Z","steps":["trace[2015944468] 'agreement among raft nodes before linearized reading' (duration: 187.192345ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:48:37.090129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.69133ms","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-06T00:48:37.09018Z","caller":"traceutil/trace.go:171","msg":"trace[635078283] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1874; }","duration":"444.854805ms","start":"2026-02-06T00:48:36.645313Z","end":"2026-02-06T00:48:37.090168Z","steps":["trace[635078283] 'range keys from in-memory index tree' (duration: 444.528757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:48:37.090201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:48:36.645285Z","time spent":"444.912035ms","remote":"127.0.0.1:39258","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-06T00:48:37.09044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.319993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:48:37.090455Z","caller":"traceutil/trace.go:171","msg":"trace[1577486006] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1874; }","duration":"172.362633ms","start":"2026-02-06T00:48:36.918087Z","end":"2026-02-06T00:48:37.09045Z","steps":["trace[1577486006] 'range keys from in-memory index tree' (duration: 172.267582ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:48:37.09057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.182908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-06T00:48:37.090587Z","caller":"traceutil/trace.go:171","msg":"trace[545290033] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1874; }","duration":"257.281671ms","start":"2026-02-06T00:48:36.833301Z","end":"2026-02-06T00:48:37.090582Z","steps":["trace[545290033] 'range keys from in-memory index tree' (duration: 256.973534ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:48:37.290465Z","caller":"traceutil/trace.go:171","msg":"trace[1802772059] linearizableReadLoop","detail":"{readStateIndex:1960; appliedIndex:1958; }","duration":"192.571661ms","start":"2026-02-06T00:48:37.09787Z","end":"2026-02-06T00:48:37.290442Z","steps":["trace[1802772059] 'read index received' (duration: 70.795793ms)","trace[1802772059] 'applied index is now lower than readState.Index' (duration: 121.774748ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:48:37.290907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.724416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:48:37.290964Z","caller":"traceutil/trace.go:171","msg":"trace[1740398497] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1877; }","duration":"193.112724ms","start":"2026-02-06T00:48:37.097834Z","end":"2026-02-06T00:48:37.290947Z","steps":["trace[1740398497] 'agreement among raft nodes before linearized reading' (duration: 192.701475ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:48:37.291574Z","caller":"traceutil/trace.go:171","msg":"trace[1547410687] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"194.62113ms","start":"2026-02-06T00:48:37.096938Z","end":"2026-02-06T00:48:37.291559Z","steps":["trace[1547410687] 'process raft request' (duration: 193.308099ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:48:37.291711Z","caller":"traceutil/trace.go:171","msg":"trace[1746940838] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"171.205536ms","start":"2026-02-06T00:48:37.120496Z","end":"2026-02-06T00:48:37.291702Z","steps":["trace[1746940838] 'process raft request' (duration: 169.900245ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:49:11.856638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.461786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:49:11.856715Z","caller":"traceutil/trace.go:171","msg":"trace[2000065691] linearizableReadLoop","detail":"{readStateIndex:2116; appliedIndex:2115; }","duration":"192.593592ms","start":"2026-02-06T00:49:11.664108Z","end":"2026-02-06T00:49:11.856702Z","steps":["trace[2000065691] 'read index received' (duration: 85.957142ms)","trace[2000065691] 'applied index is now lower than readState.Index' (duration: 106.63565ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:49:11.856871Z","caller":"traceutil/trace.go:171","msg":"trace[1940686108] transaction","detail":"{read_only:false; response_revision:2025; number_of_response:1; }","duration":"255.797696ms","start":"2026-02-06T00:49:11.601065Z","end":"2026-02-06T00:49:11.856862Z","steps":["trace[1940686108] 'process raft request' (duration: 149.050993ms)","trace[1940686108] 'compare' (duration: 106.368664ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:49:11.857043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.94238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/ceph-csi-rbd-provisioner-http-metrics\" ","response":"range_response_count:1 size:1195"} {"level":"info","ts":"2026-02-06T00:49:11.85707Z","caller":"traceutil/trace.go:171","msg":"trace[1806304620] range","detail":"{range_begin:/registry/services/specs/kube-system/ceph-csi-rbd-provisioner-http-metrics; range_end:; response_count:1; response_revision:2025; }","duration":"192.990381ms","start":"2026-02-06T00:49:11.664071Z","end":"2026-02-06T00:49:11.857061Z","steps":["trace[1806304620] 'agreement among raft nodes before linearized reading' (duration: 192.90832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:49:11.857216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.99599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/ceph-csi-rbd-nodeplugin-http-metrics\" ","response":"range_response_count:1 size:1189"} {"level":"info","ts":"2026-02-06T00:49:11.857235Z","caller":"traceutil/trace.go:171","msg":"trace[264673856] range","detail":"{range_begin:/registry/services/specs/kube-system/ceph-csi-rbd-nodeplugin-http-metrics; range_end:; response_count:1; response_revision:2025; }","duration":"154.04212ms","start":"2026-02-06T00:49:11.703187Z","end":"2026-02-06T00:49:11.857229Z","steps":["trace[264673856] 'agreement among raft nodes before linearized reading' (duration: 153.988109ms)"],"step_count":1}