{"level":"warn","ts":"2026-04-19T13:24:24.782681Z","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-19T13:24:24.78278Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.226:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.226:2380","--initial-cluster=instance=https://199.19.213.226:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.226:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.226: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-19T13:24:24.782869Z","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-19T13:24:24.782886Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.226:2380"]} {"level":"info","ts":"2026-04-19T13:24:24.782926Z","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-19T13:24:24.783504Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.226:2379"]} {"level":"info","ts":"2026-04-19T13:24:24.783763Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.226:2380"],"listen-peer-urls":["https://199.19.213.226:2380"],"advertise-client-urls":["https://199.19.213.226:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.226:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.226: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-19T13:24:24.798545Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"14.592418ms"} {"level":"info","ts":"2026-04-19T13:24:24.826669Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c1a97aa766b87c7e","cluster-id":"34571fc6a0261e75"} {"level":"info","ts":"2026-04-19T13:24:24.826838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c1a97aa766b87c7e switched to configuration voters=()"} {"level":"info","ts":"2026-04-19T13:24:24.826887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c1a97aa766b87c7e became follower at term 0"} {"level":"info","ts":"2026-04-19T13:24:24.826908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c1a97aa766b87c7e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-19T13:24:24.826926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c1a97aa766b87c7e became follower at term 1"} {"level":"info","ts":"2026-04-19T13:24:24.827023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c1a97aa766b87c7e switched to configuration voters=(13954819779785686142)"} {"level":"warn","ts":"2026-04-19T13:24:24.880124Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-19T13:24:24.88332Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-19T13:24:24.885722Z","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-19T13:24:24.889511Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c1a97aa766b87c7e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-19T13:24:24.889759Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c1a97aa766b87c7e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-19T13:24:24.893853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c1a97aa766b87c7e switched to configuration voters=(13954819779785686142)"} {"level":"info","ts":"2026-04-19T13:24:24.894258Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"34571fc6a0261e75","local-member-id":"c1a97aa766b87c7e","added-peer-id":"c1a97aa766b87c7e","added-peer-peer-urls":["https://199.19.213.226:2380"]} {"level":"info","ts":"2026-04-19T13:24:24.889985Z","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-19T13:24:24.894569Z","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-19T13:24:24.894584Z","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-19T13:24:24.898175Z","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-19T13:24:24.898345Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.226:2380"} {"level":"info","ts":"2026-04-19T13:24:24.898371Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.226:2380"} {"level":"info","ts":"2026-04-19T13:24:24.898571Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c1a97aa766b87c7e","initial-advertise-peer-urls":["https://199.19.213.226:2380"],"listen-peer-urls":["https://199.19.213.226:2380"],"advertise-client-urls":["https://199.19.213.226:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.226:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-19T13:24:24.89863Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-19T13:24:25.827984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c1a97aa766b87c7e is starting a new election at term 1"} {"level":"info","ts":"2026-04-19T13:24:25.828036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c1a97aa766b87c7e became pre-candidate at term 1"} {"level":"info","ts":"2026-04-19T13:24:25.828074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c1a97aa766b87c7e received MsgPreVoteResp from c1a97aa766b87c7e at term 1"} {"level":"info","ts":"2026-04-19T13:24:25.828092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c1a97aa766b87c7e became candidate at term 2"} {"level":"info","ts":"2026-04-19T13:24:25.828103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c1a97aa766b87c7e received MsgVoteResp from c1a97aa766b87c7e at term 2"} {"level":"info","ts":"2026-04-19T13:24:25.828118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c1a97aa766b87c7e became leader at term 2"} {"level":"info","ts":"2026-04-19T13:24:25.828131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c1a97aa766b87c7e elected leader c1a97aa766b87c7e at term 2"} {"level":"info","ts":"2026-04-19T13:24:25.84213Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T13:24:25.91037Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-19T13:24:25.910385Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-19T13:24:25.91076Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-19T13:24:25.910809Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-19T13:24:25.910353Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c1a97aa766b87c7e","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.226:2379]}","request-path":"/0/members/c1a97aa766b87c7e/attributes","cluster-id":"34571fc6a0261e75","publish-timeout":"7s"} {"level":"info","ts":"2026-04-19T13:24:25.915281Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.226:2379"} {"level":"info","ts":"2026-04-19T13:24:25.915723Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-19T13:24:25.997728Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"34571fc6a0261e75","local-member-id":"c1a97aa766b87c7e","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T13:24:25.99788Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T13:24:25.997946Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T13:24:27.383825Z","caller":"traceutil/trace.go:171","msg":"trace[2054356297] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"140.492631ms","start":"2026-04-19T13:24:27.243316Z","end":"2026-04-19T13:24:27.383809Z","steps":["trace[2054356297] 'process raft request' (duration: 140.345497ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:24:27.383795Z","caller":"traceutil/trace.go:171","msg":"trace[1969703288] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"140.541443ms","start":"2026-04-19T13:24:27.243224Z","end":"2026-04-19T13:24:27.383765Z","steps":["trace[1969703288] 'process raft request' (duration: 140.315576ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:24:27.383873Z","caller":"traceutil/trace.go:171","msg":"trace[429250782] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"140.365718ms","start":"2026-04-19T13:24:27.243483Z","end":"2026-04-19T13:24:27.383849Z","steps":["trace[429250782] 'process raft request' (duration: 140.257684ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:24:27.383935Z","caller":"traceutil/trace.go:171","msg":"trace[374093012] linearizableReadLoop","detail":"{readStateIndex:8; appliedIndex:6; }","duration":"140.647487ms","start":"2026-04-19T13:24:27.243268Z","end":"2026-04-19T13:24:27.383915Z","steps":["trace[374093012] 'read index received' (duration: 34.315496ms)","trace[374093012] 'applied index is now lower than readState.Index' (duration: 106.331021ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:24:27.383966Z","caller":"traceutil/trace.go:171","msg":"trace[1584157717] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"140.387308ms","start":"2026-04-19T13:24:27.243546Z","end":"2026-04-19T13:24:27.383933Z","steps":["trace[1584157717] 'process raft request' (duration: 140.275815ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:24:27.38403Z","caller":"traceutil/trace.go:171","msg":"trace[631062815] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"140.668817ms","start":"2026-04-19T13:24:27.243348Z","end":"2026-04-19T13:24:27.384016Z","steps":["trace[631062815] 'process raft request' (duration: 140.434579ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:24:27.384043Z","caller":"traceutil/trace.go:171","msg":"trace[1818788304] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"126.636412ms","start":"2026-04-19T13:24:27.257386Z","end":"2026-04-19T13:24:27.384022Z","steps":["trace[1818788304] 'process raft request' (duration: 126.482167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:24:27.384198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.908415ms","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-19T13:24:27.384281Z","caller":"traceutil/trace.go:171","msg":"trace[2041154200] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"140.995987ms","start":"2026-04-19T13:24:27.243255Z","end":"2026-04-19T13:24:27.384251Z","steps":["trace[2041154200] 'agreement among raft nodes before linearized reading' (duration: 140.802081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:24:27.440468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.082878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-19T13:24:27.440447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.067449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-19T13:24:27.440504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.928873ms","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-19T13:24:27.440483Z","caller":"traceutil/trace.go:171","msg":"trace[517038421] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"139.106189ms","start":"2026-04-19T13:24:27.301322Z","end":"2026-04-19T13:24:27.440429Z","steps":["trace[517038421] 'process raft request' (duration: 107.811028ms)","trace[517038421] 'compare' (duration: 31.085095ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:24:27.440533Z","caller":"traceutil/trace.go:171","msg":"trace[1617036500] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:11; }","duration":"130.160171ms","start":"2026-04-19T13:24:27.310356Z","end":"2026-04-19T13:24:27.440516Z","steps":["trace[1617036500] 'agreement among raft nodes before linearized reading' (duration: 130.097909ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:24:27.440551Z","caller":"traceutil/trace.go:171","msg":"trace[1552979700] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"147.997084ms","start":"2026-04-19T13:24:27.292536Z","end":"2026-04-19T13:24:27.440533Z","steps":["trace[1552979700] 'agreement among raft nodes before linearized reading' (duration: 147.916582ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:24:27.440544Z","caller":"traceutil/trace.go:171","msg":"trace[1867852670] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11; }","duration":"141.197113ms","start":"2026-04-19T13:24:27.299328Z","end":"2026-04-19T13:24:27.440525Z","steps":["trace[1867852670] 'agreement among raft nodes before linearized reading' (duration: 141.054489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:24:27.440446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.268621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-19T13:24:27.440636Z","caller":"traceutil/trace.go:171","msg":"trace[1237435394] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"165.478137ms","start":"2026-04-19T13:24:27.275149Z","end":"2026-04-19T13:24:27.440627Z","steps":["trace[1237435394] 'agreement among raft nodes before linearized reading' (duration: 165.183468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:24:27.440449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.210467ms","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-19T13:24:27.440917Z","caller":"traceutil/trace.go:171","msg":"trace[494269496] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"135.482486ms","start":"2026-04-19T13:24:27.305198Z","end":"2026-04-19T13:24:27.440681Z","steps":["trace[494269496] 'agreement among raft nodes before linearized reading' (duration: 135.160826ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:24:27.696106Z","caller":"traceutil/trace.go:171","msg":"trace[506897405] linearizableReadLoop","detail":"{readStateIndex:40; appliedIndex:37; }","duration":"197.228103ms","start":"2026-04-19T13:24:27.498845Z","end":"2026-04-19T13:24:27.696073Z","steps":["trace[506897405] 'read index received' (duration: 112.066799ms)","trace[506897405] 'applied index is now lower than readState.Index' (duration: 85.159684ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:24:27.696295Z","caller":"traceutil/trace.go:171","msg":"trace[1870482868] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"197.956975ms","start":"2026-04-19T13:24:27.498291Z","end":"2026-04-19T13:24:27.696248Z","steps":["trace[1870482868] 'process raft request' (duration: 197.733238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:24:27.696439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.522201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-19T13:24:27.696494Z","caller":"traceutil/trace.go:171","msg":"trace[32025731] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:36; }","duration":"197.668166ms","start":"2026-04-19T13:24:27.498812Z","end":"2026-04-19T13:24:27.696481Z","steps":["trace[32025731] 'agreement among raft nodes before linearized reading' (duration: 197.45797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:24:27.696498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.18224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-19T13:24:27.696281Z","caller":"traceutil/trace.go:171","msg":"trace[1506853144] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"217.694858ms","start":"2026-04-19T13:24:27.478561Z","end":"2026-04-19T13:24:27.696256Z","steps":["trace[1506853144] 'process raft request' (duration: 217.388649ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:24:27.696584Z","caller":"traceutil/trace.go:171","msg":"trace[154911140] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:36; }","duration":"196.329135ms","start":"2026-04-19T13:24:27.500232Z","end":"2026-04-19T13:24:27.696561Z","steps":["trace[154911140] 'agreement among raft nodes before linearized reading' (duration: 196.16783ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:24:27.696958Z","caller":"traceutil/trace.go:171","msg":"trace[1219787632] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"218.467693ms","start":"2026-04-19T13:24:27.478445Z","end":"2026-04-19T13:24:27.696913Z","steps":["trace[1219787632] 'process raft request' (duration: 132.455893ms)","trace[1219787632] 'compare' (duration: 84.832143ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:24:27.697034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.173985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-19T13:24:27.697099Z","caller":"traceutil/trace.go:171","msg":"trace[1400287332] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:36; }","duration":"191.291559ms","start":"2026-04-19T13:24:27.505789Z","end":"2026-04-19T13:24:27.69708Z","steps":["trace[1400287332] 'agreement among raft nodes before linearized reading' (duration: 191.206636ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:24:30.691812Z","caller":"traceutil/trace.go:171","msg":"trace[608261637] linearizableReadLoop","detail":"{readStateIndex:269; appliedIndex:268; }","duration":"128.531429ms","start":"2026-04-19T13:24:30.563267Z","end":"2026-04-19T13:24:30.691798Z","steps":["trace[608261637] 'read index received' (duration: 128.336843ms)","trace[608261637] 'applied index is now lower than readState.Index' (duration: 193.886µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:24:30.691847Z","caller":"traceutil/trace.go:171","msg":"trace[1704221929] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"160.420799ms","start":"2026-04-19T13:24:30.53141Z","end":"2026-04-19T13:24:30.69183Z","steps":["trace[1704221929] 'process raft request' (duration: 160.152371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:24:30.691931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.650492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-19T13:24:30.691953Z","caller":"traceutil/trace.go:171","msg":"trace[1257560126] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:263; }","duration":"128.711514ms","start":"2026-04-19T13:24:30.563234Z","end":"2026-04-19T13:24:30.691946Z","steps":["trace[1257560126] 'agreement among raft nodes before linearized reading' (duration: 128.644702ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:24:30.751796Z","caller":"traceutil/trace.go:171","msg":"trace[1800732012] transaction","detail":"{read_only:false; response_revision:264; number_of_response:1; }","duration":"187.810923ms","start":"2026-04-19T13:24:30.563946Z","end":"2026-04-19T13:24:30.751757Z","steps":["trace[1800732012] 'process raft request' (duration: 187.560056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:24:30.752161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.603927ms","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-19T13:24:30.752236Z","caller":"traceutil/trace.go:171","msg":"trace[450969035] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:266; }","duration":"188.689679ms","start":"2026-04-19T13:24:30.563523Z","end":"2026-04-19T13:24:30.752213Z","steps":["trace[450969035] 'agreement among raft nodes before linearized reading' (duration: 188.578286ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:24:30.866442Z","caller":"traceutil/trace.go:171","msg":"trace[2128269836] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"109.742294ms","start":"2026-04-19T13:24:30.756676Z","end":"2026-04-19T13:24:30.866419Z","steps":["trace[2128269836] 'process raft request' (duration: 109.708623ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:24:30.866504Z","caller":"traceutil/trace.go:171","msg":"trace[1179086406] linearizableReadLoop","detail":"{readStateIndex:277; appliedIndex:276; }","duration":"114.556658ms","start":"2026-04-19T13:24:30.751929Z","end":"2026-04-19T13:24:30.866486Z","steps":["trace[1179086406] 'read index received' (duration: 91.325409ms)","trace[1179086406] 'applied index is now lower than readState.Index' (duration: 23.229489ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:24:30.866608Z","caller":"traceutil/trace.go:171","msg":"trace[1589259772] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"166.843322ms","start":"2026-04-19T13:24:30.699751Z","end":"2026-04-19T13:24:30.866594Z","steps":["trace[1589259772] 'process raft request' (duration: 143.51318ms)","trace[1589259772] 'compare' (duration: 22.952471ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:24:30.866729Z","caller":"traceutil/trace.go:171","msg":"trace[1203058213] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"111.594619ms","start":"2026-04-19T13:24:30.755124Z","end":"2026-04-19T13:24:30.866719Z","steps":["trace[1203058213] 'process raft request' (duration: 111.220408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:24:30.866837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.989952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-04-19T13:24:30.866904Z","caller":"traceutil/trace.go:171","msg":"trace[1768542929] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:269; }","duration":"114.092804ms","start":"2026-04-19T13:24:30.752791Z","end":"2026-04-19T13:24:30.866884Z","steps":["trace[1768542929] 'agreement among raft nodes before linearized reading' (duration: 113.939989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:24:30.867011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.115945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-04-19T13:24:30.867061Z","caller":"traceutil/trace.go:171","msg":"trace[460329924] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:269; }","duration":"113.179447ms","start":"2026-04-19T13:24:30.753867Z","end":"2026-04-19T13:24:30.867046Z","steps":["trace[460329924] 'agreement among raft nodes before linearized reading' (duration: 113.088804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:24:30.867133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.342032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-04-19T13:24:30.867169Z","caller":"traceutil/trace.go:171","msg":"trace[1338839018] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:269; }","duration":"113.399483ms","start":"2026-04-19T13:24:30.753759Z","end":"2026-04-19T13:24:30.867158Z","steps":["trace[1338839018] 'agreement among raft nodes before linearized reading' (duration: 113.335351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:24:30.86728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.09929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"warn","ts":"2026-04-19T13:24:30.867284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.416933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5897"} {"level":"info","ts":"2026-04-19T13:24:30.86733Z","caller":"traceutil/trace.go:171","msg":"trace[1328037584] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:269; }","duration":"172.170633ms","start":"2026-04-19T13:24:30.695146Z","end":"2026-04-19T13:24:30.867316Z","steps":["trace[1328037584] 'agreement among raft nodes before linearized reading' (duration: 172.08948ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:24:30.867337Z","caller":"traceutil/trace.go:171","msg":"trace[1574419761] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:269; }","duration":"113.487946ms","start":"2026-04-19T13:24:30.753806Z","end":"2026-04-19T13:24:30.867323Z","steps":["trace[1574419761] 'agreement among raft nodes before linearized reading' (duration: 113.400693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:24:30.867387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.27047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-04-19T13:24:30.867417Z","caller":"traceutil/trace.go:171","msg":"trace[1051446833] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:269; }","duration":"111.322691ms","start":"2026-04-19T13:24:30.756086Z","end":"2026-04-19T13:24:30.867408Z","steps":["trace[1051446833] 'agreement among raft nodes before linearized reading' (duration: 111.270359ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:24:33.188526Z","caller":"traceutil/trace.go:171","msg":"trace[773019282] linearizableReadLoop","detail":"{readStateIndex:330; appliedIndex:329; }","duration":"113.02144ms","start":"2026-04-19T13:24:33.07544Z","end":"2026-04-19T13:24:33.188461Z","steps":["trace[773019282] 'read index received' (duration: 40.211194ms)","trace[773019282] 'applied index is now lower than readState.Index' (duration: 72.808506ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:24:33.18919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.71924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:24:33.189293Z","caller":"traceutil/trace.go:171","msg":"trace[2069428325] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cronjob-controller; range_end:; response_count:0; response_revision:319; }","duration":"113.853854ms","start":"2026-04-19T13:24:33.075408Z","end":"2026-04-19T13:24:33.189262Z","steps":["trace[2069428325] 'agreement among raft nodes before linearized reading' (duration: 113.337059ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:24:33.190233Z","caller":"traceutil/trace.go:171","msg":"trace[340888571] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"121.180638ms","start":"2026-04-19T13:24:33.069023Z","end":"2026-04-19T13:24:33.190204Z","steps":["trace[340888571] 'process raft request' (duration: 46.747835ms)","trace[340888571] 'compare' (duration: 72.246299ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:24:33.464245Z","caller":"traceutil/trace.go:171","msg":"trace[825519704] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"266.343385ms","start":"2026-04-19T13:24:33.197862Z","end":"2026-04-19T13:24:33.464205Z","steps":["trace[825519704] 'process raft request' (duration: 188.167153ms)","trace[825519704] 'compare' (duration: 77.912865ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:24:34.212944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.195722ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:24:34.213077Z","caller":"traceutil/trace.go:171","msg":"trace[100273487] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"194.640526ms","start":"2026-04-19T13:24:34.018417Z","end":"2026-04-19T13:24:34.213058Z","steps":["trace[100273487] 'process raft request' (duration: 89.858268ms)","trace[100273487] 'compare' (duration: 104.046727ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:24:36.858143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.760437ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:24:36.859021Z","caller":"traceutil/trace.go:171","msg":"trace[1204124218] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"613.601656ms","start":"2026-04-19T13:24:36.245391Z","end":"2026-04-19T13:24:36.858992Z","steps":["trace[1204124218] 'process raft request' (duration: 474.775572ms)","trace[1204124218] 'compare' (duration: 132.558127ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:24:36.859196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:24:36.245382Z","time spent":"613.718519ms","remote":"127.0.0.1:58608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3424,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:24:36.859742Z","caller":"traceutil/trace.go:171","msg":"trace[1443936988] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"613.924544ms","start":"2026-04-19T13:24:36.245547Z","end":"2026-04-19T13:24:36.859472Z","steps":["trace[1443936988] 'process raft request' (duration: 612.900345ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:24:36.859746Z","caller":"traceutil/trace.go:171","msg":"trace[914676611] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:378; }","duration":"591.818219ms","start":"2026-04-19T13:24:36.267767Z","end":"2026-04-19T13:24:36.859585Z","steps":["trace[914676611] 'read index received' (duration: 452.517242ms)","trace[914676611] 'applied index is now lower than readState.Index' (duration: 138.907536ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:24:36.860007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:24:36.245522Z","time spent":"614.304206ms","remote":"127.0.0.1:58876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2886,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T13:24:36.861671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.898197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-19T13:24:36.861774Z","caller":"traceutil/trace.go:171","msg":"trace[1223159559] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:369; }","duration":"594.009711ms","start":"2026-04-19T13:24:36.267746Z","end":"2026-04-19T13:24:36.861755Z","steps":["trace[1223159559] 'agreement among raft nodes before linearized reading' (duration: 591.462809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:24:36.861831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:24:36.267653Z","time spent":"594.169815ms","remote":"127.0.0.1:58622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-04-19T13:24:47.337032Z","caller":"traceutil/trace.go:171","msg":"trace[243765804] linearizableReadLoop","detail":"{readStateIndex:433; appliedIndex:432; }","duration":"260.695398ms","start":"2026-04-19T13:24:47.076305Z","end":"2026-04-19T13:24:47.337Z","steps":["trace[243765804] 'read index received' (duration: 260.518004ms)","trace[243765804] 'applied index is now lower than readState.Index' (duration: 176.094µs)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:24:47.337239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.527831ms","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-19T13:24:47.337317Z","caller":"traceutil/trace.go:171","msg":"trace[1628171443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:419; }","duration":"196.647984ms","start":"2026-04-19T13:24:47.140656Z","end":"2026-04-19T13:24:47.337304Z","steps":["trace[1628171443] 'agreement among raft nodes before linearized reading' (duration: 196.538671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:24:47.337238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.916794ms","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-19T13:24:47.337422Z","caller":"traceutil/trace.go:171","msg":"trace[1942658586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:419; }","duration":"261.15196ms","start":"2026-04-19T13:24:47.07625Z","end":"2026-04-19T13:24:47.337402Z","steps":["trace[1942658586] 'agreement among raft nodes before linearized reading' (duration: 260.919874ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:24:47.337522Z","caller":"traceutil/trace.go:171","msg":"trace[301023237] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"288.716023ms","start":"2026-04-19T13:24:47.048425Z","end":"2026-04-19T13:24:47.337141Z","steps":["trace[301023237] 'process raft request' (duration: 288.400565ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:24:51.750534Z","caller":"traceutil/trace.go:171","msg":"trace[675606555] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"332.994868ms","start":"2026-04-19T13:24:51.417507Z","end":"2026-04-19T13:24:51.750502Z","steps":["trace[675606555] 'process raft request' (duration: 332.777352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:24:51.750718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:24:51.41749Z","time spent":"333.146981ms","remote":"127.0.0.1:58658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T13:24:51.92114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.768616ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:24:51.921395Z","caller":"traceutil/trace.go:171","msg":"trace[1803944951] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"503.571822ms","start":"2026-04-19T13:24:51.417786Z","end":"2026-04-19T13:24:51.921358Z","steps":["trace[1803944951] 'process raft request' (duration: 387.502848ms)","trace[1803944951] 'compare' (duration: 115.572822ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:24:51.921477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.541475ms","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-19T13:24:51.921518Z","caller":"traceutil/trace.go:171","msg":"trace[952290940] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:462; }","duration":"227.628367ms","start":"2026-04-19T13:24:51.693876Z","end":"2026-04-19T13:24:51.921504Z","steps":["trace[952290940] 'agreement among raft nodes before linearized reading' (duration: 227.549585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:24:51.921515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:24:51.417772Z","time spent":"503.688054ms","remote":"127.0.0.1:58658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:24:51.921307Z","caller":"traceutil/trace.go:171","msg":"trace[837355991] linearizableReadLoop","detail":"{readStateIndex:477; appliedIndex:475; }","duration":"227.35295ms","start":"2026-04-19T13:24:51.693934Z","end":"2026-04-19T13:24:51.921286Z","steps":["trace[837355991] 'read index received' (duration: 56.350505ms)","trace[837355991] 'applied index is now lower than readState.Index' (duration: 171.001225ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:25:00.961048Z","caller":"traceutil/trace.go:171","msg":"trace[2085555317] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"653.962004ms","start":"2026-04-19T13:25:00.307054Z","end":"2026-04-19T13:25:00.961016Z","steps":["trace[2085555317] 'process raft request' (duration: 653.800761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:00.96122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:00.307028Z","time spent":"654.116107ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-19T13:25:00.974589Z","caller":"traceutil/trace.go:171","msg":"trace[1436659162] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"654.137688ms","start":"2026-04-19T13:25:00.320422Z","end":"2026-04-19T13:25:00.97456Z","steps":["trace[1436659162] 'process raft request' (duration: 653.938464ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:00.974643Z","caller":"traceutil/trace.go:171","msg":"trace[1020643824] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"650.484915ms","start":"2026-04-19T13:25:00.324141Z","end":"2026-04-19T13:25:00.974625Z","steps":["trace[1020643824] 'process raft request' (duration: 650.29301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:00.974729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:00.320406Z","time spent":"654.268841ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-19T13:25:00.974778Z","caller":"traceutil/trace.go:171","msg":"trace[1645259144] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"650.846393ms","start":"2026-04-19T13:25:00.323923Z","end":"2026-04-19T13:25:00.974769Z","steps":["trace[1645259144] 'process raft request' (duration: 650.490185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:00.974826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:00.323901Z","time spent":"650.906595ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-19T13:25:00.974892Z","caller":"traceutil/trace.go:171","msg":"trace[1544646311] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"649.678717ms","start":"2026-04-19T13:25:00.325207Z","end":"2026-04-19T13:25:00.974886Z","steps":["trace[1544646311] 'process raft request' (duration: 649.341819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:00.974742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:00.324094Z","time spent":"650.585107ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-19T13:25:00.974898Z","caller":"traceutil/trace.go:171","msg":"trace[1639846677] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"660.551493ms","start":"2026-04-19T13:25:00.314329Z","end":"2026-04-19T13:25:00.97488Z","steps":["trace[1639846677] 'process raft request' (duration: 659.903169ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:00.974943Z","caller":"traceutil/trace.go:171","msg":"trace[903556791] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"650.212499ms","start":"2026-04-19T13:25:00.324725Z","end":"2026-04-19T13:25:00.974938Z","steps":["trace[903556791] 'process raft request' (duration: 649.750738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:00.97498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:00.324709Z","time spent":"650.255489ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-19T13:25:00.974976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:00.314303Z","time spent":"660.638156ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-19T13:25:00.97492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:00.325194Z","time spent":"649.713857ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-19T13:25:00.975103Z","caller":"traceutil/trace.go:171","msg":"trace[808551555] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"645.918231ms","start":"2026-04-19T13:25:00.329178Z","end":"2026-04-19T13:25:00.975096Z","steps":["trace[808551555] 'process raft request' (duration: 645.452431ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:00.975113Z","caller":"traceutil/trace.go:171","msg":"trace[1341712549] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"638.594434ms","start":"2026-04-19T13:25:00.336508Z","end":"2026-04-19T13:25:00.975102Z","steps":["trace[1341712549] 'process raft request' (duration: 638.232946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:00.975137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:00.329168Z","time spent":"645.955072ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-19T13:25:00.975156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:00.336482Z","time spent":"638.653456ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-19T13:25:00.975227Z","caller":"traceutil/trace.go:171","msg":"trace[1452809297] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"627.451391ms","start":"2026-04-19T13:25:00.347768Z","end":"2026-04-19T13:25:00.975219Z","steps":["trace[1452809297] 'process raft request' (duration: 627.184775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:00.975271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:00.347738Z","time spent":"627.515733ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-19T13:25:00.975376Z","caller":"traceutil/trace.go:171","msg":"trace[34278402] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"614.710442ms","start":"2026-04-19T13:25:00.360658Z","end":"2026-04-19T13:25:00.975369Z","steps":["trace[34278402] 'process raft request' (duration: 614.433095ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:00.975416Z","caller":"traceutil/trace.go:171","msg":"trace[1369837378] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"491.546172ms","start":"2026-04-19T13:25:00.483852Z","end":"2026-04-19T13:25:00.975398Z","steps":["trace[1369837378] 'process raft request' (duration: 491.380728ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:00.975419Z","caller":"traceutil/trace.go:171","msg":"trace[1714399473] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:531; }","duration":"481.798921ms","start":"2026-04-19T13:25:00.493571Z","end":"2026-04-19T13:25:00.97537Z","steps":["trace[1714399473] 'read index received' (duration: 467.403943ms)","trace[1714399473] 'applied index is now lower than readState.Index' (duration: 14.394218ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:25:00.975503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:00.483821Z","time spent":"491.643735ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-19T13:25:00.975541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.963444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:25:00.975584Z","caller":"traceutil/trace.go:171","msg":"trace[491274442] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:526; }","duration":"482.033886ms","start":"2026-04-19T13:25:00.493539Z","end":"2026-04-19T13:25:00.975573Z","steps":["trace[491274442] 'agreement among raft nodes before linearized reading' (duration: 481.941444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:00.975431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:00.360633Z","time spent":"614.767373ms","remote":"127.0.0.1:58444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-19T13:25:00.975618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:00.493528Z","time spent":"482.084127ms","remote":"127.0.0.1:58396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-19T13:25:07.583525Z","caller":"traceutil/trace.go:171","msg":"trace[1106629777] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"424.528327ms","start":"2026-04-19T13:25:07.158965Z","end":"2026-04-19T13:25:07.583494Z","steps":["trace[1106629777] 'process raft request' (duration: 423.762131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:07.583727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:07.158927Z","time spent":"424.730322ms","remote":"127.0.0.1:35922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-19T13:25:08.096334Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8970780843688493130,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T13:25:09.367257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.775271ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-19T13:25:09.367667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"968.37289ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:25:09.367863Z","caller":"traceutil/trace.go:171","msg":"trace[1446824736] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"996.55948ms","start":"2026-04-19T13:25:08.371281Z","end":"2026-04-19T13:25:09.367841Z","steps":["trace[1446824736] 'process raft request' (duration: 996.504259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:09.367936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:08.371255Z","time spent":"996.647903ms","remote":"127.0.0.1:58658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:25:09.367417Z","caller":"traceutil/trace.go:171","msg":"trace[594515792] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:621; }","duration":"596.944975ms","start":"2026-04-19T13:25:08.770447Z","end":"2026-04-19T13:25:09.367392Z","steps":["trace[594515792] 'range keys from in-memory index tree' (duration: 596.749731ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:09.368134Z","caller":"traceutil/trace.go:171","msg":"trace[666178842] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"1.780852186s","start":"2026-04-19T13:25:07.58727Z","end":"2026-04-19T13:25:09.368122Z","steps":["trace[666178842] 'process raft request' (duration: 811.943825ms)","trace[666178842] 'compare' (duration: 968.193996ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:25:09.368193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:07.587251Z","time spent":"1.780913867s","remote":"127.0.0.1:58658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:25:09.368315Z","caller":"traceutil/trace.go:171","msg":"trace[1196462862] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"1.772873025s","start":"2026-04-19T13:25:07.595432Z","end":"2026-04-19T13:25:09.368305Z","steps":["trace[1196462862] 'read index received' (duration: 803.820671ms)","trace[1196462862] 'applied index is now lower than readState.Index' (duration: 969.051184ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:25:09.368442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.773003988s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:25:09.368472Z","caller":"traceutil/trace.go:171","msg":"trace[1423216550] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:623; }","duration":"1.773056478s","start":"2026-04-19T13:25:07.595405Z","end":"2026-04-19T13:25:09.368461Z","steps":["trace[1423216550] 'agreement among raft nodes before linearized reading' (duration: 1.772985327s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:09.368508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:07.595396Z","time spent":"1.77310523s","remote":"127.0.0.1:58646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-04-19T13:25:09.368734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.271973092s","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-19T13:25:09.368735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.66826693s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-t6ljb\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-04-19T13:25:09.36881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.526453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-19T13:25:09.368809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.633072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.226\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-19T13:25:09.368784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.450428817s","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-19T13:25:09.368844Z","caller":"traceutil/trace.go:171","msg":"trace[218096238] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"874.600664ms","start":"2026-04-19T13:25:08.494235Z","end":"2026-04-19T13:25:09.368836Z","steps":["trace[218096238] 'agreement among raft nodes before linearized reading' (duration: 874.556813ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:09.368828Z","caller":"traceutil/trace.go:171","msg":"trace[261831535] range","detail":"{range_begin:/registry/pods/kube-system/cilium-t6ljb; range_end:; response_count:1; response_revision:623; }","duration":"1.668411263s","start":"2026-04-19T13:25:07.70039Z","end":"2026-04-19T13:25:09.368801Z","steps":["trace[261831535] 'agreement among raft nodes before linearized reading' (duration: 1.668137558s)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:09.368872Z","caller":"traceutil/trace.go:171","msg":"trace[407042249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:623; }","duration":"1.45056835s","start":"2026-04-19T13:25:07.918287Z","end":"2026-04-19T13:25:09.368855Z","steps":["trace[407042249] 'agreement among raft nodes before linearized reading' (duration: 1.450354055s)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:09.368868Z","caller":"traceutil/trace.go:171","msg":"trace[2076603147] range","detail":"{range_begin:/registry/masterleases/199.19.213.226; range_end:; response_count:1; response_revision:623; }","duration":"522.745595ms","start":"2026-04-19T13:25:08.846103Z","end":"2026-04-19T13:25:09.368848Z","steps":["trace[2076603147] 'agreement among raft nodes before linearized reading' (duration: 522.614433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:09.368874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:08.494211Z","time spent":"874.657536ms","remote":"127.0.0.1:58396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-19T13:25:09.368915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:08.846069Z","time spent":"522.838377ms","remote":"127.0.0.1:58424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.226\" "} {"level":"warn","ts":"2026-04-19T13:25:09.368915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:07.918263Z","time spent":"1.450644701s","remote":"127.0.0.1:58658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-19T13:25:09.368942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:07.700366Z","time spent":"1.668539576s","remote":"127.0.0.1:58608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-t6ljb\" "} {"level":"info","ts":"2026-04-19T13:25:09.368772Z","caller":"traceutil/trace.go:171","msg":"trace[1050780756] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:623; }","duration":"1.272031333s","start":"2026-04-19T13:25:08.096731Z","end":"2026-04-19T13:25:09.368762Z","steps":["trace[1050780756] 'agreement among raft nodes before linearized reading' (duration: 1.27190002s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:09.369194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:08.096718Z","time spent":"1.272463542s","remote":"127.0.0.1:58658","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-19T13:25:09.36917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.284650701s","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-19T13:25:09.369515Z","caller":"traceutil/trace.go:171","msg":"trace[567790668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:623; }","duration":"1.284970409s","start":"2026-04-19T13:25:08.084415Z","end":"2026-04-19T13:25:09.369385Z","steps":["trace[567790668] 'agreement among raft nodes before linearized reading' (duration: 1.284222063s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:09.369694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:08.08438Z","time spent":"1.285198233s","remote":"127.0.0.1:58658","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-19T13:25:10.957987Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.548249653s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-19T13:25:11.584318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.97659ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:25:11.584579Z","caller":"traceutil/trace.go:171","msg":"trace[1016981342] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"2.201208633s","start":"2026-04-19T13:25:09.383352Z","end":"2026-04-19T13:25:11.584561Z","steps":["trace[1016981342] 'process raft request' (duration: 2.201161432s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:11.584645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:09.383324Z","time spent":"2.201288015s","remote":"127.0.0.1:58608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:25:11.584785Z","caller":"traceutil/trace.go:171","msg":"trace[910376318] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"2.210183722s","start":"2026-04-19T13:25:09.37459Z","end":"2026-04-19T13:25:11.584774Z","steps":["trace[910376318] 'process raft request' (duration: 2.209829375s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:11.584874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:09.374578Z","time spent":"2.210229034s","remote":"127.0.0.1:58658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:25:11.584876Z","caller":"traceutil/trace.go:171","msg":"trace[897813803] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"2.21006964s","start":"2026-04-19T13:25:09.37479Z","end":"2026-04-19T13:25:11.584859Z","steps":["trace[897813803] 'process raft request' (duration: 2.209682462s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:11.584963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:09.374777Z","time spent":"2.210150352s","remote":"127.0.0.1:58658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:25:11.585069Z","caller":"traceutil/trace.go:171","msg":"trace[1593596278] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"2.211786386s","start":"2026-04-19T13:25:09.373273Z","end":"2026-04-19T13:25:11.58506Z","steps":["trace[1593596278] 'process raft request' (duration: 1.585000959s)","trace[1593596278] 'compare' (duration: 625.694695ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:25:11.585102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:09.373253Z","time spent":"2.211831168s","remote":"127.0.0.1:58658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:25:11.846766Z","caller":"traceutil/trace.go:171","msg":"trace[322114808] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"2.435518156s","start":"2026-04-19T13:25:09.411207Z","end":"2026-04-19T13:25:11.846726Z","steps":["trace[322114808] 'process raft request' (duration: 2.43518659s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:11.847078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:09.411191Z","time spent":"2.435770552s","remote":"127.0.0.1:58424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:25:11.847467Z","caller":"traceutil/trace.go:171","msg":"trace[824090551] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:644; }","duration":"1.354270626s","start":"2026-04-19T13:25:10.493164Z","end":"2026-04-19T13:25:11.847434Z","steps":["trace[824090551] 'read index received' (duration: 465.194485ms)","trace[824090551] 'applied index is now lower than readState.Index' (duration: 889.074941ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:25:11.84761Z","caller":"traceutil/trace.go:171","msg":"trace[889397900] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"2.084108846s","start":"2026-04-19T13:25:09.763489Z","end":"2026-04-19T13:25:11.847598Z","steps":["trace[889397900] 'process raft request' (duration: 2.08336095s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:11.847721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.354552532s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:25:11.8478Z","caller":"traceutil/trace.go:171","msg":"trace[99127817] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"1.354670535s","start":"2026-04-19T13:25:10.493114Z","end":"2026-04-19T13:25:11.847784Z","steps":["trace[99127817] 'agreement among raft nodes before linearized reading' (duration: 1.354549802s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:11.8479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:10.493092Z","time spent":"1.354796777s","remote":"127.0.0.1:58396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-19T13:25:11.848064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:09.763453Z","time spent":"2.084243509s","remote":"127.0.0.1:58506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:25:11.849387Z","caller":"traceutil/trace.go:171","msg":"trace[1344936406] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"319.113015ms","start":"2026-04-19T13:25:11.530247Z","end":"2026-04-19T13:25:11.84936Z","steps":["trace[1344936406] 'process raft request' (duration: 318.927852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:11.849554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:11.530212Z","time spent":"319.260029ms","remote":"127.0.0.1:58658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T13:25:11.849621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.598041ms","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-19T13:25:11.849704Z","caller":"traceutil/trace.go:171","msg":"trace[1932104349] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:630; }","duration":"477.765003ms","start":"2026-04-19T13:25:11.371924Z","end":"2026-04-19T13:25:11.849689Z","steps":["trace[1932104349] 'agreement among raft nodes before linearized reading' (duration: 477.560359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:11.84975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:11.371903Z","time spent":"477.838265ms","remote":"127.0.0.1:58658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-19T13:25:13.989051Z","caller":"traceutil/trace.go:171","msg":"trace[333717974] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"123.931717ms","start":"2026-04-19T13:25:13.865098Z","end":"2026-04-19T13:25:13.98903Z","steps":["trace[333717974] 'process raft request' (duration: 123.56587ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:17.113851Z","caller":"traceutil/trace.go:171","msg":"trace[6335858] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"157.971984ms","start":"2026-04-19T13:25:16.955858Z","end":"2026-04-19T13:25:17.11383Z","steps":["trace[6335858] 'process raft request' (duration: 157.720069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:29.347741Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8970780843688493470,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T13:25:29.558959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.400082913s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-19T13:25:29.559014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064412735s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:25:29.559045Z","caller":"traceutil/trace.go:171","msg":"trace[1013446485] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:793; }","duration":"1.400243257s","start":"2026-04-19T13:25:28.158783Z","end":"2026-04-19T13:25:29.559027Z","steps":["trace[1013446485] 'count revisions from in-memory index tree' (duration: 1.399923811s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:29.55909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:28.158761Z","time spent":"1.400318068s","remote":"127.0.0.1:58666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-04-19T13:25:29.559095Z","caller":"traceutil/trace.go:171","msg":"trace[1785011016] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:793; }","duration":"1.064546577s","start":"2026-04-19T13:25:28.494522Z","end":"2026-04-19T13:25:29.559069Z","steps":["trace[1785011016] 'range keys from in-memory index tree' (duration: 1.064306842s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:29.559137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:28.494494Z","time spent":"1.064632288s","remote":"127.0.0.1:58396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-19T13:25:29.559429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.472184ms","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-19T13:25:29.559508Z","caller":"traceutil/trace.go:171","msg":"trace[908355247] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:793; }","duration":"788.548405ms","start":"2026-04-19T13:25:28.770945Z","end":"2026-04-19T13:25:29.559494Z","steps":["trace[908355247] 'range keys from in-memory index tree' (duration: 788.464313ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:29.562636Z","caller":"traceutil/trace.go:171","msg":"trace[576616752] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"734.42501ms","start":"2026-04-19T13:25:28.828189Z","end":"2026-04-19T13:25:29.562615Z","steps":["trace[576616752] 'process raft request' (duration: 734.187726ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:25:29.562617Z","caller":"traceutil/trace.go:171","msg":"trace[1437551652] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"715.335458ms","start":"2026-04-19T13:25:28.847246Z","end":"2026-04-19T13:25:29.562581Z","steps":["trace[1437551652] 'read index received' (duration: 715.049603ms)","trace[1437551652] 'applied index is now lower than readState.Index' (duration: 283.955µs)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:25:29.562757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:28.828165Z","time spent":"734.522381ms","remote":"127.0.0.1:58646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T13:25:29.562916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.630965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.226\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-19T13:25:29.56298Z","caller":"traceutil/trace.go:171","msg":"trace[45585129] range","detail":"{range_begin:/registry/masterleases/199.19.213.226; range_end:; response_count:1; response_revision:794; }","duration":"715.743576ms","start":"2026-04-19T13:25:28.847208Z","end":"2026-04-19T13:25:29.562952Z","steps":["trace[45585129] 'agreement among raft nodes before linearized reading' (duration: 715.537933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:29.563025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:25:28.847197Z","time spent":"715.815067ms","remote":"127.0.0.1:58424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.226\" "} {"level":"info","ts":"2026-04-19T13:25:30.736644Z","caller":"traceutil/trace.go:171","msg":"trace[1765839625] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:829; }","duration":"160.326988ms","start":"2026-04-19T13:25:30.576293Z","end":"2026-04-19T13:25:30.73662Z","steps":["trace[1765839625] 'read index received' (duration: 160.108554ms)","trace[1765839625] 'applied index is now lower than readState.Index' (duration: 217.214µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:25:30.736748Z","caller":"traceutil/trace.go:171","msg":"trace[1331817213] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"168.064528ms","start":"2026-04-19T13:25:30.568649Z","end":"2026-04-19T13:25:30.736713Z","steps":["trace[1331817213] 'process raft request' (duration: 167.819173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:25:30.737109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.782736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-htgpn\" ","response":"range_response_count:1 size:4226"} {"level":"info","ts":"2026-04-19T13:25:30.737178Z","caller":"traceutil/trace.go:171","msg":"trace[2006794658] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-htgpn; range_end:; response_count:1; response_revision:803; }","duration":"160.911368ms","start":"2026-04-19T13:25:30.576249Z","end":"2026-04-19T13:25:30.73716Z","steps":["trace[2006794658] 'agreement among raft nodes before linearized reading' (duration: 160.47833ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:26:20.132685Z","caller":"traceutil/trace.go:171","msg":"trace[1548723738] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"192.845231ms","start":"2026-04-19T13:26:19.939812Z","end":"2026-04-19T13:26:20.132657Z","steps":["trace[1548723738] 'process raft request' (duration: 192.682139ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:26:20.180108Z","caller":"traceutil/trace.go:171","msg":"trace[770369121] linearizableReadLoop","detail":"{readStateIndex:1132; appliedIndex:1130; }","duration":"191.084224ms","start":"2026-04-19T13:26:19.988997Z","end":"2026-04-19T13:26:20.180081Z","steps":["trace[770369121] 'read index received' (duration: 143.587149ms)","trace[770369121] 'applied index is now lower than readState.Index' (duration: 47.496005ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:26:20.180423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.411398ms","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-19T13:26:20.180227Z","caller":"traceutil/trace.go:171","msg":"trace[991347345] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"237.361754ms","start":"2026-04-19T13:26:19.942797Z","end":"2026-04-19T13:26:20.180159Z","steps":["trace[991347345] 'process raft request' (duration: 237.0786ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:26:20.180498Z","caller":"traceutil/trace.go:171","msg":"trace[1644553611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1094; }","duration":"191.505979ms","start":"2026-04-19T13:26:19.98898Z","end":"2026-04-19T13:26:20.180486Z","steps":["trace[1644553611] 'agreement among raft nodes before linearized reading' (duration: 191.230596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:26:20.18043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.649231ms","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-19T13:26:20.180791Z","caller":"traceutil/trace.go:171","msg":"trace[1628292917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1094; }","duration":"187.038127ms","start":"2026-04-19T13:26:19.993712Z","end":"2026-04-19T13:26:20.18075Z","steps":["trace[1628292917] 'agreement among raft nodes before linearized reading' (duration: 186.571351ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:26:20.316515Z","caller":"traceutil/trace.go:171","msg":"trace[346684379] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"130.481335ms","start":"2026-04-19T13:26:20.186007Z","end":"2026-04-19T13:26:20.316488Z","steps":["trace[346684379] 'process raft request' (duration: 130.377623ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:26:20.689252Z","caller":"traceutil/trace.go:171","msg":"trace[324509487] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"194.716513ms","start":"2026-04-19T13:26:20.49451Z","end":"2026-04-19T13:26:20.689227Z","steps":["trace[324509487] 'process raft request' (duration: 194.583602ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:26:22.317583Z","caller":"traceutil/trace.go:171","msg":"trace[1430551568] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"126.81201ms","start":"2026-04-19T13:26:22.190745Z","end":"2026-04-19T13:26:22.317557Z","steps":["trace[1430551568] 'process raft request' (duration: 43.937089ms)","trace[1430551568] 'compare' (duration: 82.667688ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:26:22.439842Z","caller":"traceutil/trace.go:171","msg":"trace[1081153635] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"115.31725ms","start":"2026-04-19T13:26:22.324485Z","end":"2026-04-19T13:26:22.439803Z","steps":["trace[1081153635] 'process raft request' (duration: 94.623563ms)","trace[1081153635] 'compare' (duration: 20.385693ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:27:14.209002Z","caller":"traceutil/trace.go:171","msg":"trace[369378621] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"195.903901ms","start":"2026-04-19T13:27:14.013068Z","end":"2026-04-19T13:27:14.208972Z","steps":["trace[369378621] 'process raft request' (duration: 195.01931ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:27:20.368101Z","caller":"traceutil/trace.go:171","msg":"trace[642876673] linearizableReadLoop","detail":"{readStateIndex:1377; appliedIndex:1376; }","duration":"126.652254ms","start":"2026-04-19T13:27:20.241423Z","end":"2026-04-19T13:27:20.368075Z","steps":["trace[642876673] 'read index received' (duration: 126.464032ms)","trace[642876673] 'applied index is now lower than readState.Index' (duration: 183.912µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:27:20.368193Z","caller":"traceutil/trace.go:171","msg":"trace[1383612810] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"130.060274ms","start":"2026-04-19T13:27:20.238104Z","end":"2026-04-19T13:27:20.368165Z","steps":["trace[1383612810] 'process raft request' (duration: 129.779471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:20.368374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.880037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-fc969f2c-7461-4623-8326-a7179f11a880\" ","response":"range_response_count:1 size:1255"} {"level":"info","ts":"2026-04-19T13:27:20.368516Z","caller":"traceutil/trace.go:171","msg":"trace[1789812446] range","detail":"{range_begin:/registry/persistentvolumes/pvc-fc969f2c-7461-4623-8326-a7179f11a880; range_end:; response_count:1; response_revision:1325; }","duration":"127.102739ms","start":"2026-04-19T13:27:20.241394Z","end":"2026-04-19T13:27:20.368497Z","steps":["trace[1789812446] 'agreement among raft nodes before linearized reading' (duration: 126.785015ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:27:20.962842Z","caller":"traceutil/trace.go:171","msg":"trace[692177844] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"172.108083ms","start":"2026-04-19T13:27:20.790675Z","end":"2026-04-19T13:27:20.962784Z","steps":["trace[692177844] 'process raft request' (duration: 171.818439ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:27:20.963174Z","caller":"traceutil/trace.go:171","msg":"trace[1192039683] linearizableReadLoop","detail":"{readStateIndex:1381; appliedIndex:1379; }","duration":"152.046945ms","start":"2026-04-19T13:27:20.811094Z","end":"2026-04-19T13:27:20.963141Z","steps":["trace[1192039683] 'read index received' (duration: 151.511049ms)","trace[1192039683] 'applied index is now lower than readState.Index' (duration: 533.546µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:27:20.963369Z","caller":"traceutil/trace.go:171","msg":"trace[1161149847] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"172.634629ms","start":"2026-04-19T13:27:20.790671Z","end":"2026-04-19T13:27:20.963306Z","steps":["trace[1161149847] 'process raft request' (duration: 172.061052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:20.963468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.360719ms","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-19T13:27:20.963509Z","caller":"traceutil/trace.go:171","msg":"trace[696765823] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1329; }","duration":"152.439869ms","start":"2026-04-19T13:27:20.811056Z","end":"2026-04-19T13:27:20.963496Z","steps":["trace[696765823] 'agreement among raft nodes before linearized reading' (duration: 152.258657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:24.463598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.522482ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-19T13:27:24.4637Z","caller":"traceutil/trace.go:171","msg":"trace[829326021] linearizableReadLoop","detail":"{readStateIndex:1391; appliedIndex:1390; }","duration":"320.360027ms","start":"2026-04-19T13:27:24.143321Z","end":"2026-04-19T13:27:24.463681Z","steps":["trace[829326021] 'read index received' (duration: 36.791563ms)","trace[829326021] 'applied index is now lower than readState.Index' (duration: 283.567034ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:27:24.463888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.547609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2569"} {"level":"info","ts":"2026-04-19T13:27:24.463932Z","caller":"traceutil/trace.go:171","msg":"trace[255803717] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1338; }","duration":"320.65327ms","start":"2026-04-19T13:27:24.143268Z","end":"2026-04-19T13:27:24.463921Z","steps":["trace[255803717] 'agreement among raft nodes before linearized reading' (duration: 320.459418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:24.463967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:27:24.143249Z","time spent":"320.709421ms","remote":"127.0.0.1:58608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":2592,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"info","ts":"2026-04-19T13:27:24.923915Z","caller":"traceutil/trace.go:171","msg":"trace[1754753465] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"452.225631ms","start":"2026-04-19T13:27:24.471659Z","end":"2026-04-19T13:27:24.923885Z","steps":["trace[1754753465] 'process raft request' (duration: 451.813136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:24.924227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:27:24.471641Z","time spent":"452.379904ms","remote":"127.0.0.1:58608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2566,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T13:27:25.862436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.451008ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:27:25.862656Z","caller":"traceutil/trace.go:171","msg":"trace[1795027124] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"1.284592397s","start":"2026-04-19T13:27:24.57803Z","end":"2026-04-19T13:27:25.862622Z","steps":["trace[1795027124] 'process raft request' (duration: 445.817645ms)","trace[1795027124] 'compare' (duration: 838.167745ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:27:25.862718Z","caller":"traceutil/trace.go:171","msg":"trace[757497613] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"931.675297ms","start":"2026-04-19T13:27:24.931015Z","end":"2026-04-19T13:27:25.86269Z","steps":["trace[757497613] 'process raft request' (duration: 931.587356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:25.862799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:27:24.930991Z","time spent":"931.775048ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":706,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-19T13:27:25.862843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:27:24.578001Z","time spent":"1.284732689s","remote":"127.0.0.1:58658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:27:25.862789Z","caller":"traceutil/trace.go:171","msg":"trace[280841120] linearizableReadLoop","detail":"{readStateIndex:1393; appliedIndex:1391; }","duration":"1.190704571s","start":"2026-04-19T13:27:24.672012Z","end":"2026-04-19T13:27:25.862716Z","steps":["trace[280841120] 'read index received' (duration: 251.691258ms)","trace[280841120] 'applied index is now lower than readState.Index' (duration: 939.003853ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:27:25.863054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.191045955s","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-19T13:27:25.864097Z","caller":"traceutil/trace.go:171","msg":"trace[1421232171] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1341; }","duration":"1.192128537s","start":"2026-04-19T13:27:24.671948Z","end":"2026-04-19T13:27:25.864076Z","steps":["trace[1421232171] 'agreement among raft nodes before linearized reading' (duration: 1.190942243s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:25.864149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:27:24.671922Z","time spent":"1.192213849s","remote":"127.0.0.1:58658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-19T13:27:26.363866Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8970780843688494783,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T13:27:26.864169Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8970780843688494783,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T13:27:27.083572Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.770980941s","expected-duration":"1s"} {"level":"info","ts":"2026-04-19T13:27:27.084231Z","caller":"traceutil/trace.go:171","msg":"trace[1750148056] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"2.020461766s","start":"2026-04-19T13:27:25.063741Z","end":"2026-04-19T13:27:27.084202Z","steps":["trace[1750148056] 'process raft request' (duration: 2.020155403s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:27.084415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:27:25.063703Z","time spent":"2.020622829s","remote":"127.0.0.1:58658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T13:27:27.475657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.278975ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:27:27.475794Z","caller":"traceutil/trace.go:171","msg":"trace[973207474] linearizableReadLoop","detail":"{readStateIndex:1396; appliedIndex:1394; }","duration":"1.612812924s","start":"2026-04-19T13:27:25.862961Z","end":"2026-04-19T13:27:27.475774Z","steps":["trace[973207474] 'read index received' (duration: 1.220921766s)","trace[973207474] 'applied index is now lower than readState.Index' (duration: 391.889678ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:27:27.475968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.434417244s","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-19T13:27:27.476031Z","caller":"traceutil/trace.go:171","msg":"trace[71042919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1343; }","duration":"2.434524535s","start":"2026-04-19T13:27:25.041489Z","end":"2026-04-19T13:27:27.476014Z","steps":["trace[71042919] 'agreement among raft nodes before linearized reading' (duration: 2.434299012s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:27.47604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.966658028s","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:7"} {"level":"info","ts":"2026-04-19T13:27:27.476041Z","caller":"traceutil/trace.go:171","msg":"trace[532692360] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"1.606224376s","start":"2026-04-19T13:27:25.869781Z","end":"2026-04-19T13:27:27.476005Z","steps":["trace[532692360] 'process raft request' (duration: 1.338534146s)","trace[532692360] 'compare' (duration: 267.022123ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:27:27.476083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:27:25.041454Z","time spent":"2.434615626s","remote":"127.0.0.1:58658","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-19T13:27:27.476103Z","caller":"traceutil/trace.go:171","msg":"trace[515936495] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1343; }","duration":"1.966777079s","start":"2026-04-19T13:27:25.509314Z","end":"2026-04-19T13:27:27.476091Z","steps":["trace[515936495] 'agreement among raft nodes before linearized reading' (duration: 1.966649008s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:27.476173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:27:25.509301Z","time spent":"1.96686215s","remote":"127.0.0.1:53674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-04-19T13:27:27.476161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.981192439s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-19T13:27:27.476172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.546735077s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2581"} {"level":"warn","ts":"2026-04-19T13:27:27.476215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:27:25.869758Z","time spent":"1.606368348s","remote":"127.0.0.1:58658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:27:27.476248Z","caller":"traceutil/trace.go:171","msg":"trace[385276393] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1343; }","duration":"1.981373751s","start":"2026-04-19T13:27:25.494855Z","end":"2026-04-19T13:27:27.476229Z","steps":["trace[385276393] 'agreement among raft nodes before linearized reading' (duration: 1.981133408s)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:27:27.476262Z","caller":"traceutil/trace.go:171","msg":"trace[1711786150] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1343; }","duration":"2.546858228s","start":"2026-04-19T13:27:24.929385Z","end":"2026-04-19T13:27:27.476244Z","steps":["trace[1711786150] 'agreement among raft nodes before linearized reading' (duration: 2.546622415s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:27.476303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:27:25.494828Z","time spent":"1.981465103s","remote":"127.0.0.1:58396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-19T13:27:27.476255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.436052343s","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-19T13:27:27.476436Z","caller":"traceutil/trace.go:171","msg":"trace[1108474930] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1343; }","duration":"2.436248185s","start":"2026-04-19T13:27:25.040144Z","end":"2026-04-19T13:27:27.476392Z","steps":["trace[1108474930] 'agreement among raft nodes before linearized reading' (duration: 2.435736399s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:27.476462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.304705591s","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-19T13:27:27.476499Z","caller":"traceutil/trace.go:171","msg":"trace[290940783] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1343; }","duration":"1.304742921s","start":"2026-04-19T13:27:26.171746Z","end":"2026-04-19T13:27:27.476489Z","steps":["trace[290940783] 'agreement among raft nodes before linearized reading' (duration: 1.30467361s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:27.476536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:27:26.171645Z","time spent":"1.304884573s","remote":"127.0.0.1:58580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-04-19T13:27:27.47653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.498397217s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-19T13:27:27.476528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:27:25.040119Z","time spent":"2.436362016s","remote":"127.0.0.1:58658","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-19T13:27:27.476679Z","caller":"traceutil/trace.go:171","msg":"trace[1688099211] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1343; }","duration":"2.498531919s","start":"2026-04-19T13:27:24.978102Z","end":"2026-04-19T13:27:27.476634Z","steps":["trace[1688099211] 'agreement among raft nodes before linearized reading' (duration: 2.498268096s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:27.476713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.958838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-19T13:27:27.476743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.6822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-19T13:27:27.476768Z","caller":"traceutil/trace.go:171","msg":"trace[143321303] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1343; }","duration":"776.073759ms","start":"2026-04-19T13:27:26.700678Z","end":"2026-04-19T13:27:27.476752Z","steps":["trace[143321303] 'agreement among raft nodes before linearized reading' (duration: 775.988868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:27.476649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.804539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-19T13:27:27.476848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:27:26.700642Z","time spent":"776.189ms","remote":"127.0.0.1:54706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-19T13:27:27.476868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.499208426s","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-19T13:27:27.47692Z","caller":"traceutil/trace.go:171","msg":"trace[1590651078] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1343; }","duration":"2.499301438s","start":"2026-04-19T13:27:24.977607Z","end":"2026-04-19T13:27:27.476909Z","steps":["trace[1590651078] 'agreement among raft nodes before linearized reading' (duration: 2.499129756s)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:27:27.476773Z","caller":"traceutil/trace.go:171","msg":"trace[415055723] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1343; }","duration":"273.757901ms","start":"2026-04-19T13:27:27.203006Z","end":"2026-04-19T13:27:27.476763Z","steps":["trace[415055723] 'agreement among raft nodes before linearized reading' (duration: 273.695061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:27.476955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:27:24.977588Z","time spent":"2.499359789s","remote":"127.0.0.1:58658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-19T13:27:27.476927Z","caller":"traceutil/trace.go:171","msg":"trace[1341486508] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1343; }","duration":"203.113723ms","start":"2026-04-19T13:27:27.27376Z","end":"2026-04-19T13:27:27.476873Z","steps":["trace[1341486508] 'agreement among raft nodes before linearized reading' (duration: 202.85444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:27.476823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:27:24.978094Z","time spent":"2.498684721s","remote":"127.0.0.1:58658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-19T13:27:27.476682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.523701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-19T13:27:27.476342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:27:24.929366Z","time spent":"2.546958719s","remote":"127.0.0.1:58608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":2604,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"info","ts":"2026-04-19T13:27:27.47726Z","caller":"traceutil/trace.go:171","msg":"trace[879195605] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1343; }","duration":"310.159629ms","start":"2026-04-19T13:27:27.167086Z","end":"2026-04-19T13:27:27.477245Z","steps":["trace[879195605] 'agreement among raft nodes before linearized reading' (duration: 309.524562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:27.477308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:27:27.167047Z","time spent":"310.24425ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-04-19T13:27:27.477513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.063385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:58699"} {"level":"info","ts":"2026-04-19T13:27:27.477756Z","caller":"traceutil/trace.go:171","msg":"trace[1520078485] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1343; }","duration":"285.233716ms","start":"2026-04-19T13:27:27.192363Z","end":"2026-04-19T13:27:27.477597Z","steps":["trace[1520078485] 'agreement among raft nodes before linearized reading' (duration: 284.195494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:28.315656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.325916ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:27:28.315978Z","caller":"traceutil/trace.go:171","msg":"trace[1767039296] linearizableReadLoop","detail":"{readStateIndex:1400; appliedIndex:1396; }","duration":"830.020791ms","start":"2026-04-19T13:27:27.48594Z","end":"2026-04-19T13:27:28.315961Z","steps":["trace[1767039296] 'read index received' (duration: 76.146143ms)","trace[1767039296] 'applied index is now lower than readState.Index' (duration: 753.873688ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:27:28.316058Z","caller":"traceutil/trace.go:171","msg":"trace[1812568230] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"832.123616ms","start":"2026-04-19T13:27:27.483924Z","end":"2026-04-19T13:27:28.316047Z","steps":["trace[1812568230] 'process raft request' (duration: 831.839313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:28.316127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:27:27.483902Z","time spent":"832.189216ms","remote":"127.0.0.1:58658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:27:28.316175Z","caller":"traceutil/trace.go:171","msg":"trace[334559738] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"447.743307ms","start":"2026-04-19T13:27:27.868386Z","end":"2026-04-19T13:27:28.316129Z","steps":["trace[334559738] 'process raft request' (duration: 447.540845ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:27:28.316255Z","caller":"traceutil/trace.go:171","msg":"trace[329915956] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"832.320898ms","start":"2026-04-19T13:27:27.483924Z","end":"2026-04-19T13:27:28.316244Z","steps":["trace[329915956] 'process raft request' (duration: 831.951624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:28.316313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:27:27.483903Z","time spent":"832.381829ms","remote":"127.0.0.1:58658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T13:27:28.316378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:27:27.86835Z","time spent":"447.906808ms","remote":"127.0.0.1:58658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:27:28.316529Z","caller":"traceutil/trace.go:171","msg":"trace[604205254] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"832.660881ms","start":"2026-04-19T13:27:27.483851Z","end":"2026-04-19T13:27:28.316512Z","steps":["trace[604205254] 'process raft request' (duration: 445.383214ms)","trace[604205254] 'compare' (duration: 386.024973ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:27:28.316754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:27:27.483839Z","time spent":"832.711843ms","remote":"127.0.0.1:58658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T13:27:28.31706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.108604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:27:28.317116Z","caller":"traceutil/trace.go:171","msg":"trace[1253546443] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1348; }","duration":"831.179064ms","start":"2026-04-19T13:27:27.485922Z","end":"2026-04-19T13:27:28.317101Z","steps":["trace[1253546443] 'agreement among raft nodes before linearized reading' (duration: 830.104972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:28.31716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:27:27.485914Z","time spent":"831.238065ms","remote":"127.0.0.1:58396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-19T13:27:29.030862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.165187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:27:29.030951Z","caller":"traceutil/trace.go:171","msg":"trace[2133887386] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1348; }","duration":"535.319908ms","start":"2026-04-19T13:27:28.495611Z","end":"2026-04-19T13:27:29.030931Z","steps":["trace[2133887386] 'range keys from in-memory index tree' (duration: 534.998915ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:29.03101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:27:28.495585Z","time spent":"535.406079ms","remote":"127.0.0.1:58396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-19T13:27:29.031409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.077967ms","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-19T13:27:29.03157Z","caller":"traceutil/trace.go:171","msg":"trace[840256898] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1348; }","duration":"261.239569ms","start":"2026-04-19T13:27:28.770317Z","end":"2026-04-19T13:27:29.031557Z","steps":["trace[840256898] 'range keys from in-memory index tree' (duration: 261.067627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:29.031499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.184628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.226\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-19T13:27:29.031767Z","caller":"traceutil/trace.go:171","msg":"trace[824856455] range","detail":"{range_begin:/registry/masterleases/199.19.213.226; range_end:; response_count:1; response_revision:1348; }","duration":"179.527472ms","start":"2026-04-19T13:27:28.852201Z","end":"2026-04-19T13:27:29.031729Z","steps":["trace[824856455] 'range keys from in-memory index tree' (duration: 178.917104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:29.87953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.327816ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:27:29.879646Z","caller":"traceutil/trace.go:171","msg":"trace[270738198] linearizableReadLoop","detail":"{readStateIndex:1403; appliedIndex:1402; }","duration":"396.381826ms","start":"2026-04-19T13:27:29.483245Z","end":"2026-04-19T13:27:29.879627Z","steps":["trace[270738198] 'read index received' (duration: 52.301µs)","trace[270738198] 'applied index is now lower than readState.Index' (duration: 396.327215ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:27:29.87976Z","caller":"traceutil/trace.go:171","msg":"trace[1974020505] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"682.39763ms","start":"2026-04-19T13:27:29.197309Z","end":"2026-04-19T13:27:29.879707Z","steps":["trace[1974020505] 'process raft request' (duration: 219.7889ms)","trace[1974020505] 'compare' (duration: 461.895701ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:27:29.879945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.706319ms","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-19T13:27:29.879977Z","caller":"traceutil/trace.go:171","msg":"trace[1746751389] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1349; }","duration":"396.78351ms","start":"2026-04-19T13:27:29.483186Z","end":"2026-04-19T13:27:29.879969Z","steps":["trace[1746751389] 'agreement among raft nodes before linearized reading' (duration: 396.577717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:29.879968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:27:29.197281Z","time spent":"682.561672ms","remote":"127.0.0.1:58424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T13:27:29.879999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:27:29.483157Z","time spent":"396.837661ms","remote":"127.0.0.1:58658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-19T13:27:29.880056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.118132ms","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-19T13:27:29.880116Z","caller":"traceutil/trace.go:171","msg":"trace[1911563899] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1349; }","duration":"313.215813ms","start":"2026-04-19T13:27:29.566886Z","end":"2026-04-19T13:27:29.880101Z","steps":["trace[1911563899] 'agreement among raft nodes before linearized reading' (duration: 313.019141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:29.880173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:27:29.566868Z","time spent":"313.277234ms","remote":"127.0.0.1:58658","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-19T13:27:30.126826Z","caller":"traceutil/trace.go:171","msg":"trace[1717271135] linearizableReadLoop","detail":"{readStateIndex:1405; appliedIndex:1403; }","duration":"241.597013ms","start":"2026-04-19T13:27:29.8852Z","end":"2026-04-19T13:27:30.126797Z","steps":["trace[1717271135] 'read index received' (duration: 74.358329ms)","trace[1717271135] 'applied index is now lower than readState.Index' (duration: 167.237184ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:27:30.126856Z","caller":"traceutil/trace.go:171","msg":"trace[1053973570] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"241.915456ms","start":"2026-04-19T13:27:29.884899Z","end":"2026-04-19T13:27:30.126815Z","steps":["trace[1053973570] 'process raft request' (duration: 162.754431ms)","trace[1053973570] 'compare' (duration: 78.867071ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:27:30.126992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.769824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-19T13:27:30.127035Z","caller":"traceutil/trace.go:171","msg":"trace[1094979338] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1351; }","duration":"241.847075ms","start":"2026-04-19T13:27:29.885177Z","end":"2026-04-19T13:27:30.127024Z","steps":["trace[1094979338] 'agreement among raft nodes before linearized reading' (duration: 241.722664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:36.348893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.689527ms","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-19T13:27:36.348973Z","caller":"traceutil/trace.go:171","msg":"trace[1193987487] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1375; }","duration":"120.823208ms","start":"2026-04-19T13:27:36.228133Z","end":"2026-04-19T13:27:36.348956Z","steps":["trace[1193987487] 'range keys from in-memory index tree' (duration: 120.521445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:36.349034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.125355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-19T13:27:36.349143Z","caller":"traceutil/trace.go:171","msg":"trace[1148738958] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1375; }","duration":"123.347897ms","start":"2026-04-19T13:27:36.225772Z","end":"2026-04-19T13:27:36.34912Z","steps":["trace[1148738958] 'count revisions from in-memory index tree' (duration: 123.000374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:36.824682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.836834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:attachdetach-controller\" ","response":"range_response_count:1 size:952"} {"level":"info","ts":"2026-04-19T13:27:36.824751Z","caller":"traceutil/trace.go:171","msg":"trace[1851916069] range","detail":"{range_begin:/registry/clusterroles/system:controller:attachdetach-controller; range_end:; response_count:1; response_revision:1380; }","duration":"111.939685ms","start":"2026-04-19T13:27:36.712796Z","end":"2026-04-19T13:27:36.824736Z","steps":["trace[1851916069] 'range keys from in-memory index tree' (duration: 111.765154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:36.82494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.455521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver\" ","response":"range_response_count:1 size:681"} {"level":"info","ts":"2026-04-19T13:27:36.825014Z","caller":"traceutil/trace.go:171","msg":"trace[1945739939] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver; range_end:; response_count:1; response_revision:1380; }","duration":"131.575142ms","start":"2026-04-19T13:27:36.693423Z","end":"2026-04-19T13:27:36.824999Z","steps":["trace[1945739939] 'range keys from in-memory index tree' (duration: 131.37709ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:27:38.591589Z","caller":"traceutil/trace.go:171","msg":"trace[1552148988] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"143.037771ms","start":"2026-04-19T13:27:38.448514Z","end":"2026-04-19T13:27:38.591552Z","steps":["trace[1552148988] 'process raft request' (duration: 142.796218ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:27:39.019676Z","caller":"traceutil/trace.go:171","msg":"trace[1638242298] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"145.599949ms","start":"2026-04-19T13:27:38.874038Z","end":"2026-04-19T13:27:39.019638Z","steps":["trace[1638242298] 'process raft request' (duration: 79.384666ms)","trace[1638242298] 'compare' (duration: 66.006601ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:27:40.556521Z","caller":"traceutil/trace.go:171","msg":"trace[706450456] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"110.153927ms","start":"2026-04-19T13:27:40.446324Z","end":"2026-04-19T13:27:40.556478Z","steps":["trace[706450456] 'process raft request' (duration: 110.008776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:41.100427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.395236ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:27:41.100577Z","caller":"traceutil/trace.go:171","msg":"trace[233580560] linearizableReadLoop","detail":"{readStateIndex:1448; appliedIndex:1447; }","duration":"496.575772ms","start":"2026-04-19T13:27:40.603971Z","end":"2026-04-19T13:27:41.100547Z","steps":["trace[233580560] 'read index received' (duration: 367.917063ms)","trace[233580560] 'applied index is now lower than readState.Index' (duration: 128.657639ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:27:41.100673Z","caller":"traceutil/trace.go:171","msg":"trace[183890264] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"502.464451ms","start":"2026-04-19T13:27:40.598196Z","end":"2026-04-19T13:27:41.10066Z","steps":["trace[183890264] 'process raft request' (duration: 373.745071ms)","trace[183890264] 'compare' (duration: 128.262894ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:27:41.100764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:27:40.598163Z","time spent":"502.559911ms","remote":"127.0.0.1:58658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T13:27:41.100785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.921704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:service-controller\" ","response":"range_response_count:1 size:724"} {"level":"info","ts":"2026-04-19T13:27:41.100912Z","caller":"traceutil/trace.go:171","msg":"trace[719686537] range","detail":"{range_begin:/registry/clusterroles/system:controller:service-controller; range_end:; response_count:1; response_revision:1391; }","duration":"462.108826ms","start":"2026-04-19T13:27:40.638773Z","end":"2026-04-19T13:27:41.100882Z","steps":["trace[719686537] 'agreement among raft nodes before linearized reading' (duration: 461.908664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:41.100967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:27:40.638753Z","time spent":"462.204057ms","remote":"127.0.0.1:58738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":747,"request content":"key:\"/registry/clusterroles/system:controller:service-controller\" "} {"level":"warn","ts":"2026-04-19T13:27:41.10109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.614152ms","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-19T13:27:41.101231Z","caller":"traceutil/trace.go:171","msg":"trace[251568872] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1391; }","duration":"442.815764ms","start":"2026-04-19T13:27:40.658388Z","end":"2026-04-19T13:27:41.101204Z","steps":["trace[251568872] 'agreement among raft nodes before linearized reading' (duration: 442.48205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:41.101292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:27:40.658367Z","time spent":"442.914725ms","remote":"127.0.0.1:58658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-19T13:27:41.101334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.116887ms","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-19T13:27:41.101349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.070122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:811"} {"level":"info","ts":"2026-04-19T13:27:41.101379Z","caller":"traceutil/trace.go:171","msg":"trace[881476431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1391; }","duration":"443.195849ms","start":"2026-04-19T13:27:40.658172Z","end":"2026-04-19T13:27:41.101368Z","steps":["trace[881476431] 'agreement among raft nodes before linearized reading' (duration: 443.042557ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:27:41.101397Z","caller":"traceutil/trace.go:171","msg":"trace[1828434151] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:1391; }","duration":"460.128893ms","start":"2026-04-19T13:27:40.641251Z","end":"2026-04-19T13:27:41.10138Z","steps":["trace[1828434151] 'agreement among raft nodes before linearized reading' (duration: 460.028312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:41.101411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:27:40.658154Z","time spent":"443.250309ms","remote":"127.0.0.1:58658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-19T13:27:41.101441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:27:40.641225Z","time spent":"460.207154ms","remote":"127.0.0.1:58754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":834,"request content":"key:\"/registry/clusterrolebindings/system:controller:clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-04-19T13:27:41.101504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.532073ms","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-19T13:27:41.101542Z","caller":"traceutil/trace.go:171","msg":"trace[1612580184] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1391; }","duration":"497.610195ms","start":"2026-04-19T13:27:40.603922Z","end":"2026-04-19T13:27:41.101532Z","steps":["trace[1612580184] 'agreement among raft nodes before linearized reading' (duration: 497.507873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:27:41.101575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:27:40.603899Z","time spent":"497.669875ms","remote":"127.0.0.1:58658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}