{"level":"warn","ts":"2026-04-02T22:23:27.137893Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-02T22:23:27.138011Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.102: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.102:2380","--initial-cluster=instance=https://199.204.45.102:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.102:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.102: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-04-02T22:23:27.138082Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-02T22:23:27.138096Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.102:2380"]} {"level":"info","ts":"2026-04-02T22:23:27.138117Z","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-04-02T22:23:27.138938Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.102:2379"]} {"level":"info","ts":"2026-04-02T22:23:27.139037Z","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":8,"max-cpu-available":8,"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.102:2380"],"listen-peer-urls":["https://199.204.45.102:2380"],"advertise-client-urls":["https://199.204.45.102:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.102:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.102: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-04-02T22:23:27.59777Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"458.472945ms"} {"level":"info","ts":"2026-04-02T22:23:27.835908Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6e7960f761c1028e","cluster-id":"502e2b31b867adf"} {"level":"info","ts":"2026-04-02T22:23:27.836044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e switched to configuration voters=()"} {"level":"info","ts":"2026-04-02T22:23:27.836076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e became follower at term 0"} {"level":"info","ts":"2026-04-02T22:23:27.836103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6e7960f761c1028e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-02T22:23:27.836131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e became follower at term 1"} {"level":"info","ts":"2026-04-02T22:23:27.836186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e switched to configuration voters=(7960500431967289998)"} {"level":"warn","ts":"2026-04-02T22:23:28.091656Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-02T22:23:28.2142Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-02T22:23:28.268401Z","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-04-02T22:23:28.279435Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6e7960f761c1028e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-02T22:23:28.279552Z","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-04-02T22:23:28.279694Z","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-04-02T22:23:28.279733Z","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-04-02T22:23:28.279636Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6e7960f761c1028e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-02T22:23:28.28322Z","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-04-02T22:23:28.283321Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.102:2380"} {"level":"info","ts":"2026-04-02T22:23:28.283365Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.102:2380"} {"level":"info","ts":"2026-04-02T22:23:28.28343Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6e7960f761c1028e","initial-advertise-peer-urls":["https://199.204.45.102:2380"],"listen-peer-urls":["https://199.204.45.102:2380"],"advertise-client-urls":["https://199.204.45.102:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.102:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-02T22:23:28.28348Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-02T22:23:28.322254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e switched to configuration voters=(7960500431967289998)"} {"level":"info","ts":"2026-04-02T22:23:28.322419Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"502e2b31b867adf","local-member-id":"6e7960f761c1028e","added-peer-id":"6e7960f761c1028e","added-peer-peer-urls":["https://199.204.45.102:2380"]} {"level":"info","ts":"2026-04-02T22:23:28.456042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e is starting a new election at term 1"} {"level":"info","ts":"2026-04-02T22:23:28.456182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e became pre-candidate at term 1"} {"level":"info","ts":"2026-04-02T22:23:28.456283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e received MsgPreVoteResp from 6e7960f761c1028e at term 1"} {"level":"info","ts":"2026-04-02T22:23:28.456382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e became candidate at term 2"} {"level":"info","ts":"2026-04-02T22:23:28.456444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e received MsgVoteResp from 6e7960f761c1028e at term 2"} {"level":"info","ts":"2026-04-02T22:23:28.456545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e became leader at term 2"} {"level":"info","ts":"2026-04-02T22:23:28.456598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6e7960f761c1028e elected leader 6e7960f761c1028e at term 2"} {"level":"info","ts":"2026-04-02T22:23:28.474433Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T22:23:28.478627Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T22:23:28.47862Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6e7960f761c1028e","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.102:2379]}","request-path":"/0/members/6e7960f761c1028e/attributes","cluster-id":"502e2b31b867adf","publish-timeout":"7s"} {"level":"info","ts":"2026-04-02T22:23:28.478687Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T22:23:28.479003Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-02T22:23:28.479032Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-02T22:23:28.483137Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.102:2379"} {"level":"info","ts":"2026-04-02T22:23:28.48314Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-02T22:23:28.485635Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"502e2b31b867adf","local-member-id":"6e7960f761c1028e","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T22:23:28.485792Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T22:23:28.485838Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T22:23:30.036921Z","caller":"traceutil/trace.go:171","msg":"trace[2094375245] linearizableReadLoop","detail":"{readStateIndex:11; appliedIndex:4; }","duration":"239.396505ms","start":"2026-04-02T22:23:29.797502Z","end":"2026-04-02T22:23:30.036898Z","steps":["trace[2094375245] 'read index received' (duration: 82.767074ms)","trace[2094375245] 'applied index is now lower than readState.Index' (duration: 156.628551ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:23:30.037272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.735362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.102\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T22:23:30.037322Z","caller":"traceutil/trace.go:171","msg":"trace[1880074213] range","detail":"{range_begin:/registry/masterleases/199.204.45.102; range_end:; response_count:0; response_revision:9; }","duration":"239.844034ms","start":"2026-04-02T22:23:29.797472Z","end":"2026-04-02T22:23:30.037316Z","steps":["trace[1880074213] 'agreement among raft nodes before linearized reading' (duration: 239.709761ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:30.036927Z","caller":"traceutil/trace.go:171","msg":"trace[1496587191] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"248.675636ms","start":"2026-04-02T22:23:29.788219Z","end":"2026-04-02T22:23:30.036895Z","steps":["trace[1496587191] 'process raft request' (duration: 248.337599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:30.037605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.272895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T22:23:30.037681Z","caller":"traceutil/trace.go:171","msg":"trace[1425937409] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9; }","duration":"235.377567ms","start":"2026-04-02T22:23:29.802291Z","end":"2026-04-02T22:23:30.037669Z","steps":["trace[1425937409] 'agreement among raft nodes before linearized reading' (duration: 235.252625ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:30.036898Z","caller":"traceutil/trace.go:171","msg":"trace[1598293135] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"247.985181ms","start":"2026-04-02T22:23:29.788877Z","end":"2026-04-02T22:23:30.036862Z","steps":["trace[1598293135] 'process raft request' (duration: 247.806278ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:30.037006Z","caller":"traceutil/trace.go:171","msg":"trace[1644595196] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"247.324916ms","start":"2026-04-02T22:23:29.78967Z","end":"2026-04-02T22:23:30.036995Z","steps":["trace[1644595196] 'process raft request' (duration: 247.115002ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:30.037086Z","caller":"traceutil/trace.go:171","msg":"trace[454515798] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"246.385456ms","start":"2026-04-02T22:23:29.790635Z","end":"2026-04-02T22:23:30.03702Z","steps":["trace[454515798] 'process raft request' (duration: 246.199692ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:30.037128Z","caller":"traceutil/trace.go:171","msg":"trace[1896840625] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"175.176243ms","start":"2026-04-02T22:23:29.861931Z","end":"2026-04-02T22:23:30.037107Z","steps":["trace[1896840625] 'process raft request' (duration: 174.934857ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:30.037042Z","caller":"traceutil/trace.go:171","msg":"trace[263827879] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"247.734266ms","start":"2026-04-02T22:23:29.789298Z","end":"2026-04-02T22:23:30.037032Z","steps":["trace[263827879] 'process raft request' (duration: 247.44485ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:30.037052Z","caller":"traceutil/trace.go:171","msg":"trace[827471141] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"246.622821ms","start":"2026-04-02T22:23:29.79041Z","end":"2026-04-02T22:23:30.037033Z","steps":["trace[827471141] 'process raft request' (duration: 246.398426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:30.037506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.874018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T22:23:30.038115Z","caller":"traceutil/trace.go:171","msg":"trace[1767520564] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"176.497671ms","start":"2026-04-02T22:23:29.861604Z","end":"2026-04-02T22:23:30.038102Z","steps":["trace[1767520564] 'agreement among raft nodes before linearized reading' (duration: 175.886638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:30.03754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.174368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T22:23:30.038157Z","caller":"traceutil/trace.go:171","msg":"trace[371453233] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"190.832372ms","start":"2026-04-02T22:23:29.847318Z","end":"2026-04-02T22:23:30.038151Z","steps":["trace[371453233] 'agreement among raft nodes before linearized reading' (duration: 190.211878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:30.037527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.160214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T22:23:30.038298Z","caller":"traceutil/trace.go:171","msg":"trace[245954526] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:9; }","duration":"176.962411ms","start":"2026-04-02T22:23:29.861304Z","end":"2026-04-02T22:23:30.038266Z","steps":["trace[245954526] 'agreement among raft nodes before linearized reading' (duration: 176.157934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:30.037581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.786455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T22:23:30.038377Z","caller":"traceutil/trace.go:171","msg":"trace[430899353] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"122.676893ms","start":"2026-04-02T22:23:29.91569Z","end":"2026-04-02T22:23:30.038367Z","steps":["trace[430899353] 'agreement among raft nodes before linearized reading' (duration: 121.791174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:30.037574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.494856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T22:23:30.038504Z","caller":"traceutil/trace.go:171","msg":"trace[1765845160] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:9; }","duration":"240.500687ms","start":"2026-04-02T22:23:29.797996Z","end":"2026-04-02T22:23:30.038497Z","steps":["trace[1765845160] 'agreement among raft nodes before linearized reading' (duration: 239.467885ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:30.349501Z","caller":"traceutil/trace.go:171","msg":"trace[722686030] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"306.435014ms","start":"2026-04-02T22:23:30.043028Z","end":"2026-04-02T22:23:30.349463Z","steps":["trace[722686030] 'process raft request' (duration: 247.70822ms)","trace[722686030] 'compare' (duration: 58.439088ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:23:30.349549Z","caller":"traceutil/trace.go:171","msg":"trace[1749273730] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"305.645388ms","start":"2026-04-02T22:23:30.043883Z","end":"2026-04-02T22:23:30.349529Z","steps":["trace[1749273730] 'process raft request' (duration: 305.560506ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:30.34957Z","caller":"traceutil/trace.go:171","msg":"trace[1863496156] transaction","detail":"{read_only:false; number_of_response:0; response_revision:11; }","duration":"306.255261ms","start":"2026-04-02T22:23:30.043269Z","end":"2026-04-02T22:23:30.349525Z","steps":["trace[1863496156] 'process raft request' (duration: 306.065006ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:30.349597Z","caller":"traceutil/trace.go:171","msg":"trace[1702221638] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"305.840402ms","start":"2026-04-02T22:23:30.043735Z","end":"2026-04-02T22:23:30.349575Z","steps":["trace[1702221638] 'process raft request' (duration: 305.642937ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:30.349741Z","caller":"traceutil/trace.go:171","msg":"trace[329423081] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"305.828181ms","start":"2026-04-02T22:23:30.043905Z","end":"2026-04-02T22:23:30.349734Z","steps":["trace[329423081] 'process raft request' (duration: 305.576666ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:30.349892Z","caller":"traceutil/trace.go:171","msg":"trace[1786950018] linearizableReadLoop","detail":"{readStateIndex:20; appliedIndex:13; }","duration":"305.217789ms","start":"2026-04-02T22:23:30.044648Z","end":"2026-04-02T22:23:30.349866Z","steps":["trace[1786950018] 'read index received' (duration: 89.66044ms)","trace[1786950018] 'applied index is now lower than readState.Index' (duration: 215.555969ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:23:30.349958Z","caller":"traceutil/trace.go:171","msg":"trace[1026297905] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"305.787261ms","start":"2026-04-02T22:23:30.044144Z","end":"2026-04-02T22:23:30.349931Z","steps":["trace[1026297905] 'process raft request' (duration: 305.577846ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:30.349931Z","caller":"traceutil/trace.go:171","msg":"trace[1416906073] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"305.931975ms","start":"2026-04-02T22:23:30.043981Z","end":"2026-04-02T22:23:30.349913Z","steps":["trace[1416906073] 'process raft request' (duration: 305.548266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:30.350206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.766257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-02T22:23:30.350207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.535835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T22:23:30.35028Z","caller":"traceutil/trace.go:171","msg":"trace[1899073146] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:16; }","duration":"211.8857ms","start":"2026-04-02T22:23:30.138382Z","end":"2026-04-02T22:23:30.350268Z","steps":["trace[1899073146] 'agreement among raft nodes before linearized reading' (duration: 211.767308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:30.35022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.201573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-04-02T22:23:30.350361Z","caller":"traceutil/trace.go:171","msg":"trace[885942073] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:16; }","duration":"249.356996ms","start":"2026-04-02T22:23:30.100987Z","end":"2026-04-02T22:23:30.350344Z","steps":["trace[885942073] 'agreement among raft nodes before linearized reading' (duration: 249.133851ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:30.350293Z","caller":"traceutil/trace.go:171","msg":"trace[831968530] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:16; }","duration":"305.653767ms","start":"2026-04-02T22:23:30.044625Z","end":"2026-04-02T22:23:30.350279Z","steps":["trace[831968530] 'agreement among raft nodes before linearized reading' (duration: 305.351521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:30.350452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:30.043009Z","time spent":"306.572797ms","remote":"127.0.0.1:35996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:23:30.350538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:30.044617Z","time spent":"305.906623ms","remote":"127.0.0.1:35592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":0,"response size":26,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-02T22:23:30.350313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.102683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-02T22:23:30.350733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:30.04387Z","time spent":"305.736969ms","remote":"127.0.0.1:35996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T22:23:30.350693Z","caller":"traceutil/trace.go:171","msg":"trace[1961993900] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:16; }","duration":"106.45932ms","start":"2026-04-02T22:23:30.244193Z","end":"2026-04-02T22:23:30.350652Z","steps":["trace[1961993900] 'agreement among raft nodes before linearized reading' (duration: 105.95392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:30.351051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:30.043257Z","time spent":"306.400683ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":26,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:23:30.351152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:30.04389Z","time spent":"305.874243ms","remote":"127.0.0.1:35908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:23:30.351373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:30.043712Z","time spent":"305.936253ms","remote":"127.0.0.1:35996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:23:30.351501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:30.04413Z","time spent":"305.896674ms","remote":"127.0.0.1:35996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:23:30.351649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:30.043964Z","time spent":"306.084978ms","remote":"127.0.0.1:35996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T22:23:30.670935Z","caller":"traceutil/trace.go:171","msg":"trace[1012474820] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"317.244945ms","start":"2026-04-02T22:23:30.35366Z","end":"2026-04-02T22:23:30.670905Z","steps":["trace[1012474820] 'process raft request' (duration: 247.901023ms)","trace[1012474820] 'compare' (duration: 69.064106ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:23:30.671073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:30.353644Z","time spent":"317.373378ms","remote":"127.0.0.1:35542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":357,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T22:23:30.671273Z","caller":"traceutil/trace.go:171","msg":"trace[305406108] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"316.863487ms","start":"2026-04-02T22:23:30.354367Z","end":"2026-04-02T22:23:30.67123Z","steps":["trace[305406108] 'process raft request' (duration: 316.472229ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:30.671373Z","caller":"traceutil/trace.go:171","msg":"trace[410966443] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"316.439698ms","start":"2026-04-02T22:23:30.35492Z","end":"2026-04-02T22:23:30.671359Z","steps":["trace[410966443] 'process raft request' (duration: 316.06767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:30.671431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:30.354358Z","time spent":"316.99648ms","remote":"127.0.0.1:35996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T22:23:30.671464Z","caller":"traceutil/trace.go:171","msg":"trace[41398764] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"316.925808ms","start":"2026-04-02T22:23:30.354531Z","end":"2026-04-02T22:23:30.671456Z","steps":["trace[41398764] 'process raft request' (duration: 316.416217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:30.671432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:30.354905Z","time spent":"316.497869ms","remote":"127.0.0.1:35996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:23:30.671506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:30.354525Z","time spent":"316.95945ms","remote":"127.0.0.1:35908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T22:23:30.671627Z","caller":"traceutil/trace.go:171","msg":"trace[2060300973] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"316.464799ms","start":"2026-04-02T22:23:30.355133Z","end":"2026-04-02T22:23:30.671598Z","steps":["trace[2060300973] 'process raft request' (duration: 315.883377ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:30.671733Z","caller":"traceutil/trace.go:171","msg":"trace[620033138] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"316.354517ms","start":"2026-04-02T22:23:30.355369Z","end":"2026-04-02T22:23:30.671723Z","steps":["trace[620033138] 'process raft request' (duration: 315.810655ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:30.671765Z","caller":"traceutil/trace.go:171","msg":"trace[662131081] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"316.468469ms","start":"2026-04-02T22:23:30.35526Z","end":"2026-04-02T22:23:30.671729Z","steps":["trace[662131081] 'process raft request' (duration: 315.835725ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:30.671867Z","caller":"traceutil/trace.go:171","msg":"trace[1728347197] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"315.687181ms","start":"2026-04-02T22:23:30.356172Z","end":"2026-04-02T22:23:30.671859Z","steps":["trace[1728347197] 'process raft request' (duration: 315.477917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:30.671894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:30.355249Z","time spent":"316.590221ms","remote":"127.0.0.1:35996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":956,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:23:30.671918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:30.356163Z","time spent":"315.735922ms","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T22:23:30.67195Z","caller":"traceutil/trace.go:171","msg":"trace[760908337] linearizableReadLoop","detail":"{readStateIndex:22; appliedIndex:21; }","duration":"317.842028ms","start":"2026-04-02T22:23:30.353879Z","end":"2026-04-02T22:23:30.671721Z","steps":["trace[760908337] 'read index received' (duration: 55.802661ms)","trace[760908337] 'applied index is now lower than readState.Index' (duration: 262.038757ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:23:30.672011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:30.355123Z","time spent":"316.625462ms","remote":"127.0.0.1:35996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:23:30.671794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:30.355342Z","time spent":"316.425778ms","remote":"127.0.0.1:35996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:23:30.672175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.741227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T22:23:30.672203Z","caller":"traceutil/trace.go:171","msg":"trace[699724988] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:25; }","duration":"258.800909ms","start":"2026-04-02T22:23:30.413393Z","end":"2026-04-02T22:23:30.672194Z","steps":["trace[699724988] 'agreement among raft nodes before linearized reading' (duration: 258.689976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:30.672254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.349979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-04-02T22:23:30.672411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.294741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T22:23:30.672464Z","caller":"traceutil/trace.go:171","msg":"trace[1241976756] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:25; }","duration":"306.371722ms","start":"2026-04-02T22:23:30.366084Z","end":"2026-04-02T22:23:30.672456Z","steps":["trace[1241976756] 'agreement among raft nodes before linearized reading' (duration: 305.957003ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:30.672403Z","caller":"traceutil/trace.go:171","msg":"trace[151720997] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:25; }","duration":"318.541813ms","start":"2026-04-02T22:23:30.353821Z","end":"2026-04-02T22:23:30.672363Z","steps":["trace[151720997] 'agreement among raft nodes before linearized reading' (duration: 318.186516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:30.672484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:30.366072Z","time spent":"306.406893ms","remote":"127.0.0.1:35850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-02T22:23:30.672552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:30.353804Z","time spent":"318.693617ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":372,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-02T22:23:31.612612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.722206ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-02T22:23:31.613042Z","caller":"traceutil/trace.go:171","msg":"trace[1340922927] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"936.324324ms","start":"2026-04-02T22:23:30.676689Z","end":"2026-04-02T22:23:31.613013Z","steps":["trace[1340922927] 'process raft request' (duration: 936.090119ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:31.613154Z","caller":"traceutil/trace.go:171","msg":"trace[1115776692] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"937.043159ms","start":"2026-04-02T22:23:30.676091Z","end":"2026-04-02T22:23:31.613134Z","steps":["trace[1115776692] 'process raft request' (duration: 824.462925ms)","trace[1115776692] 'compare' (duration: 111.570943ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:23:31.61326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:30.676059Z","time spent":"937.144123ms","remote":"127.0.0.1:35908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":713,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:23:31.613276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.757004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T22:23:31.613325Z","caller":"traceutil/trace.go:171","msg":"trace[1164523712] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:32; }","duration":"936.951218ms","start":"2026-04-02T22:23:30.676359Z","end":"2026-04-02T22:23:31.61331Z","steps":["trace[1164523712] 'agreement among raft nodes before linearized reading' (duration: 936.770033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:31.613379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:30.676348Z","time spent":"937.01948ms","remote":"127.0.0.1:35592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":0,"response size":26,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-02T22:23:31.613402Z","caller":"traceutil/trace.go:171","msg":"trace[1816643311] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"936.710154ms","start":"2026-04-02T22:23:30.676673Z","end":"2026-04-02T22:23:31.613383Z","steps":["trace[1816643311] 'process raft request' (duration: 936.039349ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:31.613492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:30.67664Z","time spent":"936.813945ms","remote":"127.0.0.1:35996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":926,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:23:31.613505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:30.676676Z","time spent":"936.481368ms","remote":"127.0.0.1:35996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T22:23:31.613049Z","caller":"traceutil/trace.go:171","msg":"trace[1791970078] linearizableReadLoop","detail":"{readStateIndex:31; appliedIndex:29; }","duration":"936.427596ms","start":"2026-04-02T22:23:30.676504Z","end":"2026-04-02T22:23:31.612932Z","steps":["trace[1791970078] 'read index received' (duration: 72.01241ms)","trace[1791970078] 'applied index is now lower than readState.Index' (duration: 864.414156ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:23:31.613186Z","caller":"traceutil/trace.go:171","msg":"trace[507048101] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"935.435334ms","start":"2026-04-02T22:23:30.677729Z","end":"2026-04-02T22:23:31.613164Z","steps":["trace[507048101] 'process raft request' (duration: 935.136288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:31.613908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.30791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-02T22:23:31.613953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:30.677696Z","time spent":"936.234102ms","remote":"127.0.0.1:35996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":959,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T22:23:31.613961Z","caller":"traceutil/trace.go:171","msg":"trace[1436878585] range","detail":"{range_begin:/registry/resourcequotas/default/; range_end:/registry/resourcequotas/default0; response_count:0; response_revision:32; }","duration":"861.397442ms","start":"2026-04-02T22:23:30.752549Z","end":"2026-04-02T22:23:31.613946Z","steps":["trace[1436878585] 'agreement among raft nodes before linearized reading' (duration: 861.315241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:31.614043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.721637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T22:23:31.613337Z","caller":"traceutil/trace.go:171","msg":"trace[1477018982] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"936.468018ms","start":"2026-04-02T22:23:30.676852Z","end":"2026-04-02T22:23:31.61332Z","steps":["trace[1477018982] 'process raft request' (duration: 935.973857ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:31.614189Z","caller":"traceutil/trace.go:171","msg":"trace[1736578757] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:32; }","duration":"854.010723ms","start":"2026-04-02T22:23:30.760164Z","end":"2026-04-02T22:23:31.614174Z","steps":["trace[1736578757] 'agreement among raft nodes before linearized reading' (duration: 853.593655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:31.614282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:30.676842Z","time spent":"937.396728ms","remote":"127.0.0.1:35996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":983,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:23:31.614361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:30.760158Z","time spent":"854.140786ms","remote":"127.0.0.1:35782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-04-02T22:23:31.613885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.85871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T22:23:31.614592Z","caller":"traceutil/trace.go:171","msg":"trace[1556888668] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:32; }","duration":"854.583475ms","start":"2026-04-02T22:23:30.759995Z","end":"2026-04-02T22:23:31.614579Z","steps":["trace[1556888668] 'agreement among raft nodes before linearized reading' (duration: 853.85607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:31.613878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.484506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T22:23:31.614979Z","caller":"traceutil/trace.go:171","msg":"trace[1215153623] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:32; }","duration":"771.62222ms","start":"2026-04-02T22:23:30.84334Z","end":"2026-04-02T22:23:31.614962Z","steps":["trace[1215153623] 'agreement among raft nodes before linearized reading' (duration: 770.495646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:31.615056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:30.843318Z","time spent":"771.720901ms","remote":"127.0.0.1:35414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T22:23:31.614939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:30.759983Z","time spent":"854.659347ms","remote":"127.0.0.1:35810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":26,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-04-02T22:23:31.613836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.288292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T22:23:31.61526Z","caller":"traceutil/trace.go:171","msg":"trace[1614416747] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:32; }","duration":"936.778114ms","start":"2026-04-02T22:23:30.678463Z","end":"2026-04-02T22:23:31.615241Z","steps":["trace[1614416747] 'agreement among raft nodes before linearized reading' (duration: 935.297023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:31.615317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:30.678443Z","time spent":"936.860655ms","remote":"127.0.0.1:35542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":26,"request content":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" "} {"level":"warn","ts":"2026-04-02T22:23:31.615603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:30.752529Z","time spent":"861.555045ms","remote":"127.0.0.1:35520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" "} {"level":"info","ts":"2026-04-02T22:23:31.616802Z","caller":"traceutil/trace.go:171","msg":"trace[1147901158] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"936.214061ms","start":"2026-04-02T22:23:30.677776Z","end":"2026-04-02T22:23:31.61399Z","steps":["trace[1147901158] 'process raft request' (duration: 935.120727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:31.617029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:30.677765Z","time spent":"939.175136ms","remote":"127.0.0.1:35996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":959,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:23:32.291093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.280703ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-02T22:23:32.291336Z","caller":"traceutil/trace.go:171","msg":"trace[429315854] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"672.355087ms","start":"2026-04-02T22:23:31.61894Z","end":"2026-04-02T22:23:32.291296Z","steps":["trace[429315854] 'process raft request' (duration: 672.243705ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:32.291371Z","caller":"traceutil/trace.go:171","msg":"trace[689358032] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"673.196866ms","start":"2026-04-02T22:23:31.618136Z","end":"2026-04-02T22:23:32.291333Z","steps":["trace[689358032] 'process raft request' (duration: 411.609726ms)","trace[689358032] 'compare' (duration: 261.128249ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:23:32.29149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.810068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T22:23:32.291528Z","caller":"traceutil/trace.go:171","msg":"trace[263018062] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:37; }","duration":"672.871209ms","start":"2026-04-02T22:23:31.618648Z","end":"2026-04-02T22:23:32.291519Z","steps":["trace[263018062] 'agreement among raft nodes before linearized reading' (duration: 672.767657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:32.291587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:31.618641Z","time spent":"672.938091ms","remote":"127.0.0.1:35796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":26,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"warn","ts":"2026-04-02T22:23:32.291668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:31.618124Z","time spent":"673.38182ms","remote":"127.0.0.1:35996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T22:23:32.29181Z","caller":"traceutil/trace.go:171","msg":"trace[1524065818] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"672.50837ms","start":"2026-04-02T22:23:31.619266Z","end":"2026-04-02T22:23:32.291774Z","steps":["trace[1524065818] 'process raft request' (duration: 672.063651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:32.291527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:31.618933Z","time spent":"672.49726ms","remote":"127.0.0.1:35810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:23:32.29191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:31.61926Z","time spent":"672.604883ms","remote":"127.0.0.1:35542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3004,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T22:23:32.291708Z","caller":"traceutil/trace.go:171","msg":"trace[1735014508] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"672.625204ms","start":"2026-04-02T22:23:31.619067Z","end":"2026-04-02T22:23:32.291692Z","steps":["trace[1735014508] 'process raft request' (duration: 672.165774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:32.292053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:31.619061Z","time spent":"672.96548ms","remote":"127.0.0.1:35908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":711,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T22:23:32.291388Z","caller":"traceutil/trace.go:171","msg":"trace[59210180] linearizableReadLoop","detail":"{readStateIndex:38; appliedIndex:37; }","duration":"672.702226ms","start":"2026-04-02T22:23:31.618669Z","end":"2026-04-02T22:23:32.291371Z","steps":["trace[59210180] 'read index received' (duration: 85.310592ms)","trace[59210180] 'applied index is now lower than readState.Index' (duration: 587.390664ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:23:32.35553Z","caller":"traceutil/trace.go:171","msg":"trace[830895357] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"118.134486ms","start":"2026-04-02T22:23:32.237354Z","end":"2026-04-02T22:23:32.355488Z","steps":["trace[830895357] 'process raft request' (duration: 117.936532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:32.355595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.586818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T22:23:32.355666Z","caller":"traceutil/trace.go:171","msg":"trace[1647362219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:38; }","duration":"411.698921ms","start":"2026-04-02T22:23:31.943949Z","end":"2026-04-02T22:23:32.355647Z","steps":["trace[1647362219] 'agreement among raft nodes before linearized reading' (duration: 411.495396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:32.355715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:31.943925Z","time spent":"411.782432ms","remote":"127.0.0.1:35414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T22:23:32.355731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.756387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T22:23:32.355771Z","caller":"traceutil/trace.go:171","msg":"trace[430203311] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:38; }","duration":"686.851349ms","start":"2026-04-02T22:23:31.668908Z","end":"2026-04-02T22:23:32.355759Z","steps":["trace[430203311] 'agreement among raft nodes before linearized reading' (duration: 686.746326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:32.355824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:31.668896Z","time spent":"686.921801ms","remote":"127.0.0.1:35850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-02T22:23:32.355579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.423265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T22:23:32.355963Z","caller":"traceutil/trace.go:171","msg":"trace[1827222978] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:38; }","duration":"229.815714ms","start":"2026-04-02T22:23:32.126131Z","end":"2026-04-02T22:23:32.355947Z","steps":["trace[1827222978] 'agreement among raft nodes before linearized reading' (duration: 229.314373ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:32.790076Z","caller":"traceutil/trace.go:171","msg":"trace[455271466] linearizableReadLoop","detail":"{readStateIndex:45; appliedIndex:43; }","duration":"414.273377ms","start":"2026-04-02T22:23:32.375779Z","end":"2026-04-02T22:23:32.790052Z","steps":["trace[455271466] 'read index received' (duration: 359.606536ms)","trace[455271466] 'applied index is now lower than readState.Index' (duration: 54.666011ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:23:32.790203Z","caller":"traceutil/trace.go:171","msg":"trace[1990624698] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"414.342348ms","start":"2026-04-02T22:23:32.375851Z","end":"2026-04-02T22:23:32.790194Z","steps":["trace[1990624698] 'process raft request' (duration: 414.122503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:32.790316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:32.375832Z","time spent":"414.41153ms","remote":"127.0.0.1:35850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T22:23:32.790357Z","caller":"traceutil/trace.go:171","msg":"trace[1905236552] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"415.907952ms","start":"2026-04-02T22:23:32.374296Z","end":"2026-04-02T22:23:32.790204Z","steps":["trace[1905236552] 'process raft request' (duration: 361.055467ms)","trace[1905236552] 'compare' (duration: 54.376194ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:23:32.790594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:32.37427Z","time spent":"416.225338ms","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:23:32.790665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.10826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T22:23:32.790808Z","caller":"traceutil/trace.go:171","msg":"trace[1148718141] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:43; }","duration":"147.273044ms","start":"2026-04-02T22:23:32.643487Z","end":"2026-04-02T22:23:32.79076Z","steps":["trace[1148718141] 'agreement among raft nodes before linearized reading' (duration: 147.038999ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:32.790961Z","caller":"traceutil/trace.go:171","msg":"trace[1348263801] transaction","detail":"{read_only:false; response_revision:43; number_of_response:1; }","duration":"414.303227ms","start":"2026-04-02T22:23:32.375998Z","end":"2026-04-02T22:23:32.790301Z","steps":["trace[1348263801] 'process raft request' (duration: 414.018001ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:32.790944Z","caller":"traceutil/trace.go:171","msg":"trace[1949033355] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"415.241446ms","start":"2026-04-02T22:23:32.375668Z","end":"2026-04-02T22:23:32.790909Z","steps":["trace[1949033355] 'process raft request' (duration: 414.258866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:32.791037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:32.375987Z","time spent":"415.013311ms","remote":"127.0.0.1:35900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":647,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:23:32.79114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.336449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T22:23:32.791177Z","caller":"traceutil/trace.go:171","msg":"trace[1925484199] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:43; }","duration":"415.40981ms","start":"2026-04-02T22:23:32.375758Z","end":"2026-04-02T22:23:32.791168Z","steps":["trace[1925484199] 'agreement among raft nodes before linearized reading' (duration: 415.065703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:32.791188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:32.375654Z","time spent":"415.359059ms","remote":"127.0.0.1:35810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:23:32.791237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:32.375749Z","time spent":"415.480921ms","remote":"127.0.0.1:35782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"warn","ts":"2026-04-02T22:23:33.172152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.571625ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-04-02T22:23:33.172378Z","caller":"traceutil/trace.go:171","msg":"trace[274929562] transaction","detail":"{read_only:false; response_revision:46; number_of_response:1; }","duration":"366.448038ms","start":"2026-04-02T22:23:32.805913Z","end":"2026-04-02T22:23:33.172361Z","steps":["trace[274929562] 'process raft request' (duration: 366.383506ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:33.172336Z","caller":"traceutil/trace.go:171","msg":"trace[1105631403] transaction","detail":"{read_only:false; response_revision:45; number_of_response:1; }","duration":"376.329426ms","start":"2026-04-02T22:23:32.795972Z","end":"2026-04-02T22:23:33.172302Z","steps":["trace[1105631403] 'process raft request' (duration: 95.071507ms)","trace[1105631403] 'compare' (duration: 280.445953ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:23:33.172535Z","caller":"traceutil/trace.go:171","msg":"trace[2047029367] linearizableReadLoop","detail":"{readStateIndex:49; appliedIndex:48; }","duration":"367.066241ms","start":"2026-04-02T22:23:32.805435Z","end":"2026-04-02T22:23:33.172501Z","steps":["trace[2047029367] 'read index received' (duration: 85.619777ms)","trace[2047029367] 'applied index is now lower than readState.Index' (duration: 281.444164ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:23:33.172807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.378296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-02T22:23:33.172477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:32.805885Z","time spent":"366.553849ms","remote":"127.0.0.1:35900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":632,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T22:23:33.172876Z","caller":"traceutil/trace.go:171","msg":"trace[664932657] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:46; }","duration":"367.477059ms","start":"2026-04-02T22:23:32.805378Z","end":"2026-04-02T22:23:33.172855Z","steps":["trace[664932657] 'agreement among raft nodes before linearized reading' (duration: 367.299765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:33.172821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.628909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-02T22:23:33.173018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:32.795962Z","time spent":"376.610702ms","remote":"127.0.0.1:35900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1058,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:23:33.173064Z","caller":"traceutil/trace.go:171","msg":"trace[1276762691] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:46; }","duration":"129.907475ms","start":"2026-04-02T22:23:33.043136Z","end":"2026-04-02T22:23:33.173043Z","steps":["trace[1276762691] 'agreement among raft nodes before linearized reading' (duration: 129.640379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:33.172925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:32.805367Z","time spent":"367.54434ms","remote":"127.0.0.1:35782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/system:aggregate-to-admin\" "} {"level":"info","ts":"2026-04-02T22:23:33.322154Z","caller":"traceutil/trace.go:171","msg":"trace[1754874706] transaction","detail":"{read_only:false; response_revision:50; number_of_response:1; }","duration":"133.565842ms","start":"2026-04-02T22:23:33.188502Z","end":"2026-04-02T22:23:33.322068Z","steps":["trace[1754874706] 'process raft request' (duration: 133.48696ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:33.322164Z","caller":"traceutil/trace.go:171","msg":"trace[541569804] linearizableReadLoop","detail":"{readStateIndex:53; appliedIndex:52; }","duration":"134.8727ms","start":"2026-04-02T22:23:33.187242Z","end":"2026-04-02T22:23:33.322114Z","steps":["trace[541569804] 'read index received' (duration: 109.928065ms)","trace[541569804] 'applied index is now lower than readState.Index' (duration: 24.935345ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:23:33.32232Z","caller":"traceutil/trace.go:171","msg":"trace[15861819] transaction","detail":"{read_only:false; response_revision:49; number_of_response:1; }","duration":"138.730591ms","start":"2026-04-02T22:23:33.183556Z","end":"2026-04-02T22:23:33.322286Z","steps":["trace[15861819] 'process raft request' (duration: 113.613552ms)","trace[15861819] 'compare' (duration: 24.652818ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:23:33.322389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.104076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cluster-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T22:23:33.322429Z","caller":"traceutil/trace.go:171","msg":"trace[968861408] range","detail":"{range_begin:/registry/clusterroles/cluster-admin; range_end:; response_count:0; response_revision:50; }","duration":"135.195358ms","start":"2026-04-02T22:23:33.187222Z","end":"2026-04-02T22:23:33.322417Z","steps":["trace[968861408] 'agreement among raft nodes before linearized reading' (duration: 135.048035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:34.660794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.322619ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T22:23:34.660923Z","caller":"traceutil/trace.go:171","msg":"trace[84233589] linearizableReadLoop","detail":"{readStateIndex:147; appliedIndex:146; }","duration":"190.429663ms","start":"2026-04-02T22:23:34.470475Z","end":"2026-04-02T22:23:34.660905Z","steps":["trace[84233589] 'read index received' (duration: 55.88265ms)","trace[84233589] 'applied index is now lower than readState.Index' (duration: 134.545763ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:23:34.660956Z","caller":"traceutil/trace.go:171","msg":"trace[1186528951] transaction","detail":"{read_only:false; response_revision:142; number_of_response:1; }","duration":"190.715909ms","start":"2026-04-02T22:23:34.470204Z","end":"2026-04-02T22:23:34.66092Z","steps":["trace[1186528951] 'process raft request' (duration: 56.194185ms)","trace[1186528951] 'compare' (duration: 134.164356ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:23:34.6611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.627558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18a2aa6fa089fd3f\" ","response":"range_response_count:1 size:652"} {"level":"info","ts":"2026-04-02T22:23:34.661137Z","caller":"traceutil/trace.go:171","msg":"trace[1943465716] range","detail":"{range_begin:/registry/events/default/instance.18a2aa6fa089fd3f; range_end:; response_count:1; response_revision:142; }","duration":"190.6936ms","start":"2026-04-02T22:23:34.470431Z","end":"2026-04-02T22:23:34.661124Z","steps":["trace[1943465716] 'agreement among raft nodes before linearized reading' (duration: 190.567396ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:35.019124Z","caller":"traceutil/trace.go:171","msg":"trace[2127494744] transaction","detail":"{read_only:false; response_revision:157; number_of_response:1; }","duration":"161.429263ms","start":"2026-04-02T22:23:34.857647Z","end":"2026-04-02T22:23:35.019076Z","steps":["trace[2127494744] 'process raft request' (duration: 149.853552ms)","trace[2127494744] 'compare' (duration: 11.395627ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:23:35.019331Z","caller":"traceutil/trace.go:171","msg":"trace[1897900617] transaction","detail":"{read_only:false; response_revision:158; number_of_response:1; }","duration":"161.478353ms","start":"2026-04-02T22:23:34.857827Z","end":"2026-04-02T22:23:35.019306Z","steps":["trace[1897900617] 'process raft request' (duration: 161.355591ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:35.682401Z","caller":"traceutil/trace.go:171","msg":"trace[775367496] transaction","detail":"{read_only:false; response_revision:189; number_of_response:1; }","duration":"112.142989ms","start":"2026-04-02T22:23:35.57022Z","end":"2026-04-02T22:23:35.682363Z","steps":["trace[775367496] 'process raft request' (duration: 96.74268ms)","trace[775367496] 'compare' (duration: 15.230476ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:23:36.048526Z","caller":"traceutil/trace.go:171","msg":"trace[1540798221] transaction","detail":"{read_only:false; response_revision:201; number_of_response:1; }","duration":"172.460305ms","start":"2026-04-02T22:23:35.876036Z","end":"2026-04-02T22:23:36.048496Z","steps":["trace[1540798221] 'process raft request' (duration: 149.208455ms)","trace[1540798221] 'compare' (duration: 23.105988ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:23:37.218249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.068057746s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T22:23:37.218396Z","caller":"traceutil/trace.go:171","msg":"trace[466276149] linearizableReadLoop","detail":"{readStateIndex:208; appliedIndex:207; }","duration":"1.027655292s","start":"2026-04-02T22:23:36.190677Z","end":"2026-04-02T22:23:37.218332Z","steps":["trace[466276149] 'read index received' (duration: 58.631µs)","trace[466276149] 'applied index is now lower than readState.Index' (duration: 1.027595531s)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:23:37.218496Z","caller":"traceutil/trace.go:171","msg":"trace[870365594] transaction","detail":"{read_only:false; response_revision:203; number_of_response:1; }","duration":"1.094261486s","start":"2026-04-02T22:23:36.124219Z","end":"2026-04-02T22:23:37.21848Z","steps":["trace[870365594] 'process raft request' (duration: 25.854093ms)","trace[870365594] 'compare' (duration: 1.067929964s)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:23:37.218624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:36.124206Z","time spent":"1.094367658s","remote":"127.0.0.1:35796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":768,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:23:37.218864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028150174s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:430"} {"level":"warn","ts":"2026-04-02T22:23:37.218972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.804215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:23:37.219007Z","caller":"traceutil/trace.go:171","msg":"trace[1878700635] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:203; }","duration":"1.028356678s","start":"2026-04-02T22:23:36.190621Z","end":"2026-04-02T22:23:37.218978Z","steps":["trace[1878700635] 'agreement among raft nodes before linearized reading' (duration: 1.027944569s)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:37.219023Z","caller":"traceutil/trace.go:171","msg":"trace[652474216] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:203; }","duration":"464.878567ms","start":"2026-04-02T22:23:36.754121Z","end":"2026-04-02T22:23:37.219Z","steps":["trace[652474216] 'agreement among raft nodes before linearized reading' (duration: 464.830046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:37.219071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:36.7541Z","time spent":"464.963618ms","remote":"127.0.0.1:35416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T22:23:37.218861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.027691395s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T22:23:37.219455Z","caller":"traceutil/trace.go:171","msg":"trace[1362347941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:203; }","duration":"1.028332688s","start":"2026-04-02T22:23:36.191101Z","end":"2026-04-02T22:23:37.219434Z","steps":["trace[1362347941] 'agreement among raft nodes before linearized reading' (duration: 1.027635714s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:37.218944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.185615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:23:37.219531Z","caller":"traceutil/trace.go:171","msg":"trace[784989178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:203; }","duration":"148.823668ms","start":"2026-04-02T22:23:37.070695Z","end":"2026-04-02T22:23:37.219518Z","steps":["trace[784989178] 'agreement among raft nodes before linearized reading' (duration: 148.222346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:37.219078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:36.190596Z","time spent":"1.028471061s","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":452,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T22:23:37.219669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:36.191076Z","time spent":"1.028555132s","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-02T22:23:37.408484Z","caller":"traceutil/trace.go:171","msg":"trace[790894376] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"183.343925ms","start":"2026-04-02T22:23:37.225111Z","end":"2026-04-02T22:23:37.408455Z","steps":["trace[790894376] 'process raft request' (duration: 183.254763ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:37.408554Z","caller":"traceutil/trace.go:171","msg":"trace[1544361608] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"184.665852ms","start":"2026-04-02T22:23:37.223848Z","end":"2026-04-02T22:23:37.408513Z","steps":["trace[1544361608] 'process raft request' (duration: 183.512749ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:37.420386Z","caller":"traceutil/trace.go:171","msg":"trace[29878698] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"114.554406ms","start":"2026-04-02T22:23:37.305808Z","end":"2026-04-02T22:23:37.420362Z","steps":["trace[29878698] 'process raft request' (duration: 114.368902ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:37.634325Z","caller":"traceutil/trace.go:171","msg":"trace[1406363963] transaction","detail":"{read_only:false; response_revision:212; number_of_response:1; }","duration":"135.173429ms","start":"2026-04-02T22:23:37.499115Z","end":"2026-04-02T22:23:37.634288Z","steps":["trace[1406363963] 'process raft request' (duration: 62.98387ms)","trace[1406363963] 'compare' (duration: 71.992275ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:23:37.745332Z","caller":"traceutil/trace.go:171","msg":"trace[552353731] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"104.47377ms","start":"2026-04-02T22:23:37.64083Z","end":"2026-04-02T22:23:37.745303Z","steps":["trace[552353731] 'process raft request' (duration: 99.133382ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:37.95584Z","caller":"traceutil/trace.go:171","msg":"trace[1071218606] transaction","detail":"{read_only:false; response_revision:219; number_of_response:1; }","duration":"134.067527ms","start":"2026-04-02T22:23:37.821734Z","end":"2026-04-02T22:23:37.955801Z","steps":["trace[1071218606] 'process raft request' (duration: 44.230446ms)","trace[1071218606] 'compare' (duration: 89.659027ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:23:38.358219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.298661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T22:23:38.358388Z","caller":"traceutil/trace.go:171","msg":"trace[106593077] linearizableReadLoop","detail":"{readStateIndex:231; appliedIndex:230; }","duration":"214.693005ms","start":"2026-04-02T22:23:38.143678Z","end":"2026-04-02T22:23:38.358371Z","steps":["trace[106593077] 'read index received' (duration: 78.226861ms)","trace[106593077] 'applied index is now lower than readState.Index' (duration: 136.464594ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:23:38.358437Z","caller":"traceutil/trace.go:171","msg":"trace[1899701701] transaction","detail":"{read_only:false; response_revision:226; number_of_response:1; }","duration":"304.063383ms","start":"2026-04-02T22:23:38.054333Z","end":"2026-04-02T22:23:38.358396Z","steps":["trace[1899701701] 'process raft request' (duration: 167.520188ms)","trace[1899701701] 'compare' (duration: 136.068706ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:23:38.358551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:38.054315Z","time spent":"304.179565ms","remote":"127.0.0.1:35796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":748,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:23:38.358554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.857558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:23:38.358619Z","caller":"traceutil/trace.go:171","msg":"trace[516710558] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:226; }","duration":"214.987841ms","start":"2026-04-02T22:23:38.143616Z","end":"2026-04-02T22:23:38.358604Z","steps":["trace[516710558] 'agreement among raft nodes before linearized reading' (duration: 214.820947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:38.681665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.600555ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T22:23:38.681776Z","caller":"traceutil/trace.go:171","msg":"trace[1825699992] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"234.074792ms","start":"2026-04-02T22:23:38.44768Z","end":"2026-04-02T22:23:38.681755Z","steps":["trace[1825699992] 'process raft request' (duration: 81.305543ms)","trace[1825699992] 'compare' (duration: 152.467893ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:23:38.862311Z","caller":"traceutil/trace.go:171","msg":"trace[1946476984] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"172.189753ms","start":"2026-04-02T22:23:38.690091Z","end":"2026-04-02T22:23:38.862281Z","steps":["trace[1946476984] 'process raft request' (duration: 169.732353ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:40.113848Z","caller":"traceutil/trace.go:171","msg":"trace[149559915] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"131.372521ms","start":"2026-04-02T22:23:39.982445Z","end":"2026-04-02T22:23:40.113818Z","steps":["trace[149559915] 'process raft request' (duration: 131.182427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:40.438907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.297067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-02T22:23:40.439003Z","caller":"traceutil/trace.go:171","msg":"trace[1881053035] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:272; }","duration":"269.429309ms","start":"2026-04-02T22:23:40.16955Z","end":"2026-04-02T22:23:40.43898Z","steps":["trace[1881053035] 'range keys from in-memory index tree' (duration: 269.151214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:40.439385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.180183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3172"} {"level":"info","ts":"2026-04-02T22:23:40.439503Z","caller":"traceutil/trace.go:171","msg":"trace[558142080] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:272; }","duration":"245.363776ms","start":"2026-04-02T22:23:40.194112Z","end":"2026-04-02T22:23:40.439476Z","steps":["trace[558142080] 'range keys from in-memory index tree' (duration: 244.493979ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:40.790396Z","caller":"traceutil/trace.go:171","msg":"trace[1048845732] linearizableReadLoop","detail":"{readStateIndex:280; appliedIndex:279; }","duration":"278.531281ms","start":"2026-04-02T22:23:40.511843Z","end":"2026-04-02T22:23:40.790375Z","steps":["trace[1048845732] 'read index received' (duration: 190.703348ms)","trace[1048845732] 'applied index is now lower than readState.Index' (duration: 87.827123ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:23:40.790496Z","caller":"traceutil/trace.go:171","msg":"trace[282533906] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"341.491266ms","start":"2026-04-02T22:23:40.448997Z","end":"2026-04-02T22:23:40.790488Z","steps":["trace[282533906] 'process raft request' (duration: 253.633841ms)","trace[282533906] 'compare' (duration: 87.65752ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:23:40.790578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:40.448981Z","time spent":"341.535007ms","remote":"127.0.0.1:35592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3375,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:23:40.790679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.494027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:23:40.790716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.848291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:23:40.790744Z","caller":"traceutil/trace.go:171","msg":"trace[1304520506] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:274; }","duration":"180.874132ms","start":"2026-04-02T22:23:40.609862Z","end":"2026-04-02T22:23:40.790736Z","steps":["trace[1304520506] 'agreement among raft nodes before linearized reading' (duration: 180.830861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:40.790719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.854239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:23:40.790819Z","caller":"traceutil/trace.go:171","msg":"trace[792576159] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/expand-controller; range_end:; response_count:0; response_revision:274; }","duration":"278.971491ms","start":"2026-04-02T22:23:40.511806Z","end":"2026-04-02T22:23:40.790778Z","steps":["trace[792576159] 'agreement among raft nodes before linearized reading' (duration: 278.845589ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:40.790746Z","caller":"traceutil/trace.go:171","msg":"trace[256691440] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:274; }","duration":"229.612279ms","start":"2026-04-02T22:23:40.561115Z","end":"2026-04-02T22:23:40.790727Z","steps":["trace[256691440] 'agreement among raft nodes before linearized reading' (duration: 229.489797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:41.60066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.26209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-04-02T22:23:41.600752Z","caller":"traceutil/trace.go:171","msg":"trace[501045027] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:290; }","duration":"281.417053ms","start":"2026-04-02T22:23:41.319314Z","end":"2026-04-02T22:23:41.600731Z","steps":["trace[501045027] 'range keys from in-memory index tree' (duration: 281.160488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:41.600747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.29344ms","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-04-02T22:23:41.601138Z","caller":"traceutil/trace.go:171","msg":"trace[1579177673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:290; }","duration":"272.717059ms","start":"2026-04-02T22:23:41.328398Z","end":"2026-04-02T22:23:41.601115Z","steps":["trace[1579177673] 'range keys from in-memory index tree' (duration: 272.221229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:41.601204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.98779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3389"} {"level":"warn","ts":"2026-04-02T22:23:41.601276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.502194ms","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-04-02T22:23:41.601389Z","caller":"traceutil/trace.go:171","msg":"trace[574482952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:290; }","duration":"115.614105ms","start":"2026-04-02T22:23:41.485729Z","end":"2026-04-02T22:23:41.601343Z","steps":["trace[574482952] 'range keys from in-memory index tree' (duration: 115.098595ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:41.601356Z","caller":"traceutil/trace.go:171","msg":"trace[1689093475] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:290; }","duration":"162.799326ms","start":"2026-04-02T22:23:41.438523Z","end":"2026-04-02T22:23:41.601323Z","steps":["trace[1689093475] 'range keys from in-memory index tree' (duration: 161.860008ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:41.896852Z","caller":"traceutil/trace.go:171","msg":"trace[1838295779] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"307.989582ms","start":"2026-04-02T22:23:41.58883Z","end":"2026-04-02T22:23:41.896819Z","steps":["trace[1838295779] 'process raft request' (duration: 307.797328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:41.897045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:41.588801Z","time spent":"308.157884ms","remote":"127.0.0.1:35602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:23:44.559445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.0002261s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-02T22:23:44.559619Z","caller":"traceutil/trace.go:171","msg":"trace[1363524236] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000452713s","start":"2026-04-02T22:23:42.559143Z","end":"2026-04-02T22:23:44.559596Z","steps":["trace[1363524236] 'agreement among raft nodes before linearized reading' (duration: 2.00026019s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:44.559724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:42.559121Z","time spent":"2.000590187s","remote":"127.0.0.1:35414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/02 22:23:44 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-02T22:23:45.011649Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.115057968s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-02T22:23:46.091375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.521248522s","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-04-02T22:23:46.091535Z","caller":"traceutil/trace.go:171","msg":"trace[2002999329] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:291; }","duration":"2.521423155s","start":"2026-04-02T22:23:43.570082Z","end":"2026-04-02T22:23:46.091505Z","steps":["trace[2002999329] 'range keys from in-memory index tree' (duration: 2.521223781s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:46.09352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.081560848s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T22:23:46.093802Z","caller":"traceutil/trace.go:171","msg":"trace[1623013822] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"4.488730492s","start":"2026-04-02T22:23:41.605043Z","end":"2026-04-02T22:23:46.093774Z","steps":["trace[1623013822] 'process raft request' (duration: 3.406832076s)","trace[1623013822] 'compare' (duration: 1.080349435s)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:23:46.093839Z","caller":"traceutil/trace.go:171","msg":"trace[953040807] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"4.485435247s","start":"2026-04-02T22:23:41.608389Z","end":"2026-04-02T22:23:46.093824Z","steps":["trace[953040807] 'process raft request' (duration: 4.485358455s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:46.09392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:41.60502Z","time spent":"4.488842444s","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:23:46.093967Z","caller":"traceutil/trace.go:171","msg":"trace[1624725483] linearizableReadLoop","detail":"{readStateIndex:298; appliedIndex:296; }","duration":"4.488713541s","start":"2026-04-02T22:23:41.605228Z","end":"2026-04-02T22:23:46.093942Z","steps":["trace[1624725483] 'read index received' (duration: 291.328138ms)","trace[1624725483] 'applied index is now lower than readState.Index' (duration: 4.197382493s)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:23:46.094285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.489041278s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pvc-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:23:46.094275Z","caller":"traceutil/trace.go:171","msg":"trace[199870352] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"4.485639379s","start":"2026-04-02T22:23:41.60861Z","end":"2026-04-02T22:23:46.09425Z","steps":["trace[199870352] 'process raft request' (duration: 4.48517347s)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:46.094322Z","caller":"traceutil/trace.go:171","msg":"trace[1482946002] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pvc-protection-controller; range_end:; response_count:0; response_revision:295; }","duration":"4.489117829s","start":"2026-04-02T22:23:41.605193Z","end":"2026-04-02T22:23:46.094311Z","steps":["trace[1482946002] 'agreement among raft nodes before linearized reading' (duration: 4.489047268s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:46.094361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:41.605183Z","time spent":"4.48916966s","remote":"127.0.0.1:35616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/pvc-protection-controller\" "} {"level":"warn","ts":"2026-04-02T22:23:46.094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:41.608378Z","time spent":"4.485555219s","remote":"127.0.0.1:35592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3683,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:23:46.094408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:41.608582Z","time spent":"4.485746381s","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:23:46.093862Z","caller":"traceutil/trace.go:171","msg":"trace[410103965] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"4.485573309s","start":"2026-04-02T22:23:41.608231Z","end":"2026-04-02T22:23:46.093805Z","steps":["trace[410103965] 'process raft request' (duration: 4.485431667s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:46.094572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:41.608221Z","time spent":"4.486263632s","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:23:46.097176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.252309279s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T22:23:46.097242Z","caller":"traceutil/trace.go:171","msg":"trace[316015031] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:296; }","duration":"3.252408131s","start":"2026-04-02T22:23:42.844817Z","end":"2026-04-02T22:23:46.097225Z","steps":["trace[316015031] 'agreement among raft nodes before linearized reading' (duration: 3.252301189s)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:46.097368Z","caller":"traceutil/trace.go:171","msg":"trace[1687361476] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"2.967053104s","start":"2026-04-02T22:23:43.1303Z","end":"2026-04-02T22:23:46.097353Z","steps":["trace[1687361476] 'process raft request' (duration: 2.966745358s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:46.09743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:43.130274Z","time spent":"2.967126926s","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:23:46.09754Z","caller":"traceutil/trace.go:171","msg":"trace[646921917] transaction","detail":"{read_only:false; number_of_response:1; response_revision:295; }","duration":"3.676568595s","start":"2026-04-02T22:23:42.420928Z","end":"2026-04-02T22:23:46.097496Z","steps":["trace[646921917] 'process raft request' (duration: 3.676045215s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:46.09765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.537685399s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:23:46.097677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:42.420902Z","time spent":"3.676698588s","remote":"127.0.0.1:35592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30,"response count":0,"response size":3730,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:23:46.097712Z","caller":"traceutil/trace.go:171","msg":"trace[1621421996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:296; }","duration":"1.53776927s","start":"2026-04-02T22:23:44.559927Z","end":"2026-04-02T22:23:46.097696Z","steps":["trace[1621421996] 'agreement among raft nodes before linearized reading' (duration: 1.537685949s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:46.097751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:44.559911Z","time spent":"1.537834742s","remote":"127.0.0.1:35416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T22:23:46.097942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:42.844797Z","time spent":"3.252477912s","remote":"127.0.0.1:35696","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-04-02T22:24:02.554507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.041784ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T22:24:02.554623Z","caller":"traceutil/trace.go:171","msg":"trace[662598913] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"330.649488ms","start":"2026-04-02T22:24:02.223955Z","end":"2026-04-02T22:24:02.554605Z","steps":["trace[662598913] 'process raft request' (duration: 65.435671ms)","trace[662598913] 'compare' (duration: 264.917942ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:24:02.554689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:02.223935Z","time spent":"330.72523ms","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:24:02.787695Z","caller":"traceutil/trace.go:171","msg":"trace[1698819832] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"562.486039ms","start":"2026-04-02T22:24:02.225186Z","end":"2026-04-02T22:24:02.787672Z","steps":["trace[1698819832] 'process raft request' (duration: 562.415608ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:24:02.787779Z","caller":"traceutil/trace.go:171","msg":"trace[1971079346] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"563.047458ms","start":"2026-04-02T22:24:02.22469Z","end":"2026-04-02T22:24:02.787738Z","steps":["trace[1971079346] 'process raft request' (duration: 472.476984ms)","trace[1971079346] 'compare' (duration: 90.272699ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:24:02.787848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:02.225175Z","time spent":"562.598461ms","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:24:02.78796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:02.224657Z","time spent":"563.203221ms","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:24:06.693981Z","caller":"traceutil/trace.go:171","msg":"trace[1309463849] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"121.715607ms","start":"2026-04-02T22:24:06.572248Z","end":"2026-04-02T22:24:06.693963Z","steps":["trace[1309463849] 'process raft request' (duration: 121.553974ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:24:11.177868Z","caller":"traceutil/trace.go:171","msg":"trace[1306620037] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"348.720157ms","start":"2026-04-02T22:24:10.829127Z","end":"2026-04-02T22:24:11.177847Z","steps":["trace[1306620037] 'process raft request' (duration: 348.421572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:11.178058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:10.82911Z","time spent":"348.819578ms","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:24:11.181757Z","caller":"traceutil/trace.go:171","msg":"trace[1083881342] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"352.496ms","start":"2026-04-02T22:24:10.829249Z","end":"2026-04-02T22:24:11.181745Z","steps":["trace[1083881342] 'process raft request' (duration: 352.248716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:11.18183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:10.829241Z","time spent":"352.5486ms","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:24:12.793947Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":184257602955670419,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T22:24:12.901882Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.29245423s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-02T22:24:12.961609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.674477976s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:24:12.961694Z","caller":"traceutil/trace.go:171","msg":"trace[410783467] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:531; }","duration":"1.674609059s","start":"2026-04-02T22:24:11.287064Z","end":"2026-04-02T22:24:12.961673Z","steps":["trace[410783467] 'range keys from in-memory index tree' (duration: 1.674355884s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:12.961735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:11.287017Z","time spent":"1.67470926s","remote":"127.0.0.1:35526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-04-02T22:24:12.961767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.609037987s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:24:12.961896Z","caller":"traceutil/trace.go:171","msg":"trace[249057966] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:531; }","duration":"1.609207139s","start":"2026-04-02T22:24:11.352668Z","end":"2026-04-02T22:24:12.961875Z","steps":["trace[249057966] 'range keys from in-memory index tree' (duration: 1.608944066s)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:24:12.961948Z","caller":"traceutil/trace.go:171","msg":"trace[980682655] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:555; }","duration":"668.779087ms","start":"2026-04-02T22:24:12.293141Z","end":"2026-04-02T22:24:12.961921Z","steps":["trace[980682655] 'read index received' (duration: 608.914655ms)","trace[980682655] 'applied index is now lower than readState.Index' (duration: 59.850122ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:24:12.962019Z","caller":"traceutil/trace.go:171","msg":"trace[2003318182] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"1.352741128s","start":"2026-04-02T22:24:11.609261Z","end":"2026-04-02T22:24:12.962003Z","steps":["trace[2003318182] 'process raft request' (duration: 1.292887767s)","trace[2003318182] 'compare' (duration: 59.1646ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:24:12.961991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:11.352649Z","time spent":"1.609329852s","remote":"127.0.0.1:35414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T22:24:12.962122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:11.609229Z","time spent":"1.35282805s","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:24:12.962139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.967546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T22:24:12.962225Z","caller":"traceutil/trace.go:171","msg":"trace[1817607302] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:532; }","duration":"166.091258ms","start":"2026-04-02T22:24:12.796112Z","end":"2026-04-02T22:24:12.962204Z","steps":["trace[1817607302] 'agreement among raft nodes before linearized reading' (duration: 165.962266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:12.962238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.013701ms","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-04-02T22:24:12.962379Z","caller":"traceutil/trace.go:171","msg":"trace[1268882769] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:532; }","duration":"669.260704ms","start":"2026-04-02T22:24:12.293091Z","end":"2026-04-02T22:24:12.962351Z","steps":["trace[1268882769] 'agreement among raft nodes before linearized reading' (duration: 668.948759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:12.962464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:12.293069Z","time spent":"669.378897ms","remote":"127.0.0.1:35696","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-04-02T22:24:13.969429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.846721ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T22:24:13.969556Z","caller":"traceutil/trace.go:171","msg":"trace[2138056323] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"1.002707485s","start":"2026-04-02T22:24:12.966825Z","end":"2026-04-02T22:24:13.969532Z","steps":["trace[2138056323] 'process raft request' (duration: 213.677521ms)","trace[2138056323] 'compare' (duration: 788.730719ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:24:13.969636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:12.966801Z","time spent":"1.002801516s","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:24:14.00971Z","caller":"traceutil/trace.go:171","msg":"trace[601070019] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:556; }","duration":"1.037810999s","start":"2026-04-02T22:24:12.971875Z","end":"2026-04-02T22:24:14.009686Z","steps":["trace[601070019] 'read index received' (duration: 208.642286ms)","trace[601070019] 'applied index is now lower than readState.Index' (duration: 829.167523ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:24:14.009813Z","caller":"traceutil/trace.go:171","msg":"trace[255842783] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.041065813s","start":"2026-04-02T22:24:12.968718Z","end":"2026-04-02T22:24:14.009783Z","steps":["trace[255842783] 'process raft request' (duration: 1.040820309s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:14.009872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.037965501s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:24:14.009936Z","caller":"traceutil/trace.go:171","msg":"trace[145576649] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"1.038072523s","start":"2026-04-02T22:24:12.971844Z","end":"2026-04-02T22:24:14.009917Z","steps":["trace[145576649] 'agreement among raft nodes before linearized reading' (duration: 1.037966822s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:14.009936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.035956097s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:24:14.009973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:12.968657Z","time spent":"1.041231916s","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:24:14.010002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:12.971834Z","time spent":"1.038152654s","remote":"127.0.0.1:35414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T22:24:14.010014Z","caller":"traceutil/trace.go:171","msg":"trace[422539476] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:534; }","duration":"1.036050189s","start":"2026-04-02T22:24:12.973938Z","end":"2026-04-02T22:24:14.009988Z","steps":["trace[422539476] 'agreement among raft nodes before linearized reading' (duration: 1.035938478s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:14.01007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:12.973929Z","time spent":"1.036129421s","remote":"127.0.0.1:35526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-04-02T22:24:14.010317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.387583ms","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-04-02T22:24:14.010338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.80567ms","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-04-02T22:24:14.010355Z","caller":"traceutil/trace.go:171","msg":"trace[1607811175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:534; }","duration":"824.503564ms","start":"2026-04-02T22:24:13.185841Z","end":"2026-04-02T22:24:14.010345Z","steps":["trace[1607811175] 'agreement among raft nodes before linearized reading' (duration: 824.386262ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:24:14.010369Z","caller":"traceutil/trace.go:171","msg":"trace[1170796834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:534; }","duration":"827.861671ms","start":"2026-04-02T22:24:13.182497Z","end":"2026-04-02T22:24:14.010358Z","steps":["trace[1170796834] 'agreement among raft nodes before linearized reading' (duration: 827.74681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:14.010384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:13.185814Z","time spent":"824.563325ms","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T22:24:14.010398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:13.18248Z","time spent":"827.913792ms","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T22:24:14.519906Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":184257602955670428,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T22:24:15.028181Z","caller":"traceutil/trace.go:171","msg":"trace[733225731] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"1.01166663s","start":"2026-04-02T22:24:14.016461Z","end":"2026-04-02T22:24:15.028128Z","steps":["trace[733225731] 'process raft request' (duration: 998.414142ms)","trace[733225731] 'compare' (duration: 13.136036ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:24:15.028371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:14.016409Z","time spent":"1.011860303s","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:24:15.041663Z","caller":"traceutil/trace.go:171","msg":"trace[1824475766] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"1.02382647s","start":"2026-04-02T22:24:14.017811Z","end":"2026-04-02T22:24:15.041638Z","steps":["trace[1824475766] 'process raft request' (duration: 1.023579456s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:15.041902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:14.017773Z","time spent":"1.023971702s","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:24:15.042255Z","caller":"traceutil/trace.go:171","msg":"trace[1312097508] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"855.163336ms","start":"2026-04-02T22:24:14.187076Z","end":"2026-04-02T22:24:15.04224Z","steps":["trace[1312097508] 'process raft request' (duration: 854.666548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:15.042395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:14.187057Z","time spent":"855.301368ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T22:24:15.042494Z","caller":"traceutil/trace.go:171","msg":"trace[1455539594] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"840.578863ms","start":"2026-04-02T22:24:14.201896Z","end":"2026-04-02T22:24:15.042475Z","steps":["trace[1455539594] 'process raft request' (duration: 840.240788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:15.042586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:14.201887Z","time spent":"840.658546ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T22:24:15.042693Z","caller":"traceutil/trace.go:171","msg":"trace[506809575] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"843.94259ms","start":"2026-04-02T22:24:14.198735Z","end":"2026-04-02T22:24:15.042678Z","steps":["trace[506809575] 'process raft request' (duration: 843.192718ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:24:15.042818Z","caller":"traceutil/trace.go:171","msg":"trace[1615477531] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"835.075183ms","start":"2026-04-02T22:24:14.207695Z","end":"2026-04-02T22:24:15.04277Z","steps":["trace[1615477531] 'process raft request' (duration: 834.612045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:15.042928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:14.198719Z","time spent":"844.133003ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:24:15.042998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:14.207686Z","time spent":"835.178856ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T22:24:15.043035Z","caller":"traceutil/trace.go:171","msg":"trace[272556058] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"840.32513ms","start":"2026-04-02T22:24:14.202685Z","end":"2026-04-02T22:24:15.04301Z","steps":["trace[272556058] 'process raft request' (duration: 839.508226ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:24:15.043056Z","caller":"traceutil/trace.go:171","msg":"trace[877767075] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"809.763133ms","start":"2026-04-02T22:24:14.233247Z","end":"2026-04-02T22:24:15.04301Z","steps":["trace[877767075] 'process raft request' (duration: 809.211883ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:24:15.043141Z","caller":"traceutil/trace.go:171","msg":"trace[405997782] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"838.161375ms","start":"2026-04-02T22:24:14.204965Z","end":"2026-04-02T22:24:15.043127Z","steps":["trace[405997782] 'process raft request' (duration: 837.28593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:15.043166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:14.202668Z","time spent":"840.437832ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:24:15.043193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:14.23323Z","time spent":"809.895315ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:24:15.04322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:14.204957Z","time spent":"838.229746ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T22:24:15.042726Z","caller":"traceutil/trace.go:171","msg":"trace[608716608] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"841.483839ms","start":"2026-04-02T22:24:14.201227Z","end":"2026-04-02T22:24:15.042711Z","steps":["trace[608716608] 'process raft request' (duration: 840.846928ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:24:15.043328Z","caller":"traceutil/trace.go:171","msg":"trace[1849730001] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"833.188652ms","start":"2026-04-02T22:24:14.210129Z","end":"2026-04-02T22:24:15.043317Z","steps":["trace[1849730001] 'process raft request' (duration: 832.233976ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:24:15.043338Z","caller":"traceutil/trace.go:171","msg":"trace[2133548009] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"798.317253ms","start":"2026-04-02T22:24:14.24501Z","end":"2026-04-02T22:24:15.043327Z","steps":["trace[2133548009] 'process raft request' (duration: 797.54683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:15.043375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:14.21012Z","time spent":"833.235453ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:24:15.043388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:14.244989Z","time spent":"798.375375ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:24:15.043525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:14.201201Z","time spent":"842.246312ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T22:24:15.043603Z","caller":"traceutil/trace.go:171","msg":"trace[969065980] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"672.082922ms","start":"2026-04-02T22:24:14.371507Z","end":"2026-04-02T22:24:15.04359Z","steps":["trace[969065980] 'process raft request' (duration: 671.217308ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:24:15.041821Z","caller":"traceutil/trace.go:171","msg":"trace[2066426982] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"860.542005ms","start":"2026-04-02T22:24:14.181229Z","end":"2026-04-02T22:24:15.041771Z","steps":["trace[2066426982] 'process raft request' (duration: 860.263831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:15.043667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:14.371476Z","time spent":"672.157874ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:24:15.043733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:14.181205Z","time spent":"862.501407ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T22:24:15.075527Z","caller":"traceutil/trace.go:171","msg":"trace[1851367623] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:558; }","duration":"1.056481268s","start":"2026-04-02T22:24:14.019022Z","end":"2026-04-02T22:24:15.075503Z","steps":["trace[1851367623] 'read index received' (duration: 995.91103ms)","trace[1851367623] 'applied index is now lower than readState.Index' (duration: 60.566838ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:24:15.075671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.056628271s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:24:15.075697Z","caller":"traceutil/trace.go:171","msg":"trace[582506397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:549; }","duration":"1.056693672s","start":"2026-04-02T22:24:14.018996Z","end":"2026-04-02T22:24:15.07569Z","steps":["trace[582506397] 'agreement among raft nodes before linearized reading' (duration: 1.056591271s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:15.075725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:14.018986Z","time spent":"1.056734333s","remote":"127.0.0.1:35414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T22:24:21.228068Z","caller":"traceutil/trace.go:171","msg":"trace[619949451] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"141.530291ms","start":"2026-04-02T22:24:21.086522Z","end":"2026-04-02T22:24:21.228052Z","steps":["trace[619949451] 'process raft request' (duration: 74.751149ms)","trace[619949451] 'compare' (duration: 66.300834ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:24:21.228304Z","caller":"traceutil/trace.go:171","msg":"trace[1925805745] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"139.051143ms","start":"2026-04-02T22:24:21.089243Z","end":"2026-04-02T22:24:21.228294Z","steps":["trace[1925805745] 'process raft request' (duration: 138.509183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:21.855022Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":184257602955670672,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T22:24:21.870278Z","caller":"traceutil/trace.go:171","msg":"trace[171729485] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"578.990484ms","start":"2026-04-02T22:24:21.291264Z","end":"2026-04-02T22:24:21.870255Z","steps":["trace[171729485] 'process raft request' (duration: 578.457235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:21.87043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:21.291239Z","time spent":"579.102016ms","remote":"127.0.0.1:35526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:24:21.876813Z","caller":"traceutil/trace.go:171","msg":"trace[266644072] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:670; }","duration":"522.236222ms","start":"2026-04-02T22:24:21.354549Z","end":"2026-04-02T22:24:21.876785Z","steps":["trace[266644072] 'read index received' (duration: 515.352502ms)","trace[266644072] 'applied index is now lower than readState.Index' (duration: 6.88287ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:24:21.876932Z","caller":"traceutil/trace.go:171","msg":"trace[1505031783] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"373.960942ms","start":"2026-04-02T22:24:21.50295Z","end":"2026-04-02T22:24:21.876911Z","steps":["trace[1505031783] 'process raft request' (duration: 373.766149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:21.877032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:21.502929Z","time spent":"374.057013ms","remote":"127.0.0.1:35512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":688,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:24:21.877134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.582738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:24:21.878522Z","caller":"traceutil/trace.go:171","msg":"trace[1646304824] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"524.000351ms","start":"2026-04-02T22:24:21.354505Z","end":"2026-04-02T22:24:21.878505Z","steps":["trace[1646304824] 'agreement among raft nodes before linearized reading' (duration: 522.520567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:21.877188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.541483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zmqpm\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-04-02T22:24:21.878677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:21.354483Z","time spent":"524.123322ms","remote":"127.0.0.1:35414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T22:24:21.878679Z","caller":"traceutil/trace.go:171","msg":"trace[1904958248] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zmqpm; range_end:; response_count:1; response_revision:643; }","duration":"201.037357ms","start":"2026-04-02T22:24:21.677606Z","end":"2026-04-02T22:24:21.878643Z","steps":["trace[1904958248] 'agreement among raft nodes before linearized reading' (duration: 199.39429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:22.527926Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":184257602955670677,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T22:24:22.713975Z","caller":"traceutil/trace.go:171","msg":"trace[686803564] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"823.908105ms","start":"2026-04-02T22:24:21.890041Z","end":"2026-04-02T22:24:22.713949Z","steps":["trace[686803564] 'process raft request' (duration: 823.711012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:22.714124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:21.890018Z","time spent":"824.028047ms","remote":"127.0.0.1:35602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:24:22.752365Z","caller":"traceutil/trace.go:171","msg":"trace[1100700798] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:672; }","duration":"725.1804ms","start":"2026-04-02T22:24:22.027169Z","end":"2026-04-02T22:24:22.75235Z","steps":["trace[1100700798] 'read index received' (duration: 686.577363ms)","trace[1100700798] 'applied index is now lower than readState.Index' (duration: 38.602307ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:24:22.752441Z","caller":"traceutil/trace.go:171","msg":"trace[805483348] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"828.496646ms","start":"2026-04-02T22:24:21.923939Z","end":"2026-04-02T22:24:22.752436Z","steps":["trace[805483348] 'process raft request' (duration: 828.324294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:22.752497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:21.92392Z","time spent":"828.538017ms","remote":"127.0.0.1:35968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:24:22.752778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.040852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-02T22:24:22.752812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.644947ms","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-04-02T22:24:22.752842Z","caller":"traceutil/trace.go:171","msg":"trace[458755593] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:645; }","duration":"725.707018ms","start":"2026-04-02T22:24:22.027128Z","end":"2026-04-02T22:24:22.752835Z","steps":["trace[458755593] 'agreement among raft nodes before linearized reading' (duration: 725.603056ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:24:22.752851Z","caller":"traceutil/trace.go:171","msg":"trace[539677026] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:645; }","duration":"664.140494ms","start":"2026-04-02T22:24:22.088692Z","end":"2026-04-02T22:24:22.752833Z","steps":["trace[539677026] 'agreement among raft nodes before linearized reading' (duration: 663.91717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:22.752862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:22.027113Z","time spent":"725.744328ms","remote":"127.0.0.1:35696","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-04-02T22:24:22.752887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:22.088679Z","time spent":"664.199884ms","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T22:24:22.752993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.120672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:24:22.753017Z","caller":"traceutil/trace.go:171","msg":"trace[791316055] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"398.176733ms","start":"2026-04-02T22:24:22.354832Z","end":"2026-04-02T22:24:22.753009Z","steps":["trace[791316055] 'agreement among raft nodes before linearized reading' (duration: 398.140073ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:22.753041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:22.354786Z","time spent":"398.246575ms","remote":"127.0.0.1:35414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T22:24:24.549922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.738130245s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T22:24:24.550008Z","caller":"traceutil/trace.go:171","msg":"trace[1172489976] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:674; }","duration":"1.786871555s","start":"2026-04-02T22:24:22.763122Z","end":"2026-04-02T22:24:24.549993Z","steps":["trace[1172489976] 'read index received' (duration: 7.960398ms)","trace[1172489976] 'applied index is now lower than readState.Index' (duration: 1.778910197s)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:24:24.550084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.786958297s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:24:24.550082Z","caller":"traceutil/trace.go:171","msg":"trace[1266562172] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"1.792776919s","start":"2026-04-02T22:24:22.757278Z","end":"2026-04-02T22:24:24.550055Z","steps":["trace[1266562172] 'process raft request' (duration: 54.443521ms)","trace[1266562172] 'compare' (duration: 1.737216771s)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:24:24.550112Z","caller":"traceutil/trace.go:171","msg":"trace[1965086673] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:647; }","duration":"1.786996547s","start":"2026-04-02T22:24:22.763104Z","end":"2026-04-02T22:24:24.550101Z","steps":["trace[1965086673] 'agreement among raft nodes before linearized reading' (duration: 1.786950366s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:24.550148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:22.763098Z","time spent":"1.787042868s","remote":"127.0.0.1:35672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-04-02T22:24:24.550207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:22.757241Z","time spent":"1.792898422s","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:24:25.0507Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":184257602955670686,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T22:24:25.354079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999843042s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-02T22:24:25.354166Z","caller":"traceutil/trace.go:171","msg":"trace[169695857] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999991453s","start":"2026-04-02T22:24:23.354149Z","end":"2026-04-02T22:24:25.354141Z","steps":["trace[169695857] 'agreement among raft nodes before linearized reading' (duration: 1.999872662s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:25.354212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:23.35413Z","time spent":"2.000073215s","remote":"127.0.0.1:35416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T22:24:25.551151Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":184257602955670686,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T22:24:26.051288Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":184257602955670686,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T22:24:26.260852Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.104502112s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T22:24:26.261238Z","caller":"traceutil/trace.go:171","msg":"trace[228190204] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"3.104971329s","start":"2026-04-02T22:24:23.156247Z","end":"2026-04-02T22:24:26.261218Z","steps":["trace[228190204] 'process raft request' (duration: 3.104810247s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:26.261399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:23.156224Z","time spent":"3.105075061s","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:24:26.296388Z","caller":"traceutil/trace.go:171","msg":"trace[5006356] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:677; }","duration":"1.746333199s","start":"2026-04-02T22:24:24.550031Z","end":"2026-04-02T22:24:26.296364Z","steps":["trace[5006356] 'read index received' (duration: 1.711225047s)","trace[5006356] 'applied index is now lower than readState.Index' (duration: 35.107072ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:24:26.296408Z","caller":"traceutil/trace.go:171","msg":"trace[101613563] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"1.334118617s","start":"2026-04-02T22:24:24.96226Z","end":"2026-04-02T22:24:26.296378Z","steps":["trace[101613563] 'process raft request' (duration: 1.333576149s)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:24:26.296515Z","caller":"traceutil/trace.go:171","msg":"trace[178445106] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"938.614102ms","start":"2026-04-02T22:24:25.357891Z","end":"2026-04-02T22:24:26.296506Z","steps":["trace[178445106] 'process raft request' (duration: 938.425549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:26.296551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:24.961804Z","time spent":"1.334666766s","remote":"127.0.0.1:35526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:24:26.29661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:25.357864Z","time spent":"938.680273ms","remote":"127.0.0.1:35512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:24:26.29698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.065483523s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-02T22:24:26.297021Z","caller":"traceutil/trace.go:171","msg":"trace[607191616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:650; }","duration":"3.065565035s","start":"2026-04-02T22:24:23.231445Z","end":"2026-04-02T22:24:26.29701Z","steps":["trace[607191616] 'agreement among raft nodes before linearized reading' (duration: 3.065434233s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:26.297052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:23.23142Z","time spent":"3.065624756s","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T22:24:26.297173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.522600073s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-02T22:24:26.297215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.065786428s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-02T22:24:26.297259Z","caller":"traceutil/trace.go:171","msg":"trace[1014600167] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:650; }","duration":"1.522733186s","start":"2026-04-02T22:24:24.774509Z","end":"2026-04-02T22:24:26.297242Z","steps":["trace[1014600167] 'agreement among raft nodes before linearized reading' (duration: 1.522522042s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:26.297301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:24.774489Z","time spent":"1.522803366s","remote":"127.0.0.1:35696","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":"info","ts":"2026-04-02T22:24:26.297272Z","caller":"traceutil/trace.go:171","msg":"trace[727526856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:650; }","duration":"3.06587287s","start":"2026-04-02T22:24:23.231384Z","end":"2026-04-02T22:24:26.297257Z","steps":["trace[727526856] 'agreement among raft nodes before linearized reading' (duration: 3.065767968s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:26.297385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.033707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:24:26.297429Z","caller":"traceutil/trace.go:171","msg":"trace[1944502394] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"937.116078ms","start":"2026-04-02T22:24:25.360303Z","end":"2026-04-02T22:24:26.297419Z","steps":["trace[1944502394] 'agreement among raft nodes before linearized reading' (duration: 937.056277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:26.297457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:25.360292Z","time spent":"937.159879ms","remote":"127.0.0.1:35414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T22:24:26.297433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:23.231366Z","time spent":"3.066054542s","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-02T22:24:28.371552Z","caller":"traceutil/trace.go:171","msg":"trace[832220192] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"414.338751ms","start":"2026-04-02T22:24:27.957176Z","end":"2026-04-02T22:24:28.371515Z","steps":["trace[832220192] 'process raft request' (duration: 414.228399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:28.37177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:27.957152Z","time spent":"414.487584ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T22:24:28.377068Z","caller":"traceutil/trace.go:171","msg":"trace[1571087702] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"219.263068ms","start":"2026-04-02T22:24:28.157778Z","end":"2026-04-02T22:24:28.377041Z","steps":["trace[1571087702] 'process raft request' (duration: 219.020834ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:24:31.102286Z","caller":"traceutil/trace.go:171","msg":"trace[2105513543] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"144.789612ms","start":"2026-04-02T22:24:30.957472Z","end":"2026-04-02T22:24:31.102261Z","steps":["trace[2105513543] 'process raft request' (duration: 144.609429ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:24:31.520853Z","caller":"traceutil/trace.go:171","msg":"trace[461408673] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"139.585091ms","start":"2026-04-02T22:24:31.381242Z","end":"2026-04-02T22:24:31.520827Z","steps":["trace[461408673] 'process raft request' (duration: 139.382528ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:24:39.062122Z","caller":"traceutil/trace.go:171","msg":"trace[1097102803] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"625.419205ms","start":"2026-04-02T22:24:38.436656Z","end":"2026-04-02T22:24:39.062075Z","steps":["trace[1097102803] 'process raft request' (duration: 625.223642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:39.062398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:38.436643Z","time spent":"625.606057ms","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:24:39.066602Z","caller":"traceutil/trace.go:171","msg":"trace[1083408948] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"628.964957ms","start":"2026-04-02T22:24:38.437615Z","end":"2026-04-02T22:24:39.06658Z","steps":["trace[1083408948] 'process raft request' (duration: 628.805924ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:24:39.066765Z","caller":"traceutil/trace.go:171","msg":"trace[1896788936] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:829; }","duration":"458.373977ms","start":"2026-04-02T22:24:38.60836Z","end":"2026-04-02T22:24:39.066734Z","steps":["trace[1896788936] 'read index received' (duration: 453.532545ms)","trace[1896788936] 'applied index is now lower than readState.Index' (duration: 4.839922ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:24:39.066809Z","caller":"traceutil/trace.go:171","msg":"trace[653643688] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"628.443889ms","start":"2026-04-02T22:24:38.438314Z","end":"2026-04-02T22:24:39.066758Z","steps":["trace[653643688] 'process raft request' (duration: 628.223226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:39.066942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.5735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-02T22:24:39.066951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:38.438302Z","time spent":"628.574831ms","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:24:39.066986Z","caller":"traceutil/trace.go:171","msg":"trace[418034959] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:798; }","duration":"458.6518ms","start":"2026-04-02T22:24:38.608323Z","end":"2026-04-02T22:24:39.066975Z","steps":["trace[418034959] 'agreement among raft nodes before linearized reading' (duration: 458.509178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:39.066947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:38.437587Z","time spent":"629.085548ms","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:24:39.067018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:38.608288Z","time spent":"458.723662ms","remote":"127.0.0.1:35696","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-04-02T22:24:42.335785Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":184257602955671009,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T22:24:42.83682Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":184257602955671009,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T22:24:43.337356Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":184257602955671009,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T22:24:43.829613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.762359666s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T22:24:43.829734Z","caller":"traceutil/trace.go:171","msg":"trace[1686554418] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:810; }","duration":"2.762484678s","start":"2026-04-02T22:24:41.067184Z","end":"2026-04-02T22:24:43.829669Z","steps":["trace[1686554418] 'range keys from in-memory index tree' (duration: 2.762190943s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:43.829779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:41.067161Z","time spent":"2.76260636s","remote":"127.0.0.1:35696","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-04-02T22:24:43.830073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.134608181s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T22:24:43.830112Z","caller":"traceutil/trace.go:171","msg":"trace[39584122] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:810; }","duration":"2.134684893s","start":"2026-04-02T22:24:41.695417Z","end":"2026-04-02T22:24:43.830102Z","steps":["trace[39584122] 'count revisions from in-memory index tree' (duration: 2.13451163s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:43.83014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:41.695392Z","time spent":"2.134740133s","remote":"127.0.0.1:35592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":29,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-04-02T22:24:43.830201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.758234715s","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-04-02T22:24:43.830278Z","caller":"traceutil/trace.go:171","msg":"trace[1757090765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:810; }","duration":"2.758354446s","start":"2026-04-02T22:24:41.071906Z","end":"2026-04-02T22:24:43.830261Z","steps":["trace[1757090765] 'range keys from in-memory index tree' (duration: 2.758090552s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:43.830318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:41.071896Z","time spent":"2.758412577s","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T22:24:43.830452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.738887746s","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-04-02T22:24:43.83052Z","caller":"traceutil/trace.go:171","msg":"trace[1072818693] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:810; }","duration":"2.738995568s","start":"2026-04-02T22:24:41.091514Z","end":"2026-04-02T22:24:43.830509Z","steps":["trace[1072818693] 'range keys from in-memory index tree' (duration: 2.738722135s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:43.830548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:41.091492Z","time spent":"2.739048209s","remote":"127.0.0.1:35696","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-04-02T22:24:43.830439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:41.279414Z","time spent":"2.551010236s","remote":"127.0.0.1:36946","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-02T22:24:43.830821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.758403227s","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-04-02T22:24:43.830862Z","caller":"traceutil/trace.go:171","msg":"trace[1326813984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:810; }","duration":"2.758458827s","start":"2026-04-02T22:24:41.072393Z","end":"2026-04-02T22:24:43.830852Z","steps":["trace[1326813984] 'range keys from in-memory index tree' (duration: 2.758292906s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:43.83089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:41.072384Z","time spent":"2.758499368s","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T22:24:43.83843Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":184257602955671009,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T22:24:44.22339Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.414819464s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T22:24:44.224358Z","caller":"traceutil/trace.go:171","msg":"trace[657448506] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"2.415519893s","start":"2026-04-02T22:24:41.808452Z","end":"2026-04-02T22:24:44.223972Z","steps":["trace[657448506] 'process raft request' (duration: 2.41528931s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:44.224526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:41.808428Z","time spent":"2.416013512s","remote":"127.0.0.1:35672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:24:44.354313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00001958s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-02T22:24:44.354473Z","caller":"traceutil/trace.go:171","msg":"trace[1241727296] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000229974s","start":"2026-04-02T22:24:42.35422Z","end":"2026-04-02T22:24:44.35445Z","steps":["trace[1241727296] 'agreement among raft nodes before linearized reading' (duration: 2.000052141s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:44.354551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:42.35418Z","time spent":"2.000358535s","remote":"127.0.0.1:35414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T22:24:44.394499Z","caller":"traceutil/trace.go:171","msg":"trace[582031271] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"559.093258ms","start":"2026-04-02T22:24:43.835376Z","end":"2026-04-02T22:24:44.394469Z","steps":["trace[582031271] 'process raft request' (duration: 552.308409ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:24:44.394654Z","caller":"traceutil/trace.go:171","msg":"trace[1792191836] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"558.911336ms","start":"2026-04-02T22:24:43.835705Z","end":"2026-04-02T22:24:44.394617Z","steps":["trace[1792191836] 'process raft request' (duration: 558.519281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:44.394702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:43.835355Z","time spent":"559.241501ms","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:24:44.394833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:43.835696Z","time spent":"559.025038ms","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:24:44.395124Z","caller":"traceutil/trace.go:171","msg":"trace[487384162] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"559.356252ms","start":"2026-04-02T22:24:43.835748Z","end":"2026-04-02T22:24:44.395104Z","steps":["trace[487384162] 'process raft request' (duration: 558.742074ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:24:44.395149Z","caller":"traceutil/trace.go:171","msg":"trace[423775917] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"558.683352ms","start":"2026-04-02T22:24:43.836429Z","end":"2026-04-02T22:24:44.395112Z","steps":["trace[423775917] 'process raft request' (duration: 558.120654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:44.395205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:43.83573Z","time spent":"559.432223ms","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:24:44.395282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:43.836399Z","time spent":"558.793035ms","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:24:44.73154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.926704ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-02T22:24:44.731672Z","caller":"traceutil/trace.go:171","msg":"trace[762377364] linearizableReadLoop","detail":"{readStateIndex:850; appliedIndex:845; }","duration":"2.896443971s","start":"2026-04-02T22:24:41.835203Z","end":"2026-04-02T22:24:44.731647Z","steps":["trace[762377364] 'read index received' (duration: 2.388380111s)","trace[762377364] 'applied index is now lower than readState.Index' (duration: 508.06155ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:24:44.731918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.896721534s","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-04-02T22:24:44.731957Z","caller":"traceutil/trace.go:171","msg":"trace[992549906] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:815; }","duration":"2.896813016s","start":"2026-04-02T22:24:41.835134Z","end":"2026-04-02T22:24:44.731947Z","steps":["trace[992549906] 'agreement among raft nodes before linearized reading' (duration: 2.896645154s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:44.731994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:41.835094Z","time spent":"2.896891127s","remote":"127.0.0.1:35696","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-04-02T22:24:44.732033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.713103328s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:24:44.732028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.75041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:24:44.732137Z","caller":"traceutil/trace.go:171","msg":"trace[1476711824] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:815; }","duration":"1.713265009s","start":"2026-04-02T22:24:43.01885Z","end":"2026-04-02T22:24:44.732115Z","steps":["trace[1476711824] 'agreement among raft nodes before linearized reading' (duration: 1.713086167s)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:24:44.732154Z","caller":"traceutil/trace.go:171","msg":"trace[1980078830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:815; }","duration":"376.925552ms","start":"2026-04-02T22:24:44.355211Z","end":"2026-04-02T22:24:44.732137Z","steps":["trace[1980078830] 'agreement among raft nodes before linearized reading' (duration: 376.739109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:44.732147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.137638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-02T22:24:44.732186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:43.018829Z","time spent":"1.713346431s","remote":"127.0.0.1:35868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-04-02T22:24:44.7322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:44.355191Z","time spent":"376.999014ms","remote":"127.0.0.1:35416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T22:24:44.732097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.207226194s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T22:24:44.732295Z","caller":"traceutil/trace.go:171","msg":"trace[915049235] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:815; }","duration":"1.207466057s","start":"2026-04-02T22:24:43.524812Z","end":"2026-04-02T22:24:44.732278Z","steps":["trace[915049235] 'agreement among raft nodes before linearized reading' (duration: 1.207205913s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:44.732405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:43.524792Z","time spent":"1.207594909s","remote":"127.0.0.1:35916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":2,"response size":29,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"info","ts":"2026-04-02T22:24:44.732232Z","caller":"traceutil/trace.go:171","msg":"trace[1206384104] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:815; }","duration":"282.292991ms","start":"2026-04-02T22:24:44.449919Z","end":"2026-04-02T22:24:44.732212Z","steps":["trace[1206384104] 'agreement among raft nodes before linearized reading' (duration: 282.124118ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:24:44.903846Z","caller":"traceutil/trace.go:171","msg":"trace[1161063199] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"166.257754ms","start":"2026-04-02T22:24:44.737569Z","end":"2026-04-02T22:24:44.903827Z","steps":["trace[1161063199] 'process raft request' (duration: 166.115382ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:24:45.013152Z","caller":"traceutil/trace.go:171","msg":"trace[1329650193] linearizableReadLoop","detail":"{readStateIndex:853; appliedIndex:851; }","duration":"272.583916ms","start":"2026-04-02T22:24:44.740547Z","end":"2026-04-02T22:24:45.013131Z","steps":["trace[1329650193] 'read index received' (duration: 163.134788ms)","trace[1329650193] 'applied index is now lower than readState.Index' (duration: 109.447958ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:24:45.013202Z","caller":"traceutil/trace.go:171","msg":"trace[898251153] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"274.84161ms","start":"2026-04-02T22:24:44.738323Z","end":"2026-04-02T22:24:45.013165Z","steps":["trace[898251153] 'process raft request' (duration: 227.87752ms)","trace[898251153] 'compare' (duration: 46.778507ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:24:45.013378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.719078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:24:45.013435Z","caller":"traceutil/trace.go:171","msg":"trace[480810725] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:817; }","duration":"272.890831ms","start":"2026-04-02T22:24:44.740531Z","end":"2026-04-02T22:24:45.013422Z","steps":["trace[480810725] 'agreement among raft nodes before linearized reading' (duration: 272.686169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:45.013437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.021547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-fb657\" ","response":"range_response_count:1 size:4040"} {"level":"info","ts":"2026-04-02T22:24:45.013503Z","caller":"traceutil/trace.go:171","msg":"trace[1059515610] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-fb657; range_end:; response_count:1; response_revision:817; }","duration":"208.129499ms","start":"2026-04-02T22:24:44.805349Z","end":"2026-04-02T22:24:45.013478Z","steps":["trace[1059515610] 'agreement among raft nodes before linearized reading' (duration: 207.924086ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:24:45.203704Z","caller":"traceutil/trace.go:171","msg":"trace[2044849678] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"182.421023ms","start":"2026-04-02T22:24:45.021263Z","end":"2026-04-02T22:24:45.203684Z","steps":["trace[2044849678] 'process raft request' (duration: 181.591591ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:25:03.211745Z","caller":"traceutil/trace.go:171","msg":"trace[1165906091] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"170.719165ms","start":"2026-04-02T22:25:03.041007Z","end":"2026-04-02T22:25:03.211727Z","steps":["trace[1165906091] 'process raft request' (duration: 170.608473ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:25:08.641732Z","caller":"traceutil/trace.go:171","msg":"trace[2008119596] linearizableReadLoop","detail":"{readStateIndex:1059; appliedIndex:1058; }","duration":"127.307579ms","start":"2026-04-02T22:25:08.514404Z","end":"2026-04-02T22:25:08.641712Z","steps":["trace[2008119596] 'read index received' (duration: 127.091086ms)","trace[2008119596] 'applied index is now lower than readState.Index' (duration: 215.373µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:25:08.641804Z","caller":"traceutil/trace.go:171","msg":"trace[1196260438] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"162.689474ms","start":"2026-04-02T22:25:08.479066Z","end":"2026-04-02T22:25:08.641756Z","steps":["trace[1196260438] 'process raft request' (duration: 162.480611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:25:08.641967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.553462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T22:25:08.642023Z","caller":"traceutil/trace.go:171","msg":"trace[1981015923] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1019; }","duration":"127.652374ms","start":"2026-04-02T22:25:08.514354Z","end":"2026-04-02T22:25:08.642007Z","steps":["trace[1981015923] 'agreement among raft nodes before linearized reading' (duration: 127.503202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:25:08.642114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.436919ms","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-04-02T22:25:08.642157Z","caller":"traceutil/trace.go:171","msg":"trace[1604112964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1019; }","duration":"100.51353ms","start":"2026-04-02T22:25:08.541631Z","end":"2026-04-02T22:25:08.642145Z","steps":["trace[1604112964] 'agreement among raft nodes before linearized reading' (duration: 100.418739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:25:08.642019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.65978ms","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-04-02T22:25:08.64226Z","caller":"traceutil/trace.go:171","msg":"trace[1806754422] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1019; }","duration":"110.926034ms","start":"2026-04-02T22:25:08.531325Z","end":"2026-04-02T22:25:08.642251Z","steps":["trace[1806754422] 'agreement among raft nodes before linearized reading' (duration: 110.600179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:25:08.642405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.795734ms","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-04-02T22:25:08.642538Z","caller":"traceutil/trace.go:171","msg":"trace[1633019691] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1019; }","duration":"101.041217ms","start":"2026-04-02T22:25:08.541477Z","end":"2026-04-02T22:25:08.642518Z","steps":["trace[1633019691] 'agreement among raft nodes before linearized reading' (duration: 100.676573ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:25:28.912564Z","caller":"traceutil/trace.go:171","msg":"trace[978087836] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"198.427271ms","start":"2026-04-02T22:25:28.714103Z","end":"2026-04-02T22:25:28.91253Z","steps":["trace[978087836] 'process raft request' (duration: 198.213268ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:25:29.174538Z","caller":"traceutil/trace.go:171","msg":"trace[1315805793] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"257.18186ms","start":"2026-04-02T22:25:28.917334Z","end":"2026-04-02T22:25:29.174515Z","steps":["trace[1315805793] 'process raft request' (duration: 257.026888ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:25:29.18758Z","caller":"traceutil/trace.go:171","msg":"trace[155071016] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"269.596103ms","start":"2026-04-02T22:25:28.917965Z","end":"2026-04-02T22:25:29.187561Z","steps":["trace[155071016] 'process raft request' (duration: 269.548362ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:25:29.187601Z","caller":"traceutil/trace.go:171","msg":"trace[1443116580] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"269.933688ms","start":"2026-04-02T22:25:28.91764Z","end":"2026-04-02T22:25:29.187574Z","steps":["trace[1443116580] 'process raft request' (duration: 269.800236ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:25:29.187823Z","caller":"traceutil/trace.go:171","msg":"trace[610866715] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"132.431479ms","start":"2026-04-02T22:25:29.055374Z","end":"2026-04-02T22:25:29.187806Z","steps":["trace[610866715] 'process raft request' (duration: 132.334048ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:25:29.187943Z","caller":"traceutil/trace.go:171","msg":"trace[808487665] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"269.691255ms","start":"2026-04-02T22:25:28.918085Z","end":"2026-04-02T22:25:29.187776Z","steps":["trace[808487665] 'process raft request' (duration: 269.454892ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:25:39.33861Z","caller":"traceutil/trace.go:171","msg":"trace[1889799882] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"119.730387ms","start":"2026-04-02T22:25:39.21886Z","end":"2026-04-02T22:25:39.338591Z","steps":["trace[1889799882] 'process raft request' (duration: 119.641636ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:25:39.338814Z","caller":"traceutil/trace.go:171","msg":"trace[995769956] linearizableReadLoop","detail":"{readStateIndex:1171; appliedIndex:1170; }","duration":"125.47361ms","start":"2026-04-02T22:25:39.2133Z","end":"2026-04-02T22:25:39.338774Z","steps":["trace[995769956] 'read index received' (duration: 44.012658ms)","trace[995769956] 'applied index is now lower than readState.Index' (duration: 81.460152ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:25:39.339032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.716993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-02T22:25:39.339082Z","caller":"traceutil/trace.go:171","msg":"trace[886207815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1124; }","duration":"125.803355ms","start":"2026-04-02T22:25:39.213269Z","end":"2026-04-02T22:25:39.339072Z","steps":["trace[886207815] 'agreement among raft nodes before linearized reading' (duration: 125.643823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:25:39.339172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.19253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-02T22:25:39.339213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.233762ms","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-04-02T22:25:39.33957Z","caller":"traceutil/trace.go:171","msg":"trace[1537337859] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1124; }","duration":"109.364853ms","start":"2026-04-02T22:25:39.22994Z","end":"2026-04-02T22:25:39.339305Z","steps":["trace[1537337859] 'agreement among raft nodes before linearized reading' (duration: 109.09995ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:25:39.339627Z","caller":"traceutil/trace.go:171","msg":"trace[608262768] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1124; }","duration":"116.672767ms","start":"2026-04-02T22:25:39.222939Z","end":"2026-04-02T22:25:39.339612Z","steps":["trace[608262768] 'agreement among raft nodes before linearized reading' (duration: 116.203141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:25:39.340149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.626359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-02T22:25:39.340246Z","caller":"traceutil/trace.go:171","msg":"trace[1968150149] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1124; }","duration":"117.736331ms","start":"2026-04-02T22:25:39.222495Z","end":"2026-04-02T22:25:39.340232Z","steps":["trace[1968150149] 'agreement among raft nodes before linearized reading' (duration: 116.541786ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:25:39.506884Z","caller":"traceutil/trace.go:171","msg":"trace[824636319] linearizableReadLoop","detail":"{readStateIndex:1177; appliedIndex:1172; }","duration":"154.369003ms","start":"2026-04-02T22:25:39.3525Z","end":"2026-04-02T22:25:39.506869Z","steps":["trace[824636319] 'read index received' (duration: 17.732769ms)","trace[824636319] 'applied index is now lower than readState.Index' (duration: 136.635484ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:25:39.507006Z","caller":"traceutil/trace.go:171","msg":"trace[1477260997] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"162.417628ms","start":"2026-04-02T22:25:39.344578Z","end":"2026-04-02T22:25:39.506996Z","steps":["trace[1477260997] 'process raft request' (duration: 162.234745ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:25:39.50692Z","caller":"traceutil/trace.go:171","msg":"trace[476508470] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"162.469979ms","start":"2026-04-02T22:25:39.344431Z","end":"2026-04-02T22:25:39.506901Z","steps":["trace[476508470] 'process raft request' (duration: 162.308696ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:25:39.507205Z","caller":"traceutil/trace.go:171","msg":"trace[866102973] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"162.482538ms","start":"2026-04-02T22:25:39.344712Z","end":"2026-04-02T22:25:39.507195Z","steps":["trace[866102973] 'process raft request' (duration: 162.128664ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:25:39.507255Z","caller":"traceutil/trace.go:171","msg":"trace[1993082038] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"163.458691ms","start":"2026-04-02T22:25:39.343777Z","end":"2026-04-02T22:25:39.507235Z","steps":["trace[1993082038] 'process raft request' (duration: 133.443203ms)","trace[1993082038] 'compare' (duration: 29.449811ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:25:39.507329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.815999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:25:39.507431Z","caller":"traceutil/trace.go:171","msg":"trace[59034148] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1129; }","duration":"154.923011ms","start":"2026-04-02T22:25:39.352481Z","end":"2026-04-02T22:25:39.507404Z","steps":["trace[59034148] 'agreement among raft nodes before linearized reading' (duration: 154.814039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:25:39.507586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.418558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-02T22:25:39.507627Z","caller":"traceutil/trace.go:171","msg":"trace[1922544500] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1129; }","duration":"135.48794ms","start":"2026-04-02T22:25:39.372129Z","end":"2026-04-02T22:25:39.507617Z","steps":["trace[1922544500] 'agreement among raft nodes before linearized reading' (duration: 135.415159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:26:02.028541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.91662ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T22:26:02.028692Z","caller":"traceutil/trace.go:171","msg":"trace[178797901] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"281.147734ms","start":"2026-04-02T22:26:01.747518Z","end":"2026-04-02T22:26:02.028666Z","steps":["trace[178797901] 'process raft request' (duration: 83.008161ms)","trace[178797901] 'compare' (duration: 197.776348ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:26:02.031116Z","caller":"traceutil/trace.go:171","msg":"trace[2106886773] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"283.474712ms","start":"2026-04-02T22:26:01.747609Z","end":"2026-04-02T22:26:02.031083Z","steps":["trace[2106886773] 'process raft request' (duration: 283.30596ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:26:02.03149Z","caller":"traceutil/trace.go:171","msg":"trace[244790152] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"283.731126ms","start":"2026-04-02T22:26:01.747737Z","end":"2026-04-02T22:26:02.031468Z","steps":["trace[244790152] 'process raft request' (duration: 283.310501ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:26:02.031699Z","caller":"traceutil/trace.go:171","msg":"trace[494836080] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"282.453109ms","start":"2026-04-02T22:26:01.749171Z","end":"2026-04-02T22:26:02.031624Z","steps":["trace[494836080] 'process raft request' (duration: 282.238646ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:26:13.712828Z","caller":"traceutil/trace.go:171","msg":"trace[143277775] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"119.325197ms","start":"2026-04-02T22:26:13.593444Z","end":"2026-04-02T22:26:13.712769Z","steps":["trace[143277775] 'process raft request' (duration: 118.920002ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:26:30.011739Z","caller":"traceutil/trace.go:171","msg":"trace[962498880] linearizableReadLoop","detail":"{readStateIndex:1382; appliedIndex:1381; }","duration":"129.624768ms","start":"2026-04-02T22:26:29.882067Z","end":"2026-04-02T22:26:30.011692Z","steps":["trace[962498880] 'read index received' (duration: 55.764098ms)","trace[962498880] 'applied index is now lower than readState.Index' (duration: 73.85858ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:26:30.012165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.053342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:105617"} {"level":"info","ts":"2026-04-02T22:26:30.012257Z","caller":"traceutil/trace.go:171","msg":"trace[257312121] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1322; }","duration":"130.226508ms","start":"2026-04-02T22:26:29.882016Z","end":"2026-04-02T22:26:30.012243Z","steps":["trace[257312121] 'agreement among raft nodes before linearized reading' (duration: 129.850145ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:26:30.012526Z","caller":"traceutil/trace.go:171","msg":"trace[888021954] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"132.267685ms","start":"2026-04-02T22:26:29.879537Z","end":"2026-04-02T22:26:30.011805Z","steps":["trace[888021954] 'process raft request' (duration: 58.361643ms)","trace[888021954] 'compare' (duration: 73.440926ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:26:30.212141Z","caller":"traceutil/trace.go:171","msg":"trace[786132731] linearizableReadLoop","detail":"{readStateIndex:1383; appliedIndex:1382; }","duration":"171.851227ms","start":"2026-04-02T22:26:30.040263Z","end":"2026-04-02T22:26:30.212114Z","steps":["trace[786132731] 'read index received' (duration: 165.067956ms)","trace[786132731] 'applied index is now lower than readState.Index' (duration: 6.781621ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:26:30.212251Z","caller":"traceutil/trace.go:171","msg":"trace[152962660] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"193.6845ms","start":"2026-04-02T22:26:30.01851Z","end":"2026-04-02T22:26:30.212194Z","steps":["trace[152962660] 'process raft request' (duration: 186.846887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:26:30.212347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.073265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-02T22:26:30.212384Z","caller":"traceutil/trace.go:171","msg":"trace[822799357] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1323; }","duration":"172.177158ms","start":"2026-04-02T22:26:30.040196Z","end":"2026-04-02T22:26:30.212373Z","steps":["trace[822799357] 'agreement among raft nodes before linearized reading' (duration: 172.080795ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:26:30.213228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.516598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:26:30.213649Z","caller":"traceutil/trace.go:171","msg":"trace[211435090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1323; }","duration":"112.003415ms","start":"2026-04-02T22:26:30.101619Z","end":"2026-04-02T22:26:30.213623Z","steps":["trace[211435090] 'agreement among raft nodes before linearized reading' (duration: 110.904069ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:26:30.395852Z","caller":"traceutil/trace.go:171","msg":"trace[1610828526] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"100.778458ms","start":"2026-04-02T22:26:30.295022Z","end":"2026-04-02T22:26:30.3958Z","steps":["trace[1610828526] 'process raft request' (duration: 49.120852ms)","trace[1610828526] 'compare' (duration: 51.404268ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:26:30.588888Z","caller":"traceutil/trace.go:171","msg":"trace[1536547260] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"187.448257ms","start":"2026-04-02T22:26:30.401412Z","end":"2026-04-02T22:26:30.58886Z","steps":["trace[1536547260] 'process raft request' (duration: 187.391814ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:26:30.588943Z","caller":"traceutil/trace.go:171","msg":"trace[1707223148] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"188.121729ms","start":"2026-04-02T22:26:30.400788Z","end":"2026-04-02T22:26:30.58891Z","steps":["trace[1707223148] 'process raft request' (duration: 119.52637ms)","trace[1707223148] 'compare' (duration: 68.309419ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:26:32.704713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.246846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:26:32.704769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.332641ms","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-04-02T22:26:32.704805Z","caller":"traceutil/trace.go:171","msg":"trace[1742811128] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1330; }","duration":"349.40498ms","start":"2026-04-02T22:26:32.35538Z","end":"2026-04-02T22:26:32.704785Z","steps":["trace[1742811128] 'range keys from in-memory index tree' (duration: 349.158913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:26:32.704869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:26:32.355357Z","time spent":"349.492393ms","remote":"127.0.0.1:35414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T22:26:32.704861Z","caller":"traceutil/trace.go:171","msg":"trace[1593904547] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1330; }","duration":"111.471276ms","start":"2026-04-02T22:26:32.593364Z","end":"2026-04-02T22:26:32.704836Z","steps":["trace[1593904547] 'range keys from in-memory index tree' (duration: 111.139905ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:26:32.705338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.64061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-02T22:26:32.705395Z","caller":"traceutil/trace.go:171","msg":"trace[687701121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1330; }","duration":"303.725512ms","start":"2026-04-02T22:26:32.401654Z","end":"2026-04-02T22:26:32.705379Z","steps":["trace[687701121] 'range keys from in-memory index tree' (duration: 302.775432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:26:32.705426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:26:32.40147Z","time spent":"303.94787ms","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T22:26:32.704909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.860808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T22:26:32.706038Z","caller":"traceutil/trace.go:171","msg":"trace[448650050] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1330; }","duration":"113.070706ms","start":"2026-04-02T22:26:32.592928Z","end":"2026-04-02T22:26:32.705998Z","steps":["trace[448650050] 'range keys from in-memory index tree' (duration: 111.60256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:26:33.234048Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":184257602955672248,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T22:26:33.345289Z","caller":"traceutil/trace.go:171","msg":"trace[1007608687] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"633.874762ms","start":"2026-04-02T22:26:32.711387Z","end":"2026-04-02T22:26:33.345262Z","steps":["trace[1007608687] 'process raft request' (duration: 633.637825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:26:33.345588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:26:32.711367Z","time spent":"634.047208ms","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:26:33.535823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.3083ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T22:26:33.536052Z","caller":"traceutil/trace.go:171","msg":"trace[918877376] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"823.459876ms","start":"2026-04-02T22:26:32.712567Z","end":"2026-04-02T22:26:33.536027Z","steps":["trace[918877376] 'process raft request' (duration: 717.831245ms)","trace[918877376] 'compare' (duration: 105.146035ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:26:33.536214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:26:32.712534Z","time spent":"823.640982ms","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:26:33.536585Z","caller":"traceutil/trace.go:171","msg":"trace[1606780272] linearizableReadLoop","detail":"{readStateIndex:1393; appliedIndex:1390; }","duration":"802.760607ms","start":"2026-04-02T22:26:32.733783Z","end":"2026-04-02T22:26:33.536544Z","steps":["trace[1606780272] 'read index received' (duration: 611.418876ms)","trace[1606780272] 'applied index is now lower than readState.Index' (duration: 191.338761ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:26:33.536645Z","caller":"traceutil/trace.go:171","msg":"trace[1167326241] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"823.56533ms","start":"2026-04-02T22:26:32.713062Z","end":"2026-04-02T22:26:33.536627Z","steps":["trace[1167326241] 'process raft request' (duration: 822.901638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:26:33.536844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.065557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2476"} {"level":"warn","ts":"2026-04-02T22:26:33.536856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:26:32.713035Z","time spent":"823.682573ms","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:26:33.536887Z","caller":"traceutil/trace.go:171","msg":"trace[1854350799] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1333; }","duration":"803.154169ms","start":"2026-04-02T22:26:32.733723Z","end":"2026-04-02T22:26:33.536877Z","steps":["trace[1854350799] 'agreement among raft nodes before linearized reading' (duration: 802.963603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:26:33.536923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:26:32.733701Z","time spent":"803.214802ms","remote":"127.0.0.1:35602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":2498,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"info","ts":"2026-04-02T22:26:34.494031Z","caller":"traceutil/trace.go:171","msg":"trace[1598474820] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"388.64821ms","start":"2026-04-02T22:26:34.105338Z","end":"2026-04-02T22:26:34.493987Z","steps":["trace[1598474820] 'process raft request' (duration: 387.164102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:26:34.494267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:26:34.10531Z","time spent":"388.834466ms","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:26:34.494578Z","caller":"traceutil/trace.go:171","msg":"trace[2114226594] linearizableReadLoop","detail":"{readStateIndex:1394; appliedIndex:1393; }","duration":"213.669221ms","start":"2026-04-02T22:26:34.280826Z","end":"2026-04-02T22:26:34.494495Z","steps":["trace[2114226594] 'read index received' (duration: 211.700927ms)","trace[2114226594] 'applied index is now lower than readState.Index' (duration: 1.956303ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:26:34.495032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.163686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-02T22:26:34.495082Z","caller":"traceutil/trace.go:171","msg":"trace[761090234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1334; }","duration":"214.318081ms","start":"2026-04-02T22:26:34.280751Z","end":"2026-04-02T22:26:34.495069Z","steps":["trace[761090234] 'agreement among raft nodes before linearized reading' (duration: 214.013941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:26:34.569667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.100213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T22:26:34.56979Z","caller":"traceutil/trace.go:171","msg":"trace[662592200] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1334; }","duration":"267.286889ms","start":"2026-04-02T22:26:34.302487Z","end":"2026-04-02T22:26:34.569774Z","steps":["trace[662592200] 'agreement among raft nodes before linearized reading' (duration: 267.095222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:26:34.570347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.434127ms","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-04-02T22:26:34.57046Z","caller":"traceutil/trace.go:171","msg":"trace[1450847554] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1335; }","duration":"254.579991ms","start":"2026-04-02T22:26:34.315853Z","end":"2026-04-02T22:26:34.570433Z","steps":["trace[1450847554] 'agreement among raft nodes before linearized reading' (duration: 254.340193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:26:34.570812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.783056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:26:34.570863Z","caller":"traceutil/trace.go:171","msg":"trace[1714282702] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1335; }","duration":"215.643023ms","start":"2026-04-02T22:26:34.355208Z","end":"2026-04-02T22:26:34.570851Z","steps":["trace[1714282702] 'agreement among raft nodes before linearized reading' (duration: 214.763495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:26:34.805962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.836135ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T22:26:34.806125Z","caller":"traceutil/trace.go:171","msg":"trace[619325417] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"231.228473ms","start":"2026-04-02T22:26:34.574832Z","end":"2026-04-02T22:26:34.806061Z","steps":["trace[619325417] 'process raft request' (duration: 61.210643ms)","trace[619325417] 'compare' (duration: 169.638198ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:26:35.46707Z","caller":"traceutil/trace.go:171","msg":"trace[1008904195] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"106.716806ms","start":"2026-04-02T22:26:35.360328Z","end":"2026-04-02T22:26:35.467045Z","steps":["trace[1008904195] 'process raft request' (duration: 106.579462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:26:36.769118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.995985ms","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-04-02T22:26:36.769205Z","caller":"traceutil/trace.go:171","msg":"trace[1836476707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1340; }","duration":"194.166271ms","start":"2026-04-02T22:26:36.575019Z","end":"2026-04-02T22:26:36.769185Z","steps":["trace[1836476707] 'range keys from in-memory index tree' (duration: 193.635584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:26:44.809251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.912053ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-02T22:26:44.809516Z","caller":"traceutil/trace.go:171","msg":"trace[707482890] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"225.10501ms","start":"2026-04-02T22:26:44.584396Z","end":"2026-04-02T22:26:44.809501Z","steps":["trace[707482890] 'process raft request' (duration: 224.944825ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:26:45.04246Z","caller":"traceutil/trace.go:171","msg":"trace[619055179] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"190.625096ms","start":"2026-04-02T22:26:44.851811Z","end":"2026-04-02T22:26:45.042436Z","steps":["trace[619055179] 'process raft request' (duration: 122.090977ms)","trace[619055179] 'compare' (duration: 68.276162ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:26:45.629347Z","caller":"traceutil/trace.go:171","msg":"trace[2131429220] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"102.542563ms","start":"2026-04-02T22:26:45.526778Z","end":"2026-04-02T22:26:45.629321Z","steps":["trace[2131429220] 'process raft request' (duration: 102.245544ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:26:46.101682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.615169ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T22:26:46.101771Z","caller":"traceutil/trace.go:171","msg":"trace[468051328] linearizableReadLoop","detail":"{readStateIndex:1441; appliedIndex:1440; }","duration":"131.27977ms","start":"2026-04-02T22:26:45.970479Z","end":"2026-04-02T22:26:46.101759Z","steps":["trace[468051328] 'read index received' (duration: 33.231µs)","trace[468051328] 'applied index is now lower than readState.Index' (duration: 131.245809ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:26:46.101901Z","caller":"traceutil/trace.go:171","msg":"trace[1708334267] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"467.380563ms","start":"2026-04-02T22:26:45.634491Z","end":"2026-04-02T22:26:46.101872Z","steps":["trace[1708334267] 'process raft request' (duration: 284.516697ms)","trace[1708334267] 'compare' (duration: 182.536537ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:26:46.102041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:26:45.634468Z","time spent":"467.501567ms","remote":"127.0.0.1:35696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:26:46.102594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.100404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T22:26:46.102628Z","caller":"traceutil/trace.go:171","msg":"trace[1018531135] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1378; }","duration":"132.184847ms","start":"2026-04-02T22:26:45.970434Z","end":"2026-04-02T22:26:46.102619Z","steps":["trace[1018531135] 'agreement among raft nodes before linearized reading' (duration: 131.356892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:26:46.315001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.065727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:26:46.315087Z","caller":"traceutil/trace.go:171","msg":"trace[157548040] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1378; }","duration":"103.16645ms","start":"2026-04-02T22:26:46.211901Z","end":"2026-04-02T22:26:46.315067Z","steps":["trace[157548040] 'count revisions from in-memory index tree' (duration: 103.002705ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:27:11.315968Z","caller":"traceutil/trace.go:171","msg":"trace[325153983] transaction","detail":"{read_only:false; response_revision:1467; number_of_response:1; }","duration":"111.18731ms","start":"2026-04-02T22:27:11.20475Z","end":"2026-04-02T22:27:11.315938Z","steps":["trace[325153983] 'process raft request' (duration: 111.041796ms)"],"step_count":1}