{"level":"warn","ts":"2026-04-08T22:50:44.633993Z","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-08T22:50:44.634076Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.43:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.43:2380","--initial-cluster=instance=https://162.253.55.43:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.43:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.43: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-08T22:50:44.634138Z","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-08T22:50:44.63415Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.43:2380"]} {"level":"info","ts":"2026-04-08T22:50:44.634167Z","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-08T22:50:44.635011Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"]} {"level":"info","ts":"2026-04-08T22:50:44.635084Z","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://162.253.55.43:2380"],"listen-peer-urls":["https://162.253.55.43:2380"],"advertise-client-urls":["https://162.253.55.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.43: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-08T22:50:44.643286Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.230939ms"} {"level":"info","ts":"2026-04-08T22:50:44.654833Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"14f8f2dfdaccebdb","cluster-id":"5f02ccb15672f35d"} {"level":"info","ts":"2026-04-08T22:50:44.65493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T22:50:44.654969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became follower at term 0"} {"level":"info","ts":"2026-04-08T22:50:44.654987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 14f8f2dfdaccebdb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T22:50:44.654996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became follower at term 1"} {"level":"info","ts":"2026-04-08T22:50:44.655035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=(1511224718245293019)"} {"level":"warn","ts":"2026-04-08T22:50:44.664369Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T22:50:44.666402Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T22:50:44.670274Z","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-08T22:50:44.673161Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"14f8f2dfdaccebdb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T22:50:44.674092Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"14f8f2dfdaccebdb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T22:50:44.674411Z","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-08T22:50:44.674667Z","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-08T22:50:44.675967Z","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-08T22:50:44.678867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=(1511224718245293019)"} {"level":"info","ts":"2026-04-08T22:50:44.678995Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5f02ccb15672f35d","local-member-id":"14f8f2dfdaccebdb","added-peer-id":"14f8f2dfdaccebdb","added-peer-peer-urls":["https://162.253.55.43:2380"]} {"level":"info","ts":"2026-04-08T22:50:44.680481Z","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-08T22:50:44.681134Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T22:50:44.680937Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"14f8f2dfdaccebdb","initial-advertise-peer-urls":["https://162.253.55.43:2380"],"listen-peer-urls":["https://162.253.55.43:2380"],"advertise-client-urls":["https://162.253.55.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T22:50:44.680745Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.43:2380"} {"level":"info","ts":"2026-04-08T22:50:44.681545Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.43:2380"} {"level":"info","ts":"2026-04-08T22:50:44.855647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T22:50:44.855738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T22:50:44.855761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb received MsgPreVoteResp from 14f8f2dfdaccebdb at term 1"} {"level":"info","ts":"2026-04-08T22:50:44.855791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became candidate at term 2"} {"level":"info","ts":"2026-04-08T22:50:44.855803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb received MsgVoteResp from 14f8f2dfdaccebdb at term 2"} {"level":"info","ts":"2026-04-08T22:50:44.855814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became leader at term 2"} {"level":"info","ts":"2026-04-08T22:50:44.855823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 14f8f2dfdaccebdb elected leader 14f8f2dfdaccebdb at term 2"} {"level":"info","ts":"2026-04-08T22:50:44.874083Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T22:50:44.884573Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T22:50:44.884589Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T22:50:44.884567Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"14f8f2dfdaccebdb","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.43:2379]}","request-path":"/0/members/14f8f2dfdaccebdb/attributes","cluster-id":"5f02ccb15672f35d","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T22:50:44.88502Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T22:50:44.885045Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T22:50:44.887242Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T22:50:44.887501Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5f02ccb15672f35d","local-member-id":"14f8f2dfdaccebdb","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T22:50:44.887671Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T22:50:44.887753Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T22:50:44.888088Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.43:2379"} {"level":"warn","ts":"2026-04-08T22:50:46.453813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.344757ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-04-08T22:50:46.453917Z","caller":"traceutil/trace.go:171","msg":"trace[490704037] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:5; }","duration":"165.446625ms","start":"2026-04-08T22:50:46.288456Z","end":"2026-04-08T22:50:46.453903Z","steps":["trace[490704037] 'read index received' (duration: 47.428673ms)","trace[490704037] 'applied index is now lower than readState.Index' (duration: 118.016792ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:50:46.454057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.334099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T22:50:46.454093Z","caller":"traceutil/trace.go:171","msg":"trace[345875496] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:3; }","duration":"193.631387ms","start":"2026-04-08T22:50:46.260449Z","end":"2026-04-08T22:50:46.45408Z","steps":["trace[345875496] 'agreement among raft nodes before linearized reading' (duration: 193.523898ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:50:46.454163Z","caller":"traceutil/trace.go:171","msg":"trace[1121227636] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"195.506984ms","start":"2026-04-08T22:50:46.25844Z","end":"2026-04-08T22:50:46.453947Z","steps":["trace[1121227636] 'process raft request' (duration: 77.462253ms)","trace[1121227636] 'compare' (duration: 117.245267ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:50:46.510451Z","caller":"traceutil/trace.go:171","msg":"trace[371253358] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"186.680424ms","start":"2026-04-08T22:50:46.323706Z","end":"2026-04-08T22:50:46.510387Z","steps":["trace[371253358] 'process raft request' (duration: 186.475666ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:50:46.510793Z","caller":"traceutil/trace.go:171","msg":"trace[1351790515] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"185.710689ms","start":"2026-04-08T22:50:46.325059Z","end":"2026-04-08T22:50:46.51077Z","steps":["trace[1351790515] 'process raft request' (duration: 185.400261ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:50:46.5112Z","caller":"traceutil/trace.go:171","msg":"trace[1546361796] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"185.943059ms","start":"2026-04-08T22:50:46.325238Z","end":"2026-04-08T22:50:46.511181Z","steps":["trace[1546361796] 'process raft request' (duration: 185.305383ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:50:46.511274Z","caller":"traceutil/trace.go:171","msg":"trace[1557145053] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"185.80511ms","start":"2026-04-08T22:50:46.325455Z","end":"2026-04-08T22:50:46.51126Z","steps":["trace[1557145053] 'process raft request' (duration: 185.77181ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:50:46.51132Z","caller":"traceutil/trace.go:171","msg":"trace[1212204299] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"185.963728ms","start":"2026-04-08T22:50:46.325311Z","end":"2026-04-08T22:50:46.511275Z","steps":["trace[1212204299] 'process raft request' (duration: 185.793639ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:50:46.511466Z","caller":"traceutil/trace.go:171","msg":"trace[1421012157] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"186.085128ms","start":"2026-04-08T22:50:46.325336Z","end":"2026-04-08T22:50:46.511421Z","steps":["trace[1421012157] 'process raft request' (duration: 185.864819ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:50:46.740226Z","caller":"traceutil/trace.go:171","msg":"trace[1707479089] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:6; }","duration":"286.243809ms","start":"2026-04-08T22:50:46.453951Z","end":"2026-04-08T22:50:46.740195Z","steps":["trace[1707479089] 'read index received' (duration: 56.156615ms)","trace[1707479089] 'applied index is now lower than readState.Index' (duration: 230.086204ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:50:46.740369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.406664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T22:50:46.740438Z","caller":"traceutil/trace.go:171","msg":"trace[1056178379] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:10; }","duration":"351.508743ms","start":"2026-04-08T22:50:46.388914Z","end":"2026-04-08T22:50:46.740423Z","steps":["trace[1056178379] 'agreement among raft nodes before linearized reading' (duration: 351.330634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:46.740422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.83463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-08T22:50:46.740478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:46.388904Z","time spent":"351.568094ms","remote":"127.0.0.1:55568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-04-08T22:50:46.74052Z","caller":"traceutil/trace.go:171","msg":"trace[2044510580] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"366.973109ms","start":"2026-04-08T22:50:46.373534Z","end":"2026-04-08T22:50:46.740507Z","steps":["trace[2044510580] 'agreement among raft nodes before linearized reading' (duration: 366.783451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:46.740624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:46.373478Z","time spent":"367.126529ms","remote":"127.0.0.1:55568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-08T22:50:46.740705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.99987ms","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-08T22:50:46.740701Z","caller":"traceutil/trace.go:171","msg":"trace[835728401] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"393.585251ms","start":"2026-04-08T22:50:46.34691Z","end":"2026-04-08T22:50:46.740495Z","steps":["trace[835728401] 'process raft request' (duration: 318.443434ms)","trace[835728401] 'compare' (duration: 74.71341ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:50:46.740937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:46.346889Z","time spent":"394.00904ms","remote":"127.0.0.1:55642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T22:50:46.740687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.795417ms","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-08T22:50:46.741103Z","caller":"traceutil/trace.go:171","msg":"trace[994169259] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"226.936795ms","start":"2026-04-08T22:50:46.514153Z","end":"2026-04-08T22:50:46.74109Z","steps":["trace[994169259] 'process raft request' (duration: 226.699347ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:50:46.741211Z","caller":"traceutil/trace.go:171","msg":"trace[82593158] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"226.689707ms","start":"2026-04-08T22:50:46.514514Z","end":"2026-04-08T22:50:46.741203Z","steps":["trace[82593158] 'process raft request' (duration: 226.452249ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:50:46.741343Z","caller":"traceutil/trace.go:171","msg":"trace[767981624] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"226.124901ms","start":"2026-04-08T22:50:46.515165Z","end":"2026-04-08T22:50:46.74129Z","steps":["trace[767981624] 'process raft request' (duration: 225.947621ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:50:46.741403Z","caller":"traceutil/trace.go:171","msg":"trace[1017215127] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"226.251459ms","start":"2026-04-08T22:50:46.515132Z","end":"2026-04-08T22:50:46.741383Z","steps":["trace[1017215127] 'process raft request' (duration: 225.884411ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:50:46.7415Z","caller":"traceutil/trace.go:171","msg":"trace[1258366694] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"226.297009ms","start":"2026-04-08T22:50:46.515193Z","end":"2026-04-08T22:50:46.74149Z","steps":["trace[1258366694] 'process raft request' (duration: 225.999611ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:50:46.741478Z","caller":"traceutil/trace.go:171","msg":"trace[1828282507] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"226.280889ms","start":"2026-04-08T22:50:46.515189Z","end":"2026-04-08T22:50:46.74147Z","steps":["trace[1828282507] 'process raft request' (duration: 225.954751ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:50:46.741618Z","caller":"traceutil/trace.go:171","msg":"trace[1871162707] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"417.766582ms","start":"2026-04-08T22:50:46.323839Z","end":"2026-04-08T22:50:46.741606Z","steps":["trace[1871162707] 'agreement among raft nodes before linearized reading' (duration: 416.775457ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:50:46.740748Z","caller":"traceutil/trace.go:171","msg":"trace[1374148376] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"346.05867ms","start":"2026-04-08T22:50:46.394676Z","end":"2026-04-08T22:50:46.740735Z","steps":["trace[1374148376] 'agreement among raft nodes before linearized reading' (duration: 345.994241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:46.741696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:46.394646Z","time spent":"347.040212ms","remote":"127.0.0.1:55568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-08T22:50:46.741714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:46.32383Z","time spent":"417.8484ms","remote":"127.0.0.1:55568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-08T22:50:46.740748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.810474ms","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-08T22:50:46.74184Z","caller":"traceutil/trace.go:171","msg":"trace[745690896] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"416.899467ms","start":"2026-04-08T22:50:46.324912Z","end":"2026-04-08T22:50:46.741812Z","steps":["trace[745690896] 'agreement among raft nodes before linearized reading' (duration: 415.816804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:46.741908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:46.324898Z","time spent":"416.993026ms","remote":"127.0.0.1:55550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-04-08T22:50:46.740785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.155751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.43\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-08T22:50:46.741846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.781404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T22:50:46.742044Z","caller":"traceutil/trace.go:171","msg":"trace[2074808682] range","detail":"{range_begin:/registry/masterleases/162.253.55.43; range_end:; response_count:0; response_revision:10; }","duration":"420.428803ms","start":"2026-04-08T22:50:46.321602Z","end":"2026-04-08T22:50:46.742031Z","steps":["trace[2074808682] 'agreement among raft nodes before linearized reading' (duration: 419.169101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:46.742524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:46.321591Z","time spent":"420.92181ms","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":26,"request content":"key:\"/registry/masterleases/162.253.55.43\" "} {"level":"info","ts":"2026-04-08T22:50:46.742072Z","caller":"traceutil/trace.go:171","msg":"trace[1583757200] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:16; }","duration":"275.015113ms","start":"2026-04-08T22:50:46.466989Z","end":"2026-04-08T22:50:46.742004Z","steps":["trace[1583757200] 'agreement among raft nodes before linearized reading' (duration: 274.247678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:46.740763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.494574ms","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-08T22:50:46.742767Z","caller":"traceutil/trace.go:171","msg":"trace[1105426875] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"350.559999ms","start":"2026-04-08T22:50:46.392184Z","end":"2026-04-08T22:50:46.742744Z","steps":["trace[1105426875] 'agreement among raft nodes before linearized reading' (duration: 348.471844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:46.742828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:46.392175Z","time spent":"350.640899ms","remote":"127.0.0.1:55568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-08T22:50:46.740792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.930327ms","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-08T22:50:46.742982Z","caller":"traceutil/trace.go:171","msg":"trace[1198021839] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"437.119761ms","start":"2026-04-08T22:50:46.305826Z","end":"2026-04-08T22:50:46.742946Z","steps":["trace[1198021839] 'agreement among raft nodes before linearized reading' (duration: 434.952676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:46.74308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:46.305807Z","time spent":"437.25422ms","remote":"127.0.0.1:55568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-08T22:50:47.719052Z","caller":"traceutil/trace.go:171","msg":"trace[539833318] transaction","detail":"{read_only:false; response_revision:128; number_of_response:1; }","duration":"385.596795ms","start":"2026-04-08T22:50:47.333237Z","end":"2026-04-08T22:50:47.718834Z","steps":["trace[539833318] 'process raft request' (duration: 373.177497ms)","trace[539833318] 'compare' (duration: 12.288839ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:50:47.719185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:47.333205Z","time spent":"385.928483ms","remote":"127.0.0.1:55810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":582,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T22:50:49.902653Z","caller":"traceutil/trace.go:171","msg":"trace[921500467] linearizableReadLoop","detail":"{readStateIndex:267; appliedIndex:266; }","duration":"130.373458ms","start":"2026-04-08T22:50:49.772252Z","end":"2026-04-08T22:50:49.902625Z","steps":["trace[921500467] 'read index received' (duration: 130.251028ms)","trace[921500467] 'applied index is now lower than readState.Index' (duration: 121.46µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:50:49.902728Z","caller":"traceutil/trace.go:171","msg":"trace[1703816620] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"146.928833ms","start":"2026-04-08T22:50:49.755764Z","end":"2026-04-08T22:50:49.902693Z","steps":["trace[1703816620] 'process raft request' (duration: 146.649923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:49.902796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.533139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:50:49.902824Z","caller":"traceutil/trace.go:171","msg":"trace[1482857819] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:261; }","duration":"130.663147ms","start":"2026-04-08T22:50:49.772151Z","end":"2026-04-08T22:50:49.902814Z","steps":["trace[1482857819] 'agreement among raft nodes before linearized reading' (duration: 130.575557ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:50:49.937296Z","caller":"traceutil/trace.go:171","msg":"trace[1497316673] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"106.412294ms","start":"2026-04-08T22:50:49.830851Z","end":"2026-04-08T22:50:49.937263Z","steps":["trace[1497316673] 'process raft request' (duration: 106.246204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:49.937377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.403681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"warn","ts":"2026-04-08T22:50:49.937381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.509076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:50:49.93743Z","caller":"traceutil/trace.go:171","msg":"trace[2056720884] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:262; }","duration":"107.521479ms","start":"2026-04-08T22:50:49.829894Z","end":"2026-04-08T22:50:49.937416Z","steps":["trace[2056720884] 'agreement among raft nodes before linearized reading' (duration: 107.3733ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:50:49.937478Z","caller":"traceutil/trace.go:171","msg":"trace[875263166] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:262; }","duration":"108.659426ms","start":"2026-04-08T22:50:49.828794Z","end":"2026-04-08T22:50:49.937453Z","steps":["trace[875263166] 'agreement among raft nodes before linearized reading' (duration: 108.482276ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:50:50.92178Z","caller":"traceutil/trace.go:171","msg":"trace[1925301963] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"166.767657ms","start":"2026-04-08T22:50:50.754974Z","end":"2026-04-08T22:50:50.921742Z","steps":["trace[1925301963] 'process raft request' (duration: 137.934278ms)","trace[1925301963] 'compare' (duration: 28.589619ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:50:51.107109Z","caller":"traceutil/trace.go:171","msg":"trace[955448623] transaction","detail":"{read_only:false; number_of_response:0; response_revision:302; }","duration":"241.778057ms","start":"2026-04-08T22:50:50.865294Z","end":"2026-04-08T22:50:51.107072Z","steps":["trace[955448623] 'process raft request' (duration: 203.676285ms)","trace[955448623] 'compare' (duration: 37.931193ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:50:51.107176Z","caller":"traceutil/trace.go:171","msg":"trace[82656367] transaction","detail":"{read_only:false; number_of_response:0; response_revision:302; }","duration":"241.490588ms","start":"2026-04-08T22:50:50.865682Z","end":"2026-04-08T22:50:51.107172Z","steps":["trace[82656367] 'process raft request' (duration: 241.338058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:51.107233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.081714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:50:51.107284Z","caller":"traceutil/trace.go:171","msg":"trace[432916112] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:0; response_revision:302; }","duration":"182.190794ms","start":"2026-04-08T22:50:50.925081Z","end":"2026-04-08T22:50:51.107272Z","steps":["trace[432916112] 'agreement among raft nodes before linearized reading' (duration: 182.090044ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:50:51.107116Z","caller":"traceutil/trace.go:171","msg":"trace[1757021459] transaction","detail":"{read_only:false; number_of_response:0; response_revision:302; }","duration":"241.438139ms","start":"2026-04-08T22:50:50.865654Z","end":"2026-04-08T22:50:51.107092Z","steps":["trace[1757021459] 'process raft request' (duration: 241.331499ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:50:51.10712Z","caller":"traceutil/trace.go:171","msg":"trace[298448220] linearizableReadLoop","detail":"{readStateIndex:315; appliedIndex:311; }","duration":"181.961646ms","start":"2026-04-08T22:50:50.925127Z","end":"2026-04-08T22:50:51.107089Z","steps":["trace[298448220] 'read index received' (duration: 143.858203ms)","trace[298448220] 'applied index is now lower than readState.Index' (duration: 38.101873ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:50:51.107408Z","caller":"traceutil/trace.go:171","msg":"trace[1309293838] transaction","detail":"{read_only:false; number_of_response:0; response_revision:302; }","duration":"240.373452ms","start":"2026-04-08T22:50:50.867026Z","end":"2026-04-08T22:50:51.1074Z","steps":["trace[1309293838] 'process raft request' (duration: 240.018083ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:50:51.260713Z","caller":"traceutil/trace.go:171","msg":"trace[2021183921] linearizableReadLoop","detail":"{readStateIndex:316; appliedIndex:315; }","duration":"153.32021ms","start":"2026-04-08T22:50:51.107364Z","end":"2026-04-08T22:50:51.260684Z","steps":["trace[2021183921] 'read index received' (duration: 153.097851ms)","trace[2021183921] 'applied index is now lower than readState.Index' (duration: 221.479µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:50:51.260825Z","caller":"traceutil/trace.go:171","msg":"trace[282546382] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"303.788113ms","start":"2026-04-08T22:50:50.956987Z","end":"2026-04-08T22:50:51.260775Z","steps":["trace[282546382] 'process raft request' (duration: 303.552724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:51.260872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.225892ms","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-08T22:50:51.260908Z","caller":"traceutil/trace.go:171","msg":"trace[1879384339] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:303; }","duration":"291.315873ms","start":"2026-04-08T22:50:50.969581Z","end":"2026-04-08T22:50:51.260897Z","steps":["trace[1879384339] 'agreement among raft nodes before linearized reading' (duration: 291.241443ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:51.261001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:50.956943Z","time spent":"303.957484ms","remote":"127.0.0.1:55656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6946,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:50:51.261024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.510791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5892"} {"level":"info","ts":"2026-04-08T22:50:51.261076Z","caller":"traceutil/trace.go:171","msg":"trace[87884306] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:303; }","duration":"152.595841ms","start":"2026-04-08T22:50:51.108467Z","end":"2026-04-08T22:50:51.261063Z","steps":["trace[87884306] 'agreement among raft nodes before linearized reading' (duration: 152.509612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:51.261168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.819905ms","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-08T22:50:51.26118Z","caller":"traceutil/trace.go:171","msg":"trace[1366657197] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:303; }","duration":"150.854204ms","start":"2026-04-08T22:50:51.110322Z","end":"2026-04-08T22:50:51.261176Z","steps":["trace[1366657197] 'agreement among raft nodes before linearized reading' (duration: 150.830495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:51.261284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.78359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"warn","ts":"2026-04-08T22:50:51.261277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.76152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3974"} {"level":"info","ts":"2026-04-08T22:50:51.261296Z","caller":"traceutil/trace.go:171","msg":"trace[694322167] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:303; }","duration":"152.83595ms","start":"2026-04-08T22:50:51.108457Z","end":"2026-04-08T22:50:51.261293Z","steps":["trace[694322167] 'agreement among raft nodes before linearized reading' (duration: 152.81005ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:50:51.261313Z","caller":"traceutil/trace.go:171","msg":"trace[544808539] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:303; }","duration":"152.826481ms","start":"2026-04-08T22:50:51.108477Z","end":"2026-04-08T22:50:51.261303Z","steps":["trace[544808539] 'agreement among raft nodes before linearized reading' (duration: 152.724031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:51.261471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.92128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"info","ts":"2026-04-08T22:50:51.261485Z","caller":"traceutil/trace.go:171","msg":"trace[1386349520] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:303; }","duration":"152.96021ms","start":"2026-04-08T22:50:51.108521Z","end":"2026-04-08T22:50:51.261481Z","steps":["trace[1386349520] 'agreement among raft nodes before linearized reading' (duration: 152.93022ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:50:53.413115Z","caller":"traceutil/trace.go:171","msg":"trace[81620937] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"109.176681ms","start":"2026-04-08T22:50:53.303903Z","end":"2026-04-08T22:50:53.41308Z","steps":["trace[81620937] 'process raft request' (duration: 108.948061ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:50:53.452569Z","caller":"traceutil/trace.go:171","msg":"trace[992175409] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"148.230818ms","start":"2026-04-08T22:50:53.304312Z","end":"2026-04-08T22:50:53.452543Z","steps":["trace[992175409] 'process raft request' (duration: 129.21949ms)","trace[992175409] 'compare' (duration: 18.897258ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:50:53.827135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.858763ms","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-08T22:50:53.827248Z","caller":"traceutil/trace.go:171","msg":"trace[1957479947] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:331; }","duration":"123.017423ms","start":"2026-04-08T22:50:53.70419Z","end":"2026-04-08T22:50:53.827208Z","steps":["trace[1957479947] 'range keys from in-memory index tree' (duration: 122.744283ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:50:54.257804Z","caller":"traceutil/trace.go:171","msg":"trace[68337637] linearizableReadLoop","detail":"{readStateIndex:346; appliedIndex:345; }","duration":"272.908074ms","start":"2026-04-08T22:50:53.984871Z","end":"2026-04-08T22:50:54.257779Z","steps":["trace[68337637] 'read index received' (duration: 194.63634ms)","trace[68337637] 'applied index is now lower than readState.Index' (duration: 78.270464ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:50:54.258043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.157763ms","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-08T22:50:54.258086Z","caller":"traceutil/trace.go:171","msg":"trace[2047451127] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:333; }","duration":"273.237724ms","start":"2026-04-08T22:50:53.984837Z","end":"2026-04-08T22:50:54.258074Z","steps":["trace[2047451127] 'agreement among raft nodes before linearized reading' (duration: 273.081993ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:50:54.258006Z","caller":"traceutil/trace.go:171","msg":"trace[56708816] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"302.810046ms","start":"2026-04-08T22:50:53.955114Z","end":"2026-04-08T22:50:54.257924Z","steps":["trace[56708816] 'process raft request' (duration: 224.470341ms)","trace[56708816] 'compare' (duration: 78.070494ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:50:54.258157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.737465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-08T22:50:54.258267Z","caller":"traceutil/trace.go:171","msg":"trace[988799405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:333; }","duration":"270.903355ms","start":"2026-04-08T22:50:53.987337Z","end":"2026-04-08T22:50:54.258241Z","steps":["trace[988799405] 'agreement among raft nodes before linearized reading' (duration: 270.688115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:54.25825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:53.955078Z","time spent":"303.094326ms","remote":"127.0.0.1:55664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":199,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T22:50:54.616294Z","caller":"traceutil/trace.go:171","msg":"trace[388586704] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"254.516617ms","start":"2026-04-08T22:50:54.361748Z","end":"2026-04-08T22:50:54.616264Z","steps":["trace[388586704] 'process raft request' (duration: 254.336236ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:50:54.755089Z","caller":"traceutil/trace.go:171","msg":"trace[2130601450] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"107.66196ms","start":"2026-04-08T22:50:54.647405Z","end":"2026-04-08T22:50:54.755067Z","steps":["trace[2130601450] 'process raft request' (duration: 48.154298ms)","trace[2130601450] 'compare' (duration: 59.397992ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:50:54.943733Z","caller":"traceutil/trace.go:171","msg":"trace[822459982] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"293.988545ms","start":"2026-04-08T22:50:54.649706Z","end":"2026-04-08T22:50:54.943695Z","steps":["trace[822459982] 'process raft request' (duration: 257.143138ms)","trace[822459982] 'compare' (duration: 36.644217ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:50:54.943769Z","caller":"traceutil/trace.go:171","msg":"trace[695466690] linearizableReadLoop","detail":"{readStateIndex:351; appliedIndex:349; }","duration":"288.408584ms","start":"2026-04-08T22:50:54.655326Z","end":"2026-04-08T22:50:54.943735Z","steps":["trace[695466690] 'read index received' (duration: 40.243967ms)","trace[695466690] 'applied index is now lower than readState.Index' (duration: 248.158497ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:50:54.944042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.38572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-04-08T22:50:54.94409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.708594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-04-08T22:50:54.944091Z","caller":"traceutil/trace.go:171","msg":"trace[278870291] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:339; }","duration":"268.46396ms","start":"2026-04-08T22:50:54.675615Z","end":"2026-04-08T22:50:54.944079Z","steps":["trace[278870291] 'agreement among raft nodes before linearized reading' (duration: 268.30643ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:50:54.944179Z","caller":"traceutil/trace.go:171","msg":"trace[161039850] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:339; }","duration":"288.849044ms","start":"2026-04-08T22:50:54.655315Z","end":"2026-04-08T22:50:54.944165Z","steps":["trace[161039850] 'agreement among raft nodes before linearized reading' (duration: 288.559954ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:50:54.944078Z","caller":"traceutil/trace.go:171","msg":"trace[496272052] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"184.746745ms","start":"2026-04-08T22:50:54.759082Z","end":"2026-04-08T22:50:54.943829Z","steps":["trace[496272052] 'process raft request' (duration: 184.542365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:54.944257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.620761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-04-08T22:50:54.944302Z","caller":"traceutil/trace.go:171","msg":"trace[2006426543] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:339; }","duration":"218.699371ms","start":"2026-04-08T22:50:54.725589Z","end":"2026-04-08T22:50:54.944289Z","steps":["trace[2006426543] 'agreement among raft nodes before linearized reading' (duration: 218.591371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:54.944361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.583759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-04-08T22:50:54.944422Z","caller":"traceutil/trace.go:171","msg":"trace[2028954722] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:339; }","duration":"255.636308ms","start":"2026-04-08T22:50:54.688748Z","end":"2026-04-08T22:50:54.944384Z","steps":["trace[2028954722] 'agreement among raft nodes before linearized reading' (duration: 255.574218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:54.944482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.029206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-04-08T22:50:54.944506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.381771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-04-08T22:50:54.944528Z","caller":"traceutil/trace.go:171","msg":"trace[558686146] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:339; }","duration":"189.108865ms","start":"2026-04-08T22:50:54.755409Z","end":"2026-04-08T22:50:54.944517Z","steps":["trace[558686146] 'agreement among raft nodes before linearized reading' (duration: 189.014705ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:50:54.94455Z","caller":"traceutil/trace.go:171","msg":"trace[535368782] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:339; }","duration":"218.441681ms","start":"2026-04-08T22:50:54.726096Z","end":"2026-04-08T22:50:54.944538Z","steps":["trace[535368782] 'agreement among raft nodes before linearized reading' (duration: 218.373301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:54.944461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.37556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-08T22:50:54.944611Z","caller":"traceutil/trace.go:171","msg":"trace[504229156] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:339; }","duration":"266.53646ms","start":"2026-04-08T22:50:54.678056Z","end":"2026-04-08T22:50:54.944593Z","steps":["trace[504229156] 'agreement among raft nodes before linearized reading' (duration: 266.34353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:54.944701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.461389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-04-08T22:50:54.944736Z","caller":"traceutil/trace.go:171","msg":"trace[107752293] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:339; }","duration":"147.538667ms","start":"2026-04-08T22:50:54.797189Z","end":"2026-04-08T22:50:54.944728Z","steps":["trace[107752293] 'agreement among raft nodes before linearized reading' (duration: 147.204557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:54.944712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.445571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-08T22:50:54.94481Z","caller":"traceutil/trace.go:171","msg":"trace[492949994] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:339; }","duration":"223.593342ms","start":"2026-04-08T22:50:54.721201Z","end":"2026-04-08T22:50:54.944794Z","steps":["trace[492949994] 'agreement among raft nodes before linearized reading' (duration: 223.124372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:54.944889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.928697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-08T22:50:54.945023Z","caller":"traceutil/trace.go:171","msg":"trace[1787418915] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:339; }","duration":"253.546927ms","start":"2026-04-08T22:50:54.691405Z","end":"2026-04-08T22:50:54.944952Z","steps":["trace[1787418915] 'agreement among raft nodes before linearized reading' (duration: 252.909587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:55.051533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.451025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-08T22:50:55.051623Z","caller":"traceutil/trace.go:171","msg":"trace[649739018] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:342; }","duration":"102.585955ms","start":"2026-04-08T22:50:54.949016Z","end":"2026-04-08T22:50:55.051602Z","steps":["trace[649739018] 'agreement among raft nodes before linearized reading' (duration: 102.434135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:55.051949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.626375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-08T22:50:55.052105Z","caller":"traceutil/trace.go:171","msg":"trace[962478644] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:342; }","duration":"102.833686ms","start":"2026-04-08T22:50:54.94923Z","end":"2026-04-08T22:50:55.052064Z","steps":["trace[962478644] 'agreement among raft nodes before linearized reading' (duration: 102.616566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:57.78934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.085427659s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:50:57.789505Z","caller":"traceutil/trace.go:171","msg":"trace[1872593082] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:393; }","duration":"1.08561724s","start":"2026-04-08T22:50:56.703865Z","end":"2026-04-08T22:50:57.789483Z","steps":["trace[1872593082] 'range keys from in-memory index tree' (duration: 1.085284819s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:57.949231Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995350720129020506,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T22:50:58.28618Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.29579432s","expected-duration":"1s"} {"level":"info","ts":"2026-04-08T22:50:58.286554Z","caller":"traceutil/trace.go:171","msg":"trace[626955399] linearizableReadLoop","detail":"{readStateIndex:408; appliedIndex:407; }","duration":"838.385497ms","start":"2026-04-08T22:50:57.448148Z","end":"2026-04-08T22:50:58.286534Z","steps":["trace[626955399] 'read index received' (duration: 838.177667ms)","trace[626955399] 'applied index is now lower than readState.Index' (duration: 204.91µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:50:58.286677Z","caller":"traceutil/trace.go:171","msg":"trace[858282614] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"1.296413612s","start":"2026-04-08T22:50:56.990242Z","end":"2026-04-08T22:50:58.286656Z","steps":["trace[858282614] 'process raft request' (duration: 1.296136541s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:58.2867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.540317ms","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-08T22:50:58.286735Z","caller":"traceutil/trace.go:171","msg":"trace[1024710203] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:394; }","duration":"838.628908ms","start":"2026-04-08T22:50:57.448095Z","end":"2026-04-08T22:50:58.286724Z","steps":["trace[1024710203] 'agreement among raft nodes before linearized reading' (duration: 838.547518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:58.286766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:57.448068Z","time spent":"838.691389ms","remote":"127.0.0.1:55730","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-08T22:50:58.286804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:56.990211Z","time spent":"1.296511102s","remote":"127.0.0.1:55730","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-08T22:50:58.286896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.944526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-08T22:50:58.286949Z","caller":"traceutil/trace.go:171","msg":"trace[56370249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:394; }","duration":"665.058047ms","start":"2026-04-08T22:50:57.621878Z","end":"2026-04-08T22:50:58.286936Z","steps":["trace[56370249] 'agreement among raft nodes before linearized reading' (duration: 664.952527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:58.286999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:57.621858Z","time spent":"665.133427ms","remote":"127.0.0.1:55730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T22:50:58.287143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.993125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5326"} {"level":"info","ts":"2026-04-08T22:50:58.287165Z","caller":"traceutil/trace.go:171","msg":"trace[570361968] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:394; }","duration":"495.055705ms","start":"2026-04-08T22:50:57.792104Z","end":"2026-04-08T22:50:58.287159Z","steps":["trace[570361968] 'agreement among raft nodes before linearized reading' (duration: 494.992624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:50:58.287184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:57.792086Z","time spent":"495.092374ms","remote":"127.0.0.1:55656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5348,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-04-08T22:50:59.570287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.904167ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-04-08T22:50:59.570578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:58.289227Z","time spent":"1.281347681s","remote":"127.0.0.1:55482","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-08T22:51:00.616253Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.017904023s","expected-duration":"1s"} {"level":"info","ts":"2026-04-08T22:51:00.617433Z","caller":"traceutil/trace.go:171","msg":"trace[1493148804] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"2.326477775s","start":"2026-04-08T22:50:58.290932Z","end":"2026-04-08T22:51:00.61741Z","steps":["trace[1493148804] 'process raft request' (duration: 2.325620161s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:00.617562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:58.290908Z","time spent":"2.326587226s","remote":"127.0.0.1:55730","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-08T22:51:00.617547Z","caller":"traceutil/trace.go:171","msg":"trace[1221361627] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"2.326343736s","start":"2026-04-08T22:50:58.291163Z","end":"2026-04-08T22:51:00.617506Z","steps":["trace[1221361627] 'process raft request' (duration: 2.326065154s)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:00.617652Z","caller":"traceutil/trace.go:171","msg":"trace[1164841099] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"2.322419322s","start":"2026-04-08T22:50:58.295209Z","end":"2026-04-08T22:51:00.617628Z","steps":["trace[1164841099] 'process raft request' (duration: 2.322153821s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:00.617709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:58.291154Z","time spent":"2.326471996s","remote":"127.0.0.1:55730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:51:00.618138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:58.295187Z","time spent":"2.322509214s","remote":"127.0.0.1:55656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5311,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:51:00.618592Z","caller":"traceutil/trace.go:171","msg":"trace[1828402837] linearizableReadLoop","detail":"{readStateIndex:412; appliedIndex:408; }","duration":"2.241659365s","start":"2026-04-08T22:50:58.375927Z","end":"2026-04-08T22:51:00.617587Z","steps":["trace[1828402837] 'read index received' (duration: 222.420896ms)","trace[1828402837] 'applied index is now lower than readState.Index' (duration: 2.019236769s)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:00.619944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.244038486s","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-08T22:51:00.620022Z","caller":"traceutil/trace.go:171","msg":"trace[153819102] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:397; }","duration":"2.244149828s","start":"2026-04-08T22:50:58.375842Z","end":"2026-04-08T22:51:00.619992Z","steps":["trace[153819102] 'agreement among raft nodes before linearized reading' (duration: 2.241876057s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:00.620059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:58.375823Z","time spent":"2.244227067s","remote":"127.0.0.1:55730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-08T22:51:00.636996Z","caller":"traceutil/trace.go:171","msg":"trace[497756605] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"548.608942ms","start":"2026-04-08T22:51:00.088369Z","end":"2026-04-08T22:51:00.636978Z","steps":["trace[497756605] 'process raft request' (duration: 548.539972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:00.637106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.199478655s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-08T22:51:00.637108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:00.088343Z","time spent":"548.708812ms","remote":"127.0.0.1:55730","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-08T22:51:00.637237Z","caller":"traceutil/trace.go:171","msg":"trace[99726055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:399; }","duration":"2.199558125s","start":"2026-04-08T22:50:58.437584Z","end":"2026-04-08T22:51:00.637142Z","steps":["trace[99726055] 'agreement among raft nodes before linearized reading' (duration: 2.199432134s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:00.637272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:58.43757Z","time spent":"2.199694875s","remote":"127.0.0.1:55730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-08T22:51:00.637022Z","caller":"traceutil/trace.go:171","msg":"trace[107212375] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"1.065295449s","start":"2026-04-08T22:50:59.571696Z","end":"2026-04-08T22:51:00.636991Z","steps":["trace[107212375] 'process raft request' (duration: 1.065049198s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:00.637481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:50:59.571678Z","time spent":"1.06570208s","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:51:10.888364Z","caller":"traceutil/trace.go:171","msg":"trace[444348328] linearizableReadLoop","detail":"{readStateIndex:472; appliedIndex:471; }","duration":"213.821243ms","start":"2026-04-08T22:51:10.674516Z","end":"2026-04-08T22:51:10.888337Z","steps":["trace[444348328] 'read index received' (duration: 213.733373ms)","trace[444348328] 'applied index is now lower than readState.Index' (duration: 86.86µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:51:10.888392Z","caller":"traceutil/trace.go:171","msg":"trace[879947975] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"213.916995ms","start":"2026-04-08T22:51:10.674448Z","end":"2026-04-08T22:51:10.888365Z","steps":["trace[879947975] 'process raft request' (duration: 213.755613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:10.888476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.944786ms","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-08T22:51:10.888507Z","caller":"traceutil/trace.go:171","msg":"trace[1821559223] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:454; }","duration":"214.056017ms","start":"2026-04-08T22:51:10.674443Z","end":"2026-04-08T22:51:10.888499Z","steps":["trace[1821559223] 'agreement among raft nodes before linearized reading' (duration: 213.975866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:10.893882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.226921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-08T22:51:10.893876Z","caller":"traceutil/trace.go:171","msg":"trace[201547882] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"217.693839ms","start":"2026-04-08T22:51:10.676166Z","end":"2026-04-08T22:51:10.893859Z","steps":["trace[201547882] 'process raft request' (duration: 217.573838ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:10.89391Z","caller":"traceutil/trace.go:171","msg":"trace[1577067245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:456; }","duration":"209.277981ms","start":"2026-04-08T22:51:10.684625Z","end":"2026-04-08T22:51:10.893903Z","steps":["trace[1577067245] 'agreement among raft nodes before linearized reading' (duration: 209.215891ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:13.033207Z","caller":"traceutil/trace.go:171","msg":"trace[1354143376] linearizableReadLoop","detail":"{readStateIndex:477; appliedIndex:476; }","duration":"135.829126ms","start":"2026-04-08T22:51:12.897354Z","end":"2026-04-08T22:51:13.033183Z","steps":["trace[1354143376] 'read index received' (duration: 135.615103ms)","trace[1354143376] 'applied index is now lower than readState.Index' (duration: 213.243µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:51:13.0335Z","caller":"traceutil/trace.go:171","msg":"trace[575399454] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"136.538344ms","start":"2026-04-08T22:51:12.896951Z","end":"2026-04-08T22:51:13.033489Z","steps":["trace[575399454] 'process raft request' (duration: 136.115909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:13.033708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.341322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-08T22:51:13.03375Z","caller":"traceutil/trace.go:171","msg":"trace[402339007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:459; }","duration":"136.417803ms","start":"2026-04-08T22:51:12.897322Z","end":"2026-04-08T22:51:13.03374Z","steps":["trace[402339007] 'agreement among raft nodes before linearized reading' (duration: 136.337912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:13.033895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.335711ms","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-08T22:51:13.033928Z","caller":"traceutil/trace.go:171","msg":"trace[306277996] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:459; }","duration":"132.391782ms","start":"2026-04-08T22:51:12.901529Z","end":"2026-04-08T22:51:13.033921Z","steps":["trace[306277996] 'agreement among raft nodes before linearized reading' (duration: 132.339921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:13.034098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.217233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-08T22:51:13.034131Z","caller":"traceutil/trace.go:171","msg":"trace[1602656209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:459; }","duration":"119.272333ms","start":"2026-04-08T22:51:12.914851Z","end":"2026-04-08T22:51:13.034124Z","steps":["trace[1602656209] 'agreement among raft nodes before linearized reading' (duration: 119.217513ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:13.249756Z","caller":"traceutil/trace.go:171","msg":"trace[934843910] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"211.437454ms","start":"2026-04-08T22:51:13.038287Z","end":"2026-04-08T22:51:13.249724Z","steps":["trace[934843910] 'process raft request' (duration: 154.855586ms)","trace[934843910] 'compare' (duration: 56.452727ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:51:13.258403Z","caller":"traceutil/trace.go:171","msg":"trace[778065641] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"219.342071ms","start":"2026-04-08T22:51:13.039022Z","end":"2026-04-08T22:51:13.258364Z","steps":["trace[778065641] 'process raft request' (duration: 219.170148ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:13.258621Z","caller":"traceutil/trace.go:171","msg":"trace[1605607091] linearizableReadLoop","detail":"{readStateIndex:480; appliedIndex:477; }","duration":"124.499819ms","start":"2026-04-08T22:51:13.134104Z","end":"2026-04-08T22:51:13.258604Z","steps":["trace[1605607091] 'read index received' (duration: 59.021901ms)","trace[1605607091] 'applied index is now lower than readState.Index' (duration: 65.476818ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:13.258754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.63176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:51:13.258795Z","caller":"traceutil/trace.go:171","msg":"trace[560651446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:462; }","duration":"124.684301ms","start":"2026-04-08T22:51:13.134098Z","end":"2026-04-08T22:51:13.258782Z","steps":["trace[560651446] 'agreement among raft nodes before linearized reading' (duration: 124.565569ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:13.258871Z","caller":"traceutil/trace.go:171","msg":"trace[2136153863] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"219.27008ms","start":"2026-04-08T22:51:13.039589Z","end":"2026-04-08T22:51:13.258859Z","steps":["trace[2136153863] 'process raft request' (duration: 218.737173ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:17.404678Z","caller":"traceutil/trace.go:171","msg":"trace[579912844] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"138.885414ms","start":"2026-04-08T22:51:17.265778Z","end":"2026-04-08T22:51:17.404663Z","steps":["trace[579912844] 'process raft request' (duration: 138.649119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:17.404926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.11162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-08T22:51:17.405Z","caller":"traceutil/trace.go:171","msg":"trace[1939279297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:468; }","duration":"117.268753ms","start":"2026-04-08T22:51:17.287715Z","end":"2026-04-08T22:51:17.404983Z","steps":["trace[1939279297] 'agreement among raft nodes before linearized reading' (duration: 117.09373ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:17.404617Z","caller":"traceutil/trace.go:171","msg":"trace[1781931263] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:486; }","duration":"116.833297ms","start":"2026-04-08T22:51:17.287759Z","end":"2026-04-08T22:51:17.404592Z","steps":["trace[1781931263] 'read index received' (duration: 116.617403ms)","trace[1781931263] 'applied index is now lower than readState.Index' (duration: 214.824µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:17.40717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.683845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T22:51:17.407239Z","caller":"traceutil/trace.go:171","msg":"trace[1683346642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:468; }","duration":"118.770646ms","start":"2026-04-08T22:51:17.288452Z","end":"2026-04-08T22:51:17.407222Z","steps":["trace[1683346642] 'agreement among raft nodes before linearized reading' (duration: 118.590074ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:18.737115Z","caller":"traceutil/trace.go:171","msg":"trace[1711211971] linearizableReadLoop","detail":"{readStateIndex:492; appliedIndex:491; }","duration":"148.264333ms","start":"2026-04-08T22:51:18.588829Z","end":"2026-04-08T22:51:18.737093Z","steps":["trace[1711211971] 'read index received' (duration: 148.09462ms)","trace[1711211971] 'applied index is now lower than readState.Index' (duration: 168.933µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:18.737234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.380605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:51:18.737278Z","caller":"traceutil/trace.go:171","msg":"trace[1183114610] range","detail":"{range_begin:/registry/clusterroles/cilium; range_end:; response_count:0; response_revision:472; }","duration":"148.464096ms","start":"2026-04-08T22:51:18.588804Z","end":"2026-04-08T22:51:18.737269Z","steps":["trace[1183114610] 'agreement among raft nodes before linearized reading' (duration: 148.374855ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:18.737354Z","caller":"traceutil/trace.go:171","msg":"trace[1134134847] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"149.163078ms","start":"2026-04-08T22:51:18.588168Z","end":"2026-04-08T22:51:18.737331Z","steps":["trace[1134134847] 'process raft request' (duration: 148.800212ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:19.024315Z","caller":"traceutil/trace.go:171","msg":"trace[836729212] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"168.015585ms","start":"2026-04-08T22:51:18.856278Z","end":"2026-04-08T22:51:19.024294Z","steps":["trace[836729212] 'process raft request' (duration: 167.673049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:21.627259Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995350720129020754,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T22:51:22.128255Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995350720129020754,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T22:51:22.136407Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.359376159s","expected-duration":"1s"} {"level":"info","ts":"2026-04-08T22:51:22.136687Z","caller":"traceutil/trace.go:171","msg":"trace[1893654080] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"1.359722615s","start":"2026-04-08T22:51:20.77694Z","end":"2026-04-08T22:51:22.136663Z","steps":["trace[1893654080] 'process raft request' (duration: 1.359609883s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:22.136818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:20.776921Z","time spent":"1.359838847s","remote":"127.0.0.1:55508","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-08T22:51:22.146506Z","caller":"traceutil/trace.go:171","msg":"trace[1323737236] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"1.366766477s","start":"2026-04-08T22:51:20.7797Z","end":"2026-04-08T22:51:22.146466Z","steps":["trace[1323737236] 'process raft request' (duration: 1.366606045s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:22.146602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:20.77968Z","time spent":"1.366866449s","remote":"127.0.0.1:55508","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-08T22:51:22.14669Z","caller":"traceutil/trace.go:171","msg":"trace[1076445023] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"1.36635387s","start":"2026-04-08T22:51:20.780328Z","end":"2026-04-08T22:51:22.146682Z","steps":["trace[1076445023] 'process raft request' (duration: 1.366064645s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:22.146723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:20.780321Z","time spent":"1.3663866s","remote":"127.0.0.1:55508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T22:51:22.146769Z","caller":"traceutil/trace.go:171","msg":"trace[846088614] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"1.353458557s","start":"2026-04-08T22:51:20.793304Z","end":"2026-04-08T22:51:22.146763Z","steps":["trace[846088614] 'process raft request' (duration: 1.353263763s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:22.146797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:20.7933Z","time spent":"1.353485177s","remote":"127.0.0.1:55508","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-08T22:51:22.146847Z","caller":"traceutil/trace.go:171","msg":"trace[2063019706] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"1.35359806s","start":"2026-04-08T22:51:20.793243Z","end":"2026-04-08T22:51:22.146842Z","steps":["trace[2063019706] 'process raft request' (duration: 1.353284984s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:22.146876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:20.793239Z","time spent":"1.35362405s","remote":"127.0.0.1:55508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T22:51:22.146927Z","caller":"traceutil/trace.go:171","msg":"trace[1515355181] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"1.353527137s","start":"2026-04-08T22:51:20.793394Z","end":"2026-04-08T22:51:22.146921Z","steps":["trace[1515355181] 'process raft request' (duration: 1.353261463s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:22.146964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:20.793389Z","time spent":"1.353562969s","remote":"127.0.0.1:55508","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-08T22:51:22.147007Z","caller":"traceutil/trace.go:171","msg":"trace[987054562] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"1.3536439s","start":"2026-04-08T22:51:20.793358Z","end":"2026-04-08T22:51:22.147002Z","steps":["trace[987054562] 'process raft request' (duration: 1.353248482s)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:22.147004Z","caller":"traceutil/trace.go:171","msg":"trace[902790404] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"1.349999468s","start":"2026-04-08T22:51:20.796972Z","end":"2026-04-08T22:51:22.146972Z","steps":["trace[902790404] 'process raft request' (duration: 1.349726582s)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:22.147077Z","caller":"traceutil/trace.go:171","msg":"trace[1563748577] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"1.342964336s","start":"2026-04-08T22:51:20.804108Z","end":"2026-04-08T22:51:22.147072Z","steps":["trace[1563748577] 'process raft request' (duration: 1.34264564s)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:22.146844Z","caller":"traceutil/trace.go:171","msg":"trace[112236577] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"1.353572269s","start":"2026-04-08T22:51:20.793183Z","end":"2026-04-08T22:51:22.146756Z","steps":["trace[112236577] 'process raft request' (duration: 1.353251183s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:22.147126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:20.796961Z","time spent":"1.35011262s","remote":"127.0.0.1:55508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T22:51:22.14716Z","caller":"traceutil/trace.go:171","msg":"trace[2107821401] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"1.33006899s","start":"2026-04-08T22:51:20.817068Z","end":"2026-04-08T22:51:22.147137Z","steps":["trace[2107821401] 'process raft request' (duration: 1.329779726s)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:22.147153Z","caller":"traceutil/trace.go:171","msg":"trace[1623814028] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:531; }","duration":"1.019934521s","start":"2026-04-08T22:51:21.127197Z","end":"2026-04-08T22:51:22.147131Z","steps":["trace[1623814028] 'read index received' (duration: 1.009404507s)","trace[1623814028] 'applied index is now lower than readState.Index' (duration: 10.529024ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:22.147192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:20.817051Z","time spent":"1.330130233s","remote":"127.0.0.1:55508","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-08T22:51:22.147039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:20.793355Z","time spent":"1.3536659s","remote":"127.0.0.1:55508","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-08T22:51:22.147295Z","caller":"traceutil/trace.go:171","msg":"trace[967255679] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"1.067712568s","start":"2026-04-08T22:51:21.079577Z","end":"2026-04-08T22:51:22.147289Z","steps":["trace[967255679] 'process raft request' (duration: 1.067500404s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:22.147341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:21.079551Z","time spent":"1.067764599s","remote":"127.0.0.1:55730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:51:22.147351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.020155206s","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-08T22:51:22.147376Z","caller":"traceutil/trace.go:171","msg":"trace[259541713] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:523; }","duration":"1.020181616s","start":"2026-04-08T22:51:21.127186Z","end":"2026-04-08T22:51:22.147368Z","steps":["trace[259541713] 'agreement among raft nodes before linearized reading' (duration: 1.020073374s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:22.147402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:21.127133Z","time spent":"1.020264499s","remote":"127.0.0.1:55730","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-08T22:51:22.147372Z","caller":"traceutil/trace.go:171","msg":"trace[1304424945] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"1.207460152s","start":"2026-04-08T22:51:20.939672Z","end":"2026-04-08T22:51:22.147132Z","steps":["trace[1304424945] 'process raft request' (duration: 1.20730328s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:22.147465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:20.939641Z","time spent":"1.207807888s","remote":"127.0.0.1:55508","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-08T22:51:22.147526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:20.793165Z","time spent":"1.354096247s","remote":"127.0.0.1:55508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T22:51:22.147104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:20.804088Z","time spent":"1.343003656s","remote":"127.0.0.1:55508","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-08T22:51:22.151184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.017479561s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:51:22.151248Z","caller":"traceutil/trace.go:171","msg":"trace[1855884840] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"1.017565601s","start":"2026-04-08T22:51:21.133666Z","end":"2026-04-08T22:51:22.151231Z","steps":["trace[1855884840] 'agreement among raft nodes before linearized reading' (duration: 1.017449059s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:22.15129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:21.133655Z","time spent":"1.017626353s","remote":"127.0.0.1:55464","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-08T22:51:22.151905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.201423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-08T22:51:22.151951Z","caller":"traceutil/trace.go:171","msg":"trace[954787249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:525; }","duration":"700.270625ms","start":"2026-04-08T22:51:21.451667Z","end":"2026-04-08T22:51:22.151937Z","steps":["trace[954787249] 'agreement among raft nodes before linearized reading' (duration: 700.195003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:22.151981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:21.451635Z","time spent":"700.337696ms","remote":"127.0.0.1:55730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T22:51:22.152188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.205149ms","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-08T22:51:22.152222Z","caller":"traceutil/trace.go:171","msg":"trace[804963669] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:525; }","duration":"731.283219ms","start":"2026-04-08T22:51:21.420929Z","end":"2026-04-08T22:51:22.152213Z","steps":["trace[804963669] 'agreement among raft nodes before linearized reading' (duration: 731.177238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:22.152247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:21.420908Z","time spent":"731.333871ms","remote":"127.0.0.1:55730","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-08T22:51:22.152759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.931405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-08T22:51:22.152801Z","caller":"traceutil/trace.go:171","msg":"trace[1177610817] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:525; }","duration":"659.001647ms","start":"2026-04-08T22:51:21.49379Z","end":"2026-04-08T22:51:22.152792Z","steps":["trace[1177610817] 'agreement among raft nodes before linearized reading' (duration: 657.976077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:22.152829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:21.493777Z","time spent":"659.045208ms","remote":"127.0.0.1:55730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-08T22:51:23.193613Z","caller":"traceutil/trace.go:171","msg":"trace[1541655973] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"281.027771ms","start":"2026-04-08T22:51:22.912555Z","end":"2026-04-08T22:51:23.193582Z","steps":["trace[1541655973] 'process raft request' (duration: 211.198589ms)","trace[1541655973] 'compare' (duration: 69.646848ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:51:23.209371Z","caller":"traceutil/trace.go:171","msg":"trace[1481934510] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"201.506054ms","start":"2026-04-08T22:51:23.007843Z","end":"2026-04-08T22:51:23.209349Z","steps":["trace[1481934510] 'process raft request' (duration: 200.553156ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:23.384505Z","caller":"traceutil/trace.go:171","msg":"trace[1867137524] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"134.667439ms","start":"2026-04-08T22:51:23.249796Z","end":"2026-04-08T22:51:23.384463Z","steps":["trace[1867137524] 'process raft request' (duration: 44.751777ms)","trace[1867137524] 'compare' (duration: 89.772889ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:51:23.678796Z","caller":"traceutil/trace.go:171","msg":"trace[1377837524] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"229.538884ms","start":"2026-04-08T22:51:23.449236Z","end":"2026-04-08T22:51:23.678775Z","steps":["trace[1377837524] 'read index received' (duration: 206.708602ms)","trace[1377837524] 'applied index is now lower than readState.Index' (duration: 22.829672ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:23.678942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.691527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-04-08T22:51:23.678972Z","caller":"traceutil/trace.go:171","msg":"trace[30766853] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:587; }","duration":"229.771909ms","start":"2026-04-08T22:51:23.449183Z","end":"2026-04-08T22:51:23.678955Z","steps":["trace[30766853] 'agreement among raft nodes before linearized reading' (duration: 229.660377ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:23.678959Z","caller":"traceutil/trace.go:171","msg":"trace[124331658] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"258.468212ms","start":"2026-04-08T22:51:23.420454Z","end":"2026-04-08T22:51:23.678922Z","steps":["trace[124331658] 'process raft request' (duration: 235.514568ms)","trace[124331658] 'compare' (duration: 22.635728ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:51:23.687171Z","caller":"traceutil/trace.go:171","msg":"trace[690786353] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"198.283553ms","start":"2026-04-08T22:51:23.488866Z","end":"2026-04-08T22:51:23.687149Z","steps":["trace[690786353] 'process raft request' (duration: 197.923606ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:26.982542Z","caller":"traceutil/trace.go:171","msg":"trace[1441850459] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"106.075445ms","start":"2026-04-08T22:51:26.876435Z","end":"2026-04-08T22:51:26.98251Z","steps":["trace[1441850459] 'process raft request' (duration: 41.285872ms)","trace[1441850459] 'compare' (duration: 64.6447ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:51:28.158586Z","caller":"traceutil/trace.go:171","msg":"trace[1055945259] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"232.85878ms","start":"2026-04-08T22:51:27.925701Z","end":"2026-04-08T22:51:28.15856Z","steps":["trace[1055945259] 'process raft request' (duration: 232.261227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:29.453292Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995350720129021000,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T22:51:29.874915Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.192562103s","expected-duration":"1s"} {"level":"info","ts":"2026-04-08T22:51:29.876139Z","caller":"traceutil/trace.go:171","msg":"trace[1493205840] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"1.199690716s","start":"2026-04-08T22:51:28.676422Z","end":"2026-04-08T22:51:29.876112Z","steps":["trace[1493205840] 'process raft request' (duration: 1.199019951s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:29.876278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:28.676399Z","time spent":"1.199798159s","remote":"127.0.0.1:55560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:51:30.228255Z","caller":"traceutil/trace.go:171","msg":"trace[620185165] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:644; }","duration":"1.275913423s","start":"2026-04-08T22:51:28.952319Z","end":"2026-04-08T22:51:30.228232Z","steps":["trace[620185165] 'read index received' (duration: 922.925129ms)","trace[620185165] 'applied index is now lower than readState.Index' (duration: 352.987374ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:51:30.228363Z","caller":"traceutil/trace.go:171","msg":"trace[499323345] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"1.453161161s","start":"2026-04-08T22:51:28.775192Z","end":"2026-04-08T22:51:30.228353Z","steps":["trace[499323345] 'process raft request' (duration: 1.452900166s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:30.228466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:28.775167Z","time spent":"1.453221093s","remote":"127.0.0.1:55730","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-08T22:51:30.228485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.276135748s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qdvch\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-08T22:51:30.22859Z","caller":"traceutil/trace.go:171","msg":"trace[1690891773] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qdvch; range_end:; response_count:1; response_revision:620; }","duration":"1.27629203s","start":"2026-04-08T22:51:28.952277Z","end":"2026-04-08T22:51:30.228569Z","steps":["trace[1690891773] 'agreement among raft nodes before linearized reading' (duration: 1.276086006s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:30.228635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:28.952258Z","time spent":"1.276367651s","remote":"127.0.0.1:55656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-qdvch\" "} {"level":"warn","ts":"2026-04-08T22:51:31.13432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000083793s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-08T22:51:31.134411Z","caller":"traceutil/trace.go:171","msg":"trace[628565036] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000223877s","start":"2026-04-08T22:51:29.134166Z","end":"2026-04-08T22:51:31.134389Z","steps":["trace[628565036] 'agreement among raft nodes before linearized reading' (duration: 2.000109534s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:31.134463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:29.13413Z","time spent":"2.00032116s","remote":"127.0.0.1:55464","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-08T22:51:32.448164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.880442048s","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-08T22:51:32.448291Z","caller":"traceutil/trace.go:171","msg":"trace[1889518755] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:620; }","duration":"1.880579791s","start":"2026-04-08T22:51:30.567694Z","end":"2026-04-08T22:51:32.448274Z","steps":["trace[1889518755] 'range keys from in-memory index tree' (duration: 1.880427298s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:32.449192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.030480825s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T22:51:32.449431Z","caller":"traceutil/trace.go:171","msg":"trace[157895259] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"101.585938ms","start":"2026-04-08T22:51:32.347833Z","end":"2026-04-08T22:51:32.449418Z","steps":["trace[157895259] 'process raft request' (duration: 101.543427ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:32.449435Z","caller":"traceutil/trace.go:171","msg":"trace[45425989] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"2.221088257s","start":"2026-04-08T22:51:30.228321Z","end":"2026-04-08T22:51:32.449409Z","steps":["trace[45425989] 'read index received' (duration: 190.263234ms)","trace[45425989] 'applied index is now lower than readState.Index' (duration: 2.030822353s)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:51:32.449524Z","caller":"traceutil/trace.go:171","msg":"trace[707439024] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"2.510559084s","start":"2026-04-08T22:51:29.938816Z","end":"2026-04-08T22:51:32.449375Z","steps":["trace[707439024] 'process raft request' (duration: 479.839883ms)","trace[707439024] 'compare' (duration: 2.029252358s)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:32.449684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.184201806s","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-08T22:51:32.449729Z","caller":"traceutil/trace.go:171","msg":"trace[509428870] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:623; }","duration":"2.184304118s","start":"2026-04-08T22:51:30.265415Z","end":"2026-04-08T22:51:32.449719Z","steps":["trace[509428870] 'agreement among raft nodes before linearized reading' (duration: 2.184127193s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:32.449771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:30.265395Z","time spent":"2.184367958s","remote":"127.0.0.1:55730","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-08T22:51:32.449857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.996767024s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-08T22:51:32.449914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.002730116s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-08T22:51:32.449953Z","caller":"traceutil/trace.go:171","msg":"trace[1595767635] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:623; }","duration":"2.00281174s","start":"2026-04-08T22:51:30.44713Z","end":"2026-04-08T22:51:32.449942Z","steps":["trace[1595767635] 'agreement among raft nodes before linearized reading' (duration: 2.002669166s)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:32.449958Z","caller":"traceutil/trace.go:171","msg":"trace[321811248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:623; }","duration":"1.996918327s","start":"2026-04-08T22:51:30.453013Z","end":"2026-04-08T22:51:32.449931Z","steps":["trace[321811248] 'agreement among raft nodes before linearized reading' (duration: 1.996728563s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:32.449999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:30.447107Z","time spent":"2.00288294s","remote":"127.0.0.1:55730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T22:51:32.450013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:30.453002Z","time spent":"1.996997018s","remote":"127.0.0.1:55730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T22:51:32.450044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.177538016s","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-08T22:51:32.450079Z","caller":"traceutil/trace.go:171","msg":"trace[1682897645] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:623; }","duration":"2.177592657s","start":"2026-04-08T22:51:30.272477Z","end":"2026-04-08T22:51:32.450069Z","steps":["trace[1682897645] 'agreement among raft nodes before linearized reading' (duration: 2.177492195s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:32.450117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:30.272467Z","time spent":"2.177643568s","remote":"127.0.0.1:55730","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-08T22:51:32.450322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.315069819s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:51:32.450379Z","caller":"traceutil/trace.go:171","msg":"trace[879004465] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"1.315154761s","start":"2026-04-08T22:51:31.13521Z","end":"2026-04-08T22:51:32.450365Z","steps":["trace[879004465] 'agreement among raft nodes before linearized reading' (duration: 1.315036527s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:32.450425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:31.135193Z","time spent":"1.315222132s","remote":"127.0.0.1:55454","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-08T22:51:32.449643Z","caller":"traceutil/trace.go:171","msg":"trace[1911534200] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"2.205034339s","start":"2026-04-08T22:51:30.244592Z","end":"2026-04-08T22:51:32.449626Z","steps":["trace[1911534200] 'process raft request' (duration: 2.204695522s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:32.450002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:29.938791Z","time spent":"2.510923642s","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T22:51:32.450634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:30.244579Z","time spent":"2.20597218s","remote":"127.0.0.1:55656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:51:33.693888Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.167147166s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-08T22:51:34.459123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000048497s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-08T22:51:34.45921Z","caller":"traceutil/trace.go:171","msg":"trace[1618757213] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000173691s","start":"2026-04-08T22:51:32.459018Z","end":"2026-04-08T22:51:34.459192Z","steps":["trace[1618757213] 'agreement among raft nodes before linearized reading' (duration: 2.000066798s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:34.45926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:32.459007Z","time spent":"2.000242281s","remote":"127.0.0.1:55464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/08 22:51:34 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-08T22:51:34.685954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.694452ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T22:51:34.68616Z","caller":"traceutil/trace.go:171","msg":"trace[479550416] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"2.228586659s","start":"2026-04-08T22:51:32.457541Z","end":"2026-04-08T22:51:34.686128Z","steps":["trace[479550416] 'process raft request' (duration: 1.236609869s)","trace[479550416] 'compare' (duration: 991.014916ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:34.68625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:32.457522Z","time spent":"2.228675641s","remote":"127.0.0.1:55730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:51:34.686263Z","caller":"traceutil/trace.go:171","msg":"trace[2127351208] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"2.228615529s","start":"2026-04-08T22:51:32.457621Z","end":"2026-04-08T22:51:34.686237Z","steps":["trace[2127351208] 'process raft request' (duration: 2.228419094s)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:34.686341Z","caller":"traceutil/trace.go:171","msg":"trace[719364110] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"2.228127168s","start":"2026-04-08T22:51:32.458197Z","end":"2026-04-08T22:51:34.686324Z","steps":["trace[719364110] 'process raft request' (duration: 2.227903503s)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:34.686434Z","caller":"traceutil/trace.go:171","msg":"trace[814861505] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"2.227009282s","start":"2026-04-08T22:51:32.459416Z","end":"2026-04-08T22:51:34.686425Z","steps":["trace[814861505] 'process raft request' (duration: 2.226713495s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:34.686428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:32.458185Z","time spent":"2.228200579s","remote":"127.0.0.1:55730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:51:34.686436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:32.457614Z","time spent":"2.228727322s","remote":"127.0.0.1:55730","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-08T22:51:34.686452Z","caller":"traceutil/trace.go:171","msg":"trace[1813732101] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:649; }","duration":"2.227380321s","start":"2026-04-08T22:51:32.459054Z","end":"2026-04-08T22:51:34.686434Z","steps":["trace[1813732101] 'read index received' (duration: 67.713881ms)","trace[1813732101] 'applied index is now lower than readState.Index' (duration: 2.15966495s)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:34.686524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:32.459406Z","time spent":"2.227053603s","remote":"127.0.0.1:55730","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-08T22:51:34.691859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.230131849s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T22:51:34.691904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.456477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:51:34.691926Z","caller":"traceutil/trace.go:171","msg":"trace[1509031782] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:628; }","duration":"2.2302153s","start":"2026-04-08T22:51:32.461694Z","end":"2026-04-08T22:51:34.69191Z","steps":["trace[1509031782] 'agreement among raft nodes before linearized reading' (duration: 2.230106287s)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:34.691952Z","caller":"traceutil/trace.go:171","msg":"trace[2018480974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"232.551449ms","start":"2026-04-08T22:51:34.459388Z","end":"2026-04-08T22:51:34.691939Z","steps":["trace[2018480974] 'agreement among raft nodes before linearized reading' (duration: 232.466747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:34.69197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:32.461689Z","time spent":"2.230269743s","remote":"127.0.0.1:56016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"info","ts":"2026-04-08T22:51:35.177112Z","caller":"traceutil/trace.go:171","msg":"trace[902686998] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"123.195729ms","start":"2026-04-08T22:51:35.053894Z","end":"2026-04-08T22:51:35.177089Z","steps":["trace[902686998] 'process raft request' (duration: 120.989034ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:35.195307Z","caller":"traceutil/trace.go:171","msg":"trace[1371775692] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"103.985058ms","start":"2026-04-08T22:51:35.091303Z","end":"2026-04-08T22:51:35.195288Z","steps":["trace[1371775692] 'process raft request' (duration: 103.514676ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:37.316353Z","caller":"traceutil/trace.go:171","msg":"trace[1903185809] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"121.588532ms","start":"2026-04-08T22:51:37.194736Z","end":"2026-04-08T22:51:37.316325Z","steps":["trace[1903185809] 'process raft request' (duration: 121.433337ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:37.913685Z","caller":"traceutil/trace.go:171","msg":"trace[21713429] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"122.139014ms","start":"2026-04-08T22:51:37.791523Z","end":"2026-04-08T22:51:37.913662Z","steps":["trace[21713429] 'process raft request' (duration: 121.998631ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:38.566741Z","caller":"traceutil/trace.go:171","msg":"trace[1404934399] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"173.768252ms","start":"2026-04-08T22:51:38.392947Z","end":"2026-04-08T22:51:38.566715Z","steps":["trace[1404934399] 'process raft request' (duration: 117.422242ms)","trace[1404934399] 'compare' (duration: 56.208387ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:39.344061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.703538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-04-08T22:51:39.344141Z","caller":"traceutil/trace.go:171","msg":"trace[2021318438] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:707; }","duration":"153.818791ms","start":"2026-04-08T22:51:39.190302Z","end":"2026-04-08T22:51:39.34412Z","steps":["trace[2021318438] 'range keys from in-memory index tree' (duration: 153.561714ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:40.83798Z","caller":"traceutil/trace.go:171","msg":"trace[1033371115] linearizableReadLoop","detail":"{readStateIndex:741; appliedIndex:740; }","duration":"129.27898ms","start":"2026-04-08T22:51:40.708677Z","end":"2026-04-08T22:51:40.837956Z","steps":["trace[1033371115] 'read index received' (duration: 129.043723ms)","trace[1033371115] 'applied index is now lower than readState.Index' (duration: 234.407µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:51:40.83802Z","caller":"traceutil/trace.go:171","msg":"trace[503498538] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"208.04908ms","start":"2026-04-08T22:51:40.629936Z","end":"2026-04-08T22:51:40.837985Z","steps":["trace[503498538] 'process raft request' (duration: 207.862645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:40.838213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.512657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-08T22:51:40.838344Z","caller":"traceutil/trace.go:171","msg":"trace[1228345326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:713; }","duration":"129.66124ms","start":"2026-04-08T22:51:40.708646Z","end":"2026-04-08T22:51:40.838307Z","steps":["trace[1228345326] 'agreement among raft nodes before linearized reading' (duration: 129.426494ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:41.150145Z","caller":"traceutil/trace.go:171","msg":"trace[1768534313] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:741; }","duration":"312.070124ms","start":"2026-04-08T22:51:40.838048Z","end":"2026-04-08T22:51:41.150118Z","steps":["trace[1768534313] 'read index received' (duration: 310.556134ms)","trace[1768534313] 'applied index is now lower than readState.Index' (duration: 1.5129ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:41.150401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.184001ms","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-08T22:51:41.150546Z","caller":"traceutil/trace.go:171","msg":"trace[1315673340] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:714; }","duration":"435.309477ms","start":"2026-04-08T22:51:40.71516Z","end":"2026-04-08T22:51:41.15047Z","steps":["trace[1315673340] 'agreement among raft nodes before linearized reading' (duration: 434.961466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:41.150648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:40.715147Z","time spent":"435.48705ms","remote":"127.0.0.1:55568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-08T22:51:41.15067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.295074ms","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-08T22:51:41.150511Z","caller":"traceutil/trace.go:171","msg":"trace[675157090] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"331.466609ms","start":"2026-04-08T22:51:40.819001Z","end":"2026-04-08T22:51:41.150468Z","steps":["trace[675157090] 'process raft request' (duration: 329.590719ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:41.150712Z","caller":"traceutil/trace.go:171","msg":"trace[1654128624] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:714; }","duration":"441.341216ms","start":"2026-04-08T22:51:40.709357Z","end":"2026-04-08T22:51:41.150699Z","steps":["trace[1654128624] 'agreement among raft nodes before linearized reading' (duration: 441.253023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:41.150783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:40.709352Z","time spent":"441.423467ms","remote":"127.0.0.1:55730","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-08T22:51:41.15084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:40.818968Z","time spent":"331.795399ms","remote":"127.0.0.1:55508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T22:51:41.15056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.288994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-08T22:51:41.150957Z","caller":"traceutil/trace.go:171","msg":"trace[142141479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:714; }","duration":"441.713796ms","start":"2026-04-08T22:51:40.709233Z","end":"2026-04-08T22:51:41.150947Z","steps":["trace[142141479] 'agreement among raft nodes before linearized reading' (duration: 441.163041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:41.150992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:40.709227Z","time spent":"441.757317ms","remote":"127.0.0.1:55730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T22:51:41.151072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.677804ms","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-08T22:51:41.151103Z","caller":"traceutil/trace.go:171","msg":"trace[593581917] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:714; }","duration":"441.711005ms","start":"2026-04-08T22:51:40.709385Z","end":"2026-04-08T22:51:41.151096Z","steps":["trace[593581917] 'agreement among raft nodes before linearized reading' (duration: 440.855101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:41.15114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:40.709351Z","time spent":"441.783068ms","remote":"127.0.0.1:55730","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-08T22:51:41.39977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.101531ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T22:51:41.40024Z","caller":"traceutil/trace.go:171","msg":"trace[173747148] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"558.470849ms","start":"2026-04-08T22:51:40.841711Z","end":"2026-04-08T22:51:41.400181Z","steps":["trace[173747148] 'process raft request' (duration: 449.882966ms)","trace[173747148] 'compare' (duration: 108.003768ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:51:41.400363Z","caller":"traceutil/trace.go:171","msg":"trace[2081002108] linearizableReadLoop","detail":"{readStateIndex:744; appliedIndex:742; }","duration":"250.102123ms","start":"2026-04-08T22:51:41.150216Z","end":"2026-04-08T22:51:41.400318Z","steps":["trace[2081002108] 'read index received' (duration: 141.337736ms)","trace[2081002108] 'applied index is now lower than readState.Index' (duration: 108.763477ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:41.400434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:40.841686Z","time spent":"558.634515ms","remote":"127.0.0.1:55730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:51:41.400512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.143771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qdvch\" ","response":"range_response_count:1 size:18512"} {"level":"info","ts":"2026-04-08T22:51:41.400583Z","caller":"traceutil/trace.go:171","msg":"trace[1751089173] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qdvch; range_end:; response_count:1; response_revision:716; }","duration":"371.255495ms","start":"2026-04-08T22:51:41.029311Z","end":"2026-04-08T22:51:41.400567Z","steps":["trace[1751089173] 'agreement among raft nodes before linearized reading' (duration: 371.039729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:41.400626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:41.029292Z","time spent":"371.326536ms","remote":"127.0.0.1:55656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18534,"request content":"key:\"/registry/pods/kube-system/cilium-qdvch\" "} {"level":"warn","ts":"2026-04-08T22:51:41.400583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.066252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:51:41.401286Z","caller":"traceutil/trace.go:171","msg":"trace[812161643] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:716; }","duration":"266.867154ms","start":"2026-04-08T22:51:41.134398Z","end":"2026-04-08T22:51:41.401265Z","steps":["trace[812161643] 'agreement among raft nodes before linearized reading' (duration: 265.93339ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:41.400749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.921075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-08T22:51:41.400835Z","caller":"traceutil/trace.go:171","msg":"trace[1754706554] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"492.369756ms","start":"2026-04-08T22:51:40.908429Z","end":"2026-04-08T22:51:41.400799Z","steps":["trace[1754706554] 'process raft request' (duration: 491.705548ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:41.401645Z","caller":"traceutil/trace.go:171","msg":"trace[897723154] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:716; }","duration":"384.767547ms","start":"2026-04-08T22:51:41.016738Z","end":"2026-04-08T22:51:41.401505Z","steps":["trace[897723154] 'agreement among raft nodes before linearized reading' (duration: 383.78564ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:41.401747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:40.908395Z","time spent":"493.280361ms","remote":"127.0.0.1:55508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:51:41.401783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:41.016718Z","time spent":"385.048615ms","remote":"127.0.0.1:55730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-08T22:51:41.401035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.166212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-04-08T22:51:41.40187Z","caller":"traceutil/trace.go:171","msg":"trace[1435991493] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:716; }","duration":"412.017435ms","start":"2026-04-08T22:51:40.98983Z","end":"2026-04-08T22:51:41.401848Z","steps":["trace[1435991493] 'agreement among raft nodes before linearized reading' (duration: 410.879464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:41.401929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:40.989808Z","time spent":"412.103327ms","remote":"127.0.0.1:55508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10399,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-04-08T22:51:41.439207Z","caller":"traceutil/trace.go:171","msg":"trace[603184119] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"283.702467ms","start":"2026-04-08T22:51:41.155484Z","end":"2026-04-08T22:51:41.439186Z","steps":["trace[603184119] 'process raft request' (duration: 283.662956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:41.439384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.839806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T22:51:41.43935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.387055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:51:41.439468Z","caller":"traceutil/trace.go:171","msg":"trace[2112818645] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:719; }","duration":"109.97007ms","start":"2026-04-08T22:51:41.329478Z","end":"2026-04-08T22:51:41.439448Z","steps":["trace[2112818645] 'agreement among raft nodes before linearized reading' (duration: 109.769985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:41.439417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.105464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:51:41.439576Z","caller":"traceutil/trace.go:171","msg":"trace[428246092] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:719; }","duration":"110.298849ms","start":"2026-04-08T22:51:41.329256Z","end":"2026-04-08T22:51:41.439555Z","steps":["trace[428246092] 'agreement among raft nodes before linearized reading' (duration: 110.101794ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:41.439659Z","caller":"traceutil/trace.go:171","msg":"trace[1679931192] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"284.255473ms","start":"2026-04-08T22:51:41.155391Z","end":"2026-04-08T22:51:41.439646Z","steps":["trace[1679931192] 'process raft request' (duration: 283.721399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:41.439661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.064142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-04-08T22:51:41.439399Z","caller":"traceutil/trace.go:171","msg":"trace[522995172] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"284.399576ms","start":"2026-04-08T22:51:41.154956Z","end":"2026-04-08T22:51:41.439356Z","steps":["trace[522995172] 'process raft request' (duration: 284.039287ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:41.43972Z","caller":"traceutil/trace.go:171","msg":"trace[246281676] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:719; }","duration":"250.162284ms","start":"2026-04-08T22:51:41.189543Z","end":"2026-04-08T22:51:41.439705Z","steps":["trace[246281676] 'agreement among raft nodes before linearized reading' (duration: 249.98796ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:41.439498Z","caller":"traceutil/trace.go:171","msg":"trace[2018002112] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:719; }","duration":"109.575189ms","start":"2026-04-08T22:51:41.329904Z","end":"2026-04-08T22:51:41.439479Z","steps":["trace[2018002112] 'agreement among raft nodes before linearized reading' (duration: 109.324353ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:47.546562Z","caller":"traceutil/trace.go:171","msg":"trace[1034239227] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"104.881984ms","start":"2026-04-08T22:51:47.441649Z","end":"2026-04-08T22:51:47.546531Z","steps":["trace[1034239227] 'process raft request' (duration: 104.650968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:47.889287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.687912ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-04-08T22:51:47.889394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:47.562553Z","time spent":"326.836967ms","remote":"127.0.0.1:55548","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-08T22:51:48.101783Z","caller":"traceutil/trace.go:171","msg":"trace[302352673] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"211.008417ms","start":"2026-04-08T22:51:47.890751Z","end":"2026-04-08T22:51:48.101759Z","steps":["trace[302352673] 'process raft request' (duration: 210.884493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:50.067289Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995350720129021298,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-08T22:51:50.3991Z","caller":"traceutil/trace.go:171","msg":"trace[69839892] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"833.555596ms","start":"2026-04-08T22:51:49.56551Z","end":"2026-04-08T22:51:50.399065Z","steps":["trace[69839892] 'process raft request' (duration: 833.297289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:50.399274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:49.565482Z","time spent":"833.69687ms","remote":"127.0.0.1:55730","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-08T22:51:50.456517Z","caller":"traceutil/trace.go:171","msg":"trace[133845750] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"889.43431ms","start":"2026-04-08T22:51:49.567053Z","end":"2026-04-08T22:51:50.456487Z","steps":["trace[133845750] 'process raft request' (duration: 878.619205ms)","trace[133845750] 'compare' (duration: 10.503567ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:50.456668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:49.567032Z","time spent":"889.567325ms","remote":"127.0.0.1:55730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:51:50.456745Z","caller":"traceutil/trace.go:171","msg":"trace[1522896920] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"888.523925ms","start":"2026-04-08T22:51:49.5682Z","end":"2026-04-08T22:51:50.456723Z","steps":["trace[1522896920] 'process raft request' (duration: 888.429162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:50.45689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:49.568184Z","time spent":"888.630827ms","remote":"127.0.0.1:55730","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-08T22:51:50.457021Z","caller":"traceutil/trace.go:171","msg":"trace[1713364653] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:812; }","duration":"890.085141ms","start":"2026-04-08T22:51:49.566924Z","end":"2026-04-08T22:51:50.45701Z","steps":["trace[1713364653] 'read index received' (duration: 832.465934ms)","trace[1713364653] 'applied index is now lower than readState.Index' (duration: 57.618057ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:50.457176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.248786ms","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-08T22:51:50.457206Z","caller":"traceutil/trace.go:171","msg":"trace[1504032711] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:782; }","duration":"890.302217ms","start":"2026-04-08T22:51:49.566893Z","end":"2026-04-08T22:51:50.457196Z","steps":["trace[1504032711] 'agreement among raft nodes before linearized reading' (duration: 890.192124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:50.457233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:49.566877Z","time spent":"890.349909ms","remote":"127.0.0.1:55730","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-08T22:51:50.457311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.462013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T22:51:50.457386Z","caller":"traceutil/trace.go:171","msg":"trace[2114353555] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:782; }","duration":"889.575286ms","start":"2026-04-08T22:51:49.567792Z","end":"2026-04-08T22:51:50.457367Z","steps":["trace[2114353555] 'agreement among raft nodes before linearized reading' (duration: 889.432962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:50.457432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:49.567725Z","time spent":"889.696129ms","remote":"127.0.0.1:55568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":29,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-04-08T22:51:50.457538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.002896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:51:50.45757Z","caller":"traceutil/trace.go:171","msg":"trace[1057914765] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:782; }","duration":"323.098309ms","start":"2026-04-08T22:51:50.134463Z","end":"2026-04-08T22:51:50.457561Z","steps":["trace[1057914765] 'agreement among raft nodes before linearized reading' (duration: 323.048247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:50.457618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:50.134445Z","time spent":"323.163872ms","remote":"127.0.0.1:55464","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-08T22:51:52.693895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.88048ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T22:51:52.694009Z","caller":"traceutil/trace.go:171","msg":"trace[1152904995] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:820; }","duration":"217.733086ms","start":"2026-04-08T22:51:52.476259Z","end":"2026-04-08T22:51:52.693992Z","steps":["trace[1152904995] 'read index received' (duration: 33.525042ms)","trace[1152904995] 'applied index is now lower than readState.Index' (duration: 184.206634ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:51:52.694148Z","caller":"traceutil/trace.go:171","msg":"trace[1637711458] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"229.652624ms","start":"2026-04-08T22:51:52.464455Z","end":"2026-04-08T22:51:52.694107Z","steps":["trace[1637711458] 'process raft request' (duration: 49.461844ms)","trace[1637711458] 'compare' (duration: 179.721014ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:52.694337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.073026ms","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-08T22:51:52.694381Z","caller":"traceutil/trace.go:171","msg":"trace[1455294215] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:789; }","duration":"218.148668ms","start":"2026-04-08T22:51:52.47622Z","end":"2026-04-08T22:51:52.694368Z","steps":["trace[1455294215] 'agreement among raft nodes before linearized reading' (duration: 217.819958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:53.476389Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995350720129021319,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-08T22:51:53.633487Z","caller":"traceutil/trace.go:171","msg":"trace[1229979983] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"934.720678ms","start":"2026-04-08T22:51:52.698736Z","end":"2026-04-08T22:51:53.633456Z","steps":["trace[1229979983] 'process raft request' (duration: 919.568856ms)","trace[1229979983] 'compare' (duration: 14.536983ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:53.633739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:52.698717Z","time spent":"934.840361ms","remote":"127.0.0.1:55730","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-08T22:51:53.750165Z","caller":"traceutil/trace.go:171","msg":"trace[2043588301] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"718.607351ms","start":"2026-04-08T22:51:53.031525Z","end":"2026-04-08T22:51:53.750133Z","steps":["trace[2043588301] 'process raft request' (duration: 716.161546ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:53.750329Z","caller":"traceutil/trace.go:171","msg":"trace[1408018117] linearizableReadLoop","detail":"{readStateIndex:823; appliedIndex:821; }","duration":"774.620515ms","start":"2026-04-08T22:51:52.975677Z","end":"2026-04-08T22:51:53.750298Z","steps":["trace[1408018117] 'read index received' (duration: 642.596436ms)","trace[1408018117] 'applied index is now lower than readState.Index' (duration: 132.021189ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:53.750435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:53.031495Z","time spent":"718.765206ms","remote":"127.0.0.1:55730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:51:53.764045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.362653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:51:53.764111Z","caller":"traceutil/trace.go:171","msg":"trace[211716563] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:791; }","duration":"788.481846ms","start":"2026-04-08T22:51:52.975614Z","end":"2026-04-08T22:51:53.764096Z","steps":["trace[211716563] 'agreement among raft nodes before linearized reading' (duration: 774.76832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:53.764098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.707025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-08T22:51:53.764154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:52.975576Z","time spent":"788.567249ms","remote":"127.0.0.1:55754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-04-08T22:51:53.764168Z","caller":"traceutil/trace.go:171","msg":"trace[1544853689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:791; }","duration":"127.819007ms","start":"2026-04-08T22:51:53.636331Z","end":"2026-04-08T22:51:53.76415Z","steps":["trace[1544853689] 'agreement among raft nodes before linearized reading' (duration: 127.626481ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:54.711608Z","caller":"traceutil/trace.go:171","msg":"trace[927214713] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"193.702389ms","start":"2026-04-08T22:51:54.51787Z","end":"2026-04-08T22:51:54.711572Z","steps":["trace[927214713] 'process raft request' (duration: 142.987644ms)","trace[927214713] 'compare' (duration: 50.422615ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:57.982459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.117495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-08T22:51:57.982444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.978124ms","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-08T22:51:57.982559Z","caller":"traceutil/trace.go:171","msg":"trace[717006136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:809; }","duration":"158.233219ms","start":"2026-04-08T22:51:57.824309Z","end":"2026-04-08T22:51:57.982542Z","steps":["trace[717006136] 'range keys from in-memory index tree' (duration: 157.939059ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:57.982587Z","caller":"traceutil/trace.go:171","msg":"trace[539577419] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:809; }","duration":"334.181092ms","start":"2026-04-08T22:51:57.648385Z","end":"2026-04-08T22:51:57.982566Z","steps":["trace[539577419] 'range keys from in-memory index tree' (duration: 333.740287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:57.982637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:57.648364Z","time spent":"334.261404ms","remote":"127.0.0.1:55730","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-08T22:51:58.634929Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995350720129021381,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-08T22:51:58.891006Z","caller":"traceutil/trace.go:171","msg":"trace[2030244501] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"903.659147ms","start":"2026-04-08T22:51:57.987321Z","end":"2026-04-08T22:51:58.89098Z","steps":["trace[2030244501] 'process raft request' (duration: 903.506771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:58.891142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:57.9873Z","time spent":"903.77222ms","remote":"127.0.0.1:55730","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-08T22:51:59.026795Z","caller":"traceutil/trace.go:171","msg":"trace[1670962713] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"1.039356244s","start":"2026-04-08T22:51:57.98741Z","end":"2026-04-08T22:51:59.026766Z","steps":["trace[1670962713] 'process raft request' (duration: 1.038259988s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:59.026951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:57.987384Z","time spent":"1.039487058s","remote":"127.0.0.1:55730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:51:59.027183Z","caller":"traceutil/trace.go:171","msg":"trace[676839707] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"921.947806ms","start":"2026-04-08T22:51:58.105199Z","end":"2026-04-08T22:51:59.027146Z","steps":["trace[676839707] 'process raft request' (duration: 921.499712ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:51:59.027178Z","caller":"traceutil/trace.go:171","msg":"trace[1686704896] linearizableReadLoop","detail":"{readStateIndex:845; appliedIndex:842; }","duration":"892.544495ms","start":"2026-04-08T22:51:58.134204Z","end":"2026-04-08T22:51:59.026749Z","steps":["trace[1686704896] 'read index received' (duration: 756.635181ms)","trace[1686704896] 'applied index is now lower than readState.Index' (duration: 135.908444ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:51:59.027374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.130595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:51:59.027438Z","caller":"traceutil/trace.go:171","msg":"trace[1287873337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:812; }","duration":"893.2581ms","start":"2026-04-08T22:51:58.134168Z","end":"2026-04-08T22:51:59.027426Z","steps":["trace[1287873337] 'agreement among raft nodes before linearized reading' (duration: 893.123565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:59.027525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:58.134156Z","time spent":"893.358392ms","remote":"127.0.0.1:55464","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-08T22:51:59.027495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:58.105182Z","time spent":"922.035079ms","remote":"127.0.0.1:55706","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-08T22:51:59.027679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.612916ms","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-08T22:51:59.027811Z","caller":"traceutil/trace.go:171","msg":"trace[1720507116] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:812; }","duration":"302.754751ms","start":"2026-04-08T22:51:58.724989Z","end":"2026-04-08T22:51:59.027744Z","steps":["trace[1720507116] 'agreement among raft nodes before linearized reading' (duration: 302.489503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:59.027961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:58.724944Z","time spent":"302.991079ms","remote":"127.0.0.1:55730","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-08T22:51:59.028007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.080521ms","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-08T22:51:59.028063Z","caller":"traceutil/trace.go:171","msg":"trace[1751423536] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:812; }","duration":"514.177954ms","start":"2026-04-08T22:51:58.51387Z","end":"2026-04-08T22:51:59.028048Z","steps":["trace[1751423536] 'agreement among raft nodes before linearized reading' (duration: 514.001978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:59.028118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:58.51385Z","time spent":"514.256336ms","remote":"127.0.0.1:55730","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-08T22:51:59.028124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.649309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-08T22:51:59.028184Z","caller":"traceutil/trace.go:171","msg":"trace[372758138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:812; }","duration":"291.72117ms","start":"2026-04-08T22:51:58.736443Z","end":"2026-04-08T22:51:59.028164Z","steps":["trace[372758138] 'agreement among raft nodes before linearized reading' (duration: 291.567316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:59.028546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.187888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.43\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-08T22:51:59.028618Z","caller":"traceutil/trace.go:171","msg":"trace[1135242524] range","detail":"{range_begin:/registry/masterleases/162.253.55.43; range_end:; response_count:1; response_revision:812; }","duration":"743.069396ms","start":"2026-04-08T22:51:58.285534Z","end":"2026-04-08T22:51:59.028604Z","steps":["trace[1135242524] 'agreement among raft nodes before linearized reading' (duration: 741.889567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:51:59.028659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:51:58.285517Z","time spent":"743.132909ms","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/162.253.55.43\" "} {"level":"info","ts":"2026-04-08T22:52:01.191853Z","caller":"traceutil/trace.go:171","msg":"trace[298098772] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"145.432483ms","start":"2026-04-08T22:52:01.046393Z","end":"2026-04-08T22:52:01.191826Z","steps":["trace[298098772] 'process raft request' (duration: 145.292079ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:52:01.193842Z","caller":"traceutil/trace.go:171","msg":"trace[1376137842] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"146.84122ms","start":"2026-04-08T22:52:01.046985Z","end":"2026-04-08T22:52:01.193826Z","steps":["trace[1376137842] 'process raft request' (duration: 146.725086ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:52:01.194174Z","caller":"traceutil/trace.go:171","msg":"trace[2002127808] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"146.655243ms","start":"2026-04-08T22:52:01.047488Z","end":"2026-04-08T22:52:01.194144Z","steps":["trace[2002127808] 'process raft request' (duration: 146.302192ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:52:13.0966Z","caller":"traceutil/trace.go:171","msg":"trace[560578093] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1003; }","duration":"142.823833ms","start":"2026-04-08T22:52:12.953756Z","end":"2026-04-08T22:52:13.09658Z","steps":["trace[560578093] 'read index received' (duration: 142.707439ms)","trace[560578093] 'applied index is now lower than readState.Index' (duration: 115.384µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:52:13.096653Z","caller":"traceutil/trace.go:171","msg":"trace[91749253] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"455.746623ms","start":"2026-04-08T22:52:12.640875Z","end":"2026-04-08T22:52:13.096621Z","steps":["trace[91749253] 'process raft request' (duration: 455.587928ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:13.096802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.037131ms","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-08T22:52:13.096839Z","caller":"traceutil/trace.go:171","msg":"trace[1510955257] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:968; }","duration":"143.116593ms","start":"2026-04-08T22:52:12.953711Z","end":"2026-04-08T22:52:13.096827Z","steps":["trace[1510955257] 'agreement among raft nodes before linearized reading' (duration: 142.992498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:13.096882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:52:12.640838Z","time spent":"455.884488ms","remote":"127.0.0.1:55656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5468,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:52:27.628635Z","caller":"traceutil/trace.go:171","msg":"trace[1494901943] linearizableReadLoop","detail":"{readStateIndex:1072; appliedIndex:1071; }","duration":"125.050008ms","start":"2026-04-08T22:52:27.503564Z","end":"2026-04-08T22:52:27.628614Z","steps":["trace[1494901943] 'read index received' (duration: 124.873302ms)","trace[1494901943] 'applied index is now lower than readState.Index' (duration: 175.906µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:52:27.629076Z","caller":"traceutil/trace.go:171","msg":"trace[1162580464] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"160.750377ms","start":"2026-04-08T22:52:27.468303Z","end":"2026-04-08T22:52:27.629054Z","steps":["trace[1162580464] 'process raft request' (duration: 160.193815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:27.629397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.816208ms","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-08T22:52:27.629444Z","caller":"traceutil/trace.go:171","msg":"trace[1695037101] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1033; }","duration":"125.899322ms","start":"2026-04-08T22:52:27.503533Z","end":"2026-04-08T22:52:27.629433Z","steps":["trace[1695037101] 'agreement among raft nodes before linearized reading' (duration: 125.761987ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:27.629792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.988655ms","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-08T22:52:27.629826Z","caller":"traceutil/trace.go:171","msg":"trace[1122154634] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1033; }","duration":"126.061088ms","start":"2026-04-08T22:52:27.503755Z","end":"2026-04-08T22:52:27.629816Z","steps":["trace[1122154634] 'agreement among raft nodes before linearized reading' (duration: 125.978465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:52:27.630068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.589229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-08T22:52:27.6301Z","caller":"traceutil/trace.go:171","msg":"trace[1154767941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1033; }","duration":"125.622751ms","start":"2026-04-08T22:52:27.504469Z","end":"2026-04-08T22:52:27.630092Z","steps":["trace[1154767941] 'agreement among raft nodes before linearized reading' (duration: 125.541937ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:01.488659Z","caller":"traceutil/trace.go:171","msg":"trace[2092013895] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"133.676394ms","start":"2026-04-08T22:53:01.354939Z","end":"2026-04-08T22:53:01.488615Z","steps":["trace[2092013895] 'process raft request' (duration: 133.253386ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:04.065811Z","caller":"traceutil/trace.go:171","msg":"trace[1123525515] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"163.472358ms","start":"2026-04-08T22:53:03.902312Z","end":"2026-04-08T22:53:04.065785Z","steps":["trace[1123525515] 'process raft request' (duration: 116.127631ms)","trace[1123525515] 'compare' (duration: 47.114596ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:53:04.066036Z","caller":"traceutil/trace.go:171","msg":"trace[1178076336] linearizableReadLoop","detail":"{readStateIndex:1206; appliedIndex:1204; }","duration":"152.373082ms","start":"2026-04-08T22:53:03.913647Z","end":"2026-04-08T22:53:04.06602Z","steps":["trace[1178076336] 'read index received' (duration: 104.814416ms)","trace[1178076336] 'applied index is now lower than readState.Index' (duration: 47.557596ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:53:04.066176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.499898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:53:04.066229Z","caller":"traceutil/trace.go:171","msg":"trace[1911449310] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1159; }","duration":"152.576372ms","start":"2026-04-08T22:53:03.913636Z","end":"2026-04-08T22:53:04.066212Z","steps":["trace[1911449310] 'agreement among raft nodes before linearized reading' (duration: 152.444745ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:04.066581Z","caller":"traceutil/trace.go:171","msg":"trace[1206442700] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"162.685772ms","start":"2026-04-08T22:53:03.903879Z","end":"2026-04-08T22:53:04.066565Z","steps":["trace[1206442700] 'process raft request' (duration: 161.859755ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:16.255809Z","caller":"traceutil/trace.go:171","msg":"trace[678812301] linearizableReadLoop","detail":"{readStateIndex:1247; appliedIndex:1246; }","duration":"137.147057ms","start":"2026-04-08T22:53:16.118646Z","end":"2026-04-08T22:53:16.255793Z","steps":["trace[678812301] 'read index received' (duration: 137.00098ms)","trace[678812301] 'applied index is now lower than readState.Index' (duration: 145.417µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:53:16.255848Z","caller":"traceutil/trace.go:171","msg":"trace[1455984288] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"138.279118ms","start":"2026-04-08T22:53:16.11754Z","end":"2026-04-08T22:53:16.255819Z","steps":["trace[1455984288] 'process raft request' (duration: 138.150312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:53:16.255979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.308964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-08T22:53:16.256011Z","caller":"traceutil/trace.go:171","msg":"trace[785830898] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1198; }","duration":"137.365827ms","start":"2026-04-08T22:53:16.118638Z","end":"2026-04-08T22:53:16.256004Z","steps":["trace[785830898] 'agreement among raft nodes before linearized reading' (duration: 137.241591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:53:16.256007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.093062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:53:16.256072Z","caller":"traceutil/trace.go:171","msg":"trace[876174159] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1198; }","duration":"123.202987ms","start":"2026-04-08T22:53:16.132855Z","end":"2026-04-08T22:53:16.256058Z","steps":["trace[876174159] 'agreement among raft nodes before linearized reading' (duration: 123.079301ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:28.030408Z","caller":"traceutil/trace.go:171","msg":"trace[833140788] linearizableReadLoop","detail":"{readStateIndex:1284; appliedIndex:1283; }","duration":"348.242878ms","start":"2026-04-08T22:53:27.682141Z","end":"2026-04-08T22:53:28.030384Z","steps":["trace[833140788] 'read index received' (duration: 348.08133ms)","trace[833140788] 'applied index is now lower than readState.Index' (duration: 160.248µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:53:28.030621Z","caller":"traceutil/trace.go:171","msg":"trace[2115247356] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"374.811319ms","start":"2026-04-08T22:53:27.655748Z","end":"2026-04-08T22:53:28.03056Z","steps":["trace[2115247356] 'process raft request' (duration: 374.492984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:53:28.030833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.649306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-08T22:53:28.030977Z","caller":"traceutil/trace.go:171","msg":"trace[1011682100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1233; }","duration":"348.839816ms","start":"2026-04-08T22:53:27.682117Z","end":"2026-04-08T22:53:28.030957Z","steps":["trace[1011682100] 'agreement among raft nodes before linearized reading' (duration: 348.50131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:53:28.030924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:53:27.655733Z","time spent":"374.967486ms","remote":"127.0.0.1:55730","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-08T22:53:28.031052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:53:27.682109Z","time spent":"348.92992ms","remote":"127.0.0.1:55730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T22:53:28.048672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.254874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5547"} {"level":"info","ts":"2026-04-08T22:53:28.048731Z","caller":"traceutil/trace.go:171","msg":"trace[2054192514] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1234; }","duration":"337.3464ms","start":"2026-04-08T22:53:27.71137Z","end":"2026-04-08T22:53:28.048716Z","steps":["trace[2054192514] 'agreement among raft nodes before linearized reading' (duration: 337.1522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:53:28.048767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:53:27.711355Z","time spent":"337.404342ms","remote":"127.0.0.1:55642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5569,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-08T22:53:28.048849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.790818ms","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-08T22:53:28.048944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.405408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:53:28.049064Z","caller":"traceutil/trace.go:171","msg":"trace[613113077] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1234; }","duration":"226.739644ms","start":"2026-04-08T22:53:27.822308Z","end":"2026-04-08T22:53:28.049048Z","steps":["trace[613113077] 'agreement among raft nodes before linearized reading' (duration: 226.389068ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:28.049177Z","caller":"traceutil/trace.go:171","msg":"trace[302284287] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"153.892001ms","start":"2026-04-08T22:53:27.89478Z","end":"2026-04-08T22:53:28.048672Z","steps":["trace[302284287] 'process raft request' (duration: 153.640419ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:28.049028Z","caller":"traceutil/trace.go:171","msg":"trace[1491606005] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1234; }","duration":"283.961386ms","start":"2026-04-08T22:53:27.765003Z","end":"2026-04-08T22:53:28.048964Z","steps":["trace[1491606005] 'agreement among raft nodes before linearized reading' (duration: 283.781788ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:28.692768Z","caller":"traceutil/trace.go:171","msg":"trace[2064805429] linearizableReadLoop","detail":"{readStateIndex:1291; appliedIndex:1289; }","duration":"271.520802ms","start":"2026-04-08T22:53:28.421222Z","end":"2026-04-08T22:53:28.692743Z","steps":["trace[2064805429] 'read index received' (duration: 34.716674ms)","trace[2064805429] 'applied index is now lower than readState.Index' (duration: 236.803128ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:53:28.692996Z","caller":"traceutil/trace.go:171","msg":"trace[375112982] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"271.973453ms","start":"2026-04-08T22:53:28.421003Z","end":"2026-04-08T22:53:28.692976Z","steps":["trace[375112982] 'process raft request' (duration: 270.550726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:53:28.693017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.660188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-08T22:53:28.693064Z","caller":"traceutil/trace.go:171","msg":"trace[1333062417] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1239; }","duration":"271.874259ms","start":"2026-04-08T22:53:28.421175Z","end":"2026-04-08T22:53:28.693049Z","steps":["trace[1333062417] 'agreement among raft nodes before linearized reading' (duration: 271.662299ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:30.922904Z","caller":"traceutil/trace.go:171","msg":"trace[1438237127] linearizableReadLoop","detail":"{readStateIndex:1297; appliedIndex:1296; }","duration":"111.720952ms","start":"2026-04-08T22:53:30.811152Z","end":"2026-04-08T22:53:30.922873Z","steps":["trace[1438237127] 'read index received' (duration: 111.514612ms)","trace[1438237127] 'applied index is now lower than readState.Index' (duration: 205.21µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:53:30.923133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.962674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-04-08T22:53:30.923193Z","caller":"traceutil/trace.go:171","msg":"trace[1502976000] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:1245; }","duration":"112.062377ms","start":"2026-04-08T22:53:30.811112Z","end":"2026-04-08T22:53:30.923175Z","steps":["trace[1502976000] 'agreement among raft nodes before linearized reading' (duration: 111.882259ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:30.923271Z","caller":"traceutil/trace.go:171","msg":"trace[168485840] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"222.864196ms","start":"2026-04-08T22:53:30.700386Z","end":"2026-04-08T22:53:30.92325Z","steps":["trace[168485840] 'process raft request' (duration: 222.329371ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:35.925338Z","caller":"traceutil/trace.go:171","msg":"trace[191829449] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"114.559864ms","start":"2026-04-08T22:53:35.810755Z","end":"2026-04-08T22:53:35.925315Z","steps":["trace[191829449] 'process raft request' (duration: 114.435187ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:52.368444Z","caller":"traceutil/trace.go:171","msg":"trace[1232545072] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"208.175915ms","start":"2026-04-08T22:53:52.160244Z","end":"2026-04-08T22:53:52.36842Z","steps":["trace[1232545072] 'process raft request' (duration: 208.014586ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:52.368423Z","caller":"traceutil/trace.go:171","msg":"trace[474372116] linearizableReadLoop","detail":"{readStateIndex:1409; appliedIndex:1408; }","duration":"139.877923ms","start":"2026-04-08T22:53:52.22852Z","end":"2026-04-08T22:53:52.368398Z","steps":["trace[474372116] 'read index received' (duration: 139.737225ms)","trace[474372116] 'applied index is now lower than readState.Index' (duration: 137.768µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:53:52.368671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.131004ms","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-08T22:53:52.368704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.319936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T22:53:52.368742Z","caller":"traceutil/trace.go:171","msg":"trace[1576114156] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1351; }","duration":"140.255772ms","start":"2026-04-08T22:53:52.228474Z","end":"2026-04-08T22:53:52.36873Z","steps":["trace[1576114156] 'agreement among raft nodes before linearized reading' (duration: 140.047871ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:52.36876Z","caller":"traceutil/trace.go:171","msg":"trace[1012408778] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1351; }","duration":"133.414461ms","start":"2026-04-08T22:53:52.235336Z","end":"2026-04-08T22:53:52.36875Z","steps":["trace[1012408778] 'agreement among raft nodes before linearized reading' (duration: 133.234622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:53:52.369368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.136341ms","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-08T22:53:52.369438Z","caller":"traceutil/trace.go:171","msg":"trace[32344843] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1351; }","duration":"126.217154ms","start":"2026-04-08T22:53:52.243196Z","end":"2026-04-08T22:53:52.369413Z","steps":["trace[32344843] 'agreement among raft nodes before linearized reading' (duration: 125.431357ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:52.951527Z","caller":"traceutil/trace.go:171","msg":"trace[1494638180] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"578.971149ms","start":"2026-04-08T22:53:52.37252Z","end":"2026-04-08T22:53:52.951491Z","steps":["trace[1494638180] 'process raft request' (duration: 519.240099ms)","trace[1494638180] 'compare' (duration: 59.596753ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:53:52.951711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:53:52.372498Z","time spent":"579.123306ms","remote":"127.0.0.1:55730","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-08T22:53:53.423104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.441419ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T22:53:53.423474Z","caller":"traceutil/trace.go:171","msg":"trace[1971695785] linearizableReadLoop","detail":"{readStateIndex:1412; appliedIndex:1409; }","duration":"768.719875ms","start":"2026-04-08T22:53:52.654583Z","end":"2026-04-08T22:53:53.423303Z","steps":["trace[1971695785] 'read index received' (duration: 237.161681ms)","trace[1971695785] 'applied index is now lower than readState.Index' (duration: 531.556594ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:53:53.423583Z","caller":"traceutil/trace.go:171","msg":"trace[1491474964] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"1.04948381s","start":"2026-04-08T22:53:52.373965Z","end":"2026-04-08T22:53:53.423448Z","steps":["trace[1491474964] 'process raft request' (duration: 1.049259929s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:53:53.423695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.114504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-08T22:53:53.423748Z","caller":"traceutil/trace.go:171","msg":"trace[907488268] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1354; }","duration":"769.21419ms","start":"2026-04-08T22:53:52.65452Z","end":"2026-04-08T22:53:53.423734Z","steps":["trace[907488268] 'agreement among raft nodes before linearized reading' (duration: 769.041811ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:53.423488Z","caller":"traceutil/trace.go:171","msg":"trace[1285968947] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"1.049472979s","start":"2026-04-08T22:53:52.373957Z","end":"2026-04-08T22:53:53.42343Z","steps":["trace[1285968947] 'process raft request' (duration: 703.597849ms)","trace[1285968947] 'compare' (duration: 345.178087ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:53:53.423801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:53:52.654498Z","time spent":"769.287093ms","remote":"127.0.0.1:55730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T22:53:53.423804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:53:52.373938Z","time spent":"1.049744952s","remote":"127.0.0.1:55730","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-08T22:53:53.424194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:53:52.373942Z","time spent":"1.049967942s","remote":"127.0.0.1:55730","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-08T22:53:53.816475Z","caller":"traceutil/trace.go:171","msg":"trace[791658214] linearizableReadLoop","detail":"{readStateIndex:1413; appliedIndex:1412; }","duration":"392.92477ms","start":"2026-04-08T22:53:53.423525Z","end":"2026-04-08T22:53:53.816449Z","steps":["trace[791658214] 'read index received' (duration: 322.500682ms)","trace[791658214] 'applied index is now lower than readState.Index' (duration: 70.422818ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:53:53.81664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.076946002s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T22:53:53.816637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.65151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:53:53.816736Z","caller":"traceutil/trace.go:171","msg":"trace[1112019392] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1355; }","duration":"682.799417ms","start":"2026-04-08T22:53:53.133914Z","end":"2026-04-08T22:53:53.816713Z","steps":["trace[1112019392] 'agreement among raft nodes before linearized reading' (duration: 682.599607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:53:53.816795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:53:53.133893Z","time spent":"682.889721ms","remote":"127.0.0.1:55464","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-08T22:53:53.816677Z","caller":"traceutil/trace.go:171","msg":"trace[1128752791] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1355; }","duration":"1.077003204s","start":"2026-04-08T22:53:52.739664Z","end":"2026-04-08T22:53:53.816667Z","steps":["trace[1128752791] 'agreement among raft nodes before linearized reading' (duration: 1.076886609s)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:53.816874Z","caller":"traceutil/trace.go:171","msg":"trace[1390589362] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"680.952057ms","start":"2026-04-08T22:53:53.135871Z","end":"2026-04-08T22:53:53.816823Z","steps":["trace[1390589362] 'process raft request' (duration: 610.14858ms)","trace[1390589362] 'compare' (duration: 70.266872ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:53:53.81701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:53:52.739646Z","time spent":"1.077300459s","remote":"127.0.0.1:55600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-04-08T22:53:53.817158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:53:53.135841Z","time spent":"681.214031ms","remote":"127.0.0.1:55730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:53:53.817064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.644209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:53:53.817373Z","caller":"traceutil/trace.go:171","msg":"trace[1145443885] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1355; }","duration":"964.917533ms","start":"2026-04-08T22:53:52.852357Z","end":"2026-04-08T22:53:53.817274Z","steps":["trace[1145443885] 'agreement among raft nodes before linearized reading' (duration: 964.353396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:53:53.817654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:53:52.852152Z","time spent":"965.273768ms","remote":"127.0.0.1:55616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-04-08T22:53:53.818517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.540728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-08T22:53:53.818589Z","caller":"traceutil/trace.go:171","msg":"trace[149357963] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1355; }","duration":"743.648033ms","start":"2026-04-08T22:53:53.074926Z","end":"2026-04-08T22:53:53.818574Z","steps":["trace[149357963] 'agreement among raft nodes before linearized reading' (duration: 741.768562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:53:53.818644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:53:53.074908Z","time spent":"743.724677ms","remote":"127.0.0.1:55730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-08T22:53:53.832973Z","caller":"traceutil/trace.go:171","msg":"trace[682235852] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"404.11715ms","start":"2026-04-08T22:53:53.428838Z","end":"2026-04-08T22:53:53.832955Z","steps":["trace[682235852] 'process raft request' (duration: 404.005984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:53:53.833097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:53:53.42882Z","time spent":"404.211694ms","remote":"127.0.0.1:55730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:53:53.992023Z","caller":"traceutil/trace.go:171","msg":"trace[651098301] linearizableReadLoop","detail":"{readStateIndex:1415; appliedIndex:1413; }","duration":"173.673329ms","start":"2026-04-08T22:53:53.818324Z","end":"2026-04-08T22:53:53.991997Z","steps":["trace[651098301] 'read index received' (duration: 14.571422ms)","trace[651098301] 'applied index is now lower than readState.Index' (duration: 159.100927ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T22:53:53.992167Z","caller":"traceutil/trace.go:171","msg":"trace[85082091] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"168.803655ms","start":"2026-04-08T22:53:53.823315Z","end":"2026-04-08T22:53:53.992119Z","steps":["trace[85082091] 'process raft request' (duration: 168.541753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:53:53.992297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.950573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:53:53.992336Z","caller":"traceutil/trace.go:171","msg":"trace[435193902] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1357; }","duration":"174.048028ms","start":"2026-04-08T22:53:53.818277Z","end":"2026-04-08T22:53:53.992325Z","steps":["trace[435193902] 'agreement among raft nodes before linearized reading' (duration: 173.946313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:53:54.492528Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995350720129022677,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-08T22:53:54.845855Z","caller":"traceutil/trace.go:171","msg":"trace[1727561360] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"881.726926ms","start":"2026-04-08T22:53:53.9641Z","end":"2026-04-08T22:53:54.845827Z","steps":["trace[1727561360] 'process raft request' (duration: 824.533973ms)","trace[1727561360] 'compare' (duration: 57.045176ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:53:54.846076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:53:53.964068Z","time spent":"881.855501ms","remote":"127.0.0.1:55642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5897,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T22:53:54.877812Z","caller":"traceutil/trace.go:171","msg":"trace[1705713016] linearizableReadLoop","detail":"{readStateIndex:1418; appliedIndex:1416; }","duration":"885.597194ms","start":"2026-04-08T22:53:53.992194Z","end":"2026-04-08T22:53:54.877791Z","steps":["trace[1705713016] 'read index received' (duration: 796.560263ms)","trace[1705713016] 'applied index is now lower than readState.Index' (duration: 89.03616ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T22:53:54.877922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054333556s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:53:54.877945Z","caller":"traceutil/trace.go:171","msg":"trace[80663732] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1359; }","duration":"1.05440899s","start":"2026-04-08T22:53:53.823529Z","end":"2026-04-08T22:53:54.877938Z","steps":["trace[80663732] 'agreement among raft nodes before linearized reading' (duration: 1.054355447s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:53:54.877978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:53:53.823512Z","time spent":"1.054454791s","remote":"127.0.0.1:55616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-04-08T22:53:54.877971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.189917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:53:54.878024Z","caller":"traceutil/trace.go:171","msg":"trace[1125778786] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1359; }","duration":"743.29492ms","start":"2026-04-08T22:53:54.134715Z","end":"2026-04-08T22:53:54.87801Z","steps":["trace[1125778786] 'agreement among raft nodes before linearized reading' (duration: 743.201885ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:53:54.877909Z","caller":"traceutil/trace.go:171","msg":"trace[1871121637] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"501.591156ms","start":"2026-04-08T22:53:54.376283Z","end":"2026-04-08T22:53:54.877874Z","steps":["trace[1871121637] 'process raft request' (duration: 501.398646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:53:54.87807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:53:54.134692Z","time spent":"743.370945ms","remote":"127.0.0.1:55464","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-08T22:53:54.878131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.174118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T22:53:54.878223Z","caller":"traceutil/trace.go:171","msg":"trace[1513246436] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1359; }","duration":"536.339925ms","start":"2026-04-08T22:53:54.341864Z","end":"2026-04-08T22:53:54.878204Z","steps":["trace[1513246436] 'agreement among raft nodes before linearized reading' (duration: 536.109944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T22:53:54.878191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:53:54.376252Z","time spent":"501.821508ms","remote":"127.0.0.1:55730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T22:53:54.878267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T22:53:54.341842Z","time spent":"536.412669ms","remote":"127.0.0.1:37806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-04-08T22:53:57.089151Z","caller":"traceutil/trace.go:171","msg":"trace[1984899673] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"203.482121ms","start":"2026-04-08T22:53:56.885643Z","end":"2026-04-08T22:53:57.089125Z","steps":["trace[1984899673] 'process raft request' (duration: 203.05501ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T22:54:03.299284Z","caller":"traceutil/trace.go:171","msg":"trace[1380354042] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"140.615098ms","start":"2026-04-08T22:54:03.158642Z","end":"2026-04-08T22:54:03.299257Z","steps":["trace[1380354042] 'process raft request' (duration: 40.947805ms)","trace[1380354042] 'compare' (duration: 99.352278ms)"],"step_count":2}