{"level":"warn","ts":"2026-03-19T17:46:11.035177Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-19T17:46:11.035272Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.47: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.47:2380","--initial-cluster=instance=https://199.19.213.47:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.47:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.47: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-03-19T17:46:11.035345Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-19T17:46:11.035359Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.47:2380"]} {"level":"info","ts":"2026-03-19T17:46:11.035377Z","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-03-19T17:46:11.035978Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.47:2379"]} {"level":"info","ts":"2026-03-19T17:46:11.036049Z","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.47:2380"],"listen-peer-urls":["https://199.19.213.47:2380"],"advertise-client-urls":["https://199.19.213.47:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.47:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.47: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-03-19T17:46:11.042553Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.288206ms"} {"level":"info","ts":"2026-03-19T17:46:11.052401Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"944e7e1987ec6eaf","cluster-id":"cb5c24893c5cbfa7"} {"level":"info","ts":"2026-03-19T17:46:11.052514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf switched to configuration voters=()"} {"level":"info","ts":"2026-03-19T17:46:11.052546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became follower at term 0"} {"level":"info","ts":"2026-03-19T17:46:11.052562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 944e7e1987ec6eaf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-19T17:46:11.052572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became follower at term 1"} {"level":"info","ts":"2026-03-19T17:46:11.052617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf switched to configuration voters=(10686617613916466863)"} {"level":"warn","ts":"2026-03-19T17:46:11.058194Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-19T17:46:11.059548Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-19T17:46:11.063223Z","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-03-19T17:46:11.065504Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"944e7e1987ec6eaf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-19T17:46:11.068346Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"944e7e1987ec6eaf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-19T17:46:11.068609Z","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-03-19T17:46:11.068663Z","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-03-19T17:46:11.068688Z","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-03-19T17:46:11.069304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf switched to configuration voters=(10686617613916466863)"} {"level":"info","ts":"2026-03-19T17:46:11.071362Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cb5c24893c5cbfa7","local-member-id":"944e7e1987ec6eaf","added-peer-id":"944e7e1987ec6eaf","added-peer-peer-urls":["https://199.19.213.47:2380"]} {"level":"info","ts":"2026-03-19T17:46:11.071966Z","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-03-19T17:46:11.072072Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.47:2380"} {"level":"info","ts":"2026-03-19T17:46:11.072103Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.47:2380"} {"level":"info","ts":"2026-03-19T17:46:11.072458Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"944e7e1987ec6eaf","initial-advertise-peer-urls":["https://199.19.213.47:2380"],"listen-peer-urls":["https://199.19.213.47:2380"],"advertise-client-urls":["https://199.19.213.47:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.47:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-19T17:46:11.072508Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-19T17:46:11.553641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf is starting a new election at term 1"} {"level":"info","ts":"2026-03-19T17:46:11.553699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became pre-candidate at term 1"} {"level":"info","ts":"2026-03-19T17:46:11.553721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf received MsgPreVoteResp from 944e7e1987ec6eaf at term 1"} {"level":"info","ts":"2026-03-19T17:46:11.55374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became candidate at term 2"} {"level":"info","ts":"2026-03-19T17:46:11.553751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf received MsgVoteResp from 944e7e1987ec6eaf at term 2"} {"level":"info","ts":"2026-03-19T17:46:11.553766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"944e7e1987ec6eaf became leader at term 2"} {"level":"info","ts":"2026-03-19T17:46:11.553791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 944e7e1987ec6eaf elected leader 944e7e1987ec6eaf at term 2"} {"level":"info","ts":"2026-03-19T17:46:11.554696Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"944e7e1987ec6eaf","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.47:2379]}","request-path":"/0/members/944e7e1987ec6eaf/attributes","cluster-id":"cb5c24893c5cbfa7","publish-timeout":"7s"} {"level":"info","ts":"2026-03-19T17:46:11.554727Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T17:46:11.554791Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T17:46:11.554946Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T17:46:11.555109Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-19T17:46:11.555204Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-19T17:46:11.556093Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cb5c24893c5cbfa7","local-member-id":"944e7e1987ec6eaf","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T17:46:11.556189Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T17:46:11.556244Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T17:46:11.55845Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.47:2379"} {"level":"info","ts":"2026-03-19T17:46:11.559709Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-19T17:46:13.209121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.412116ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-19T17:46:13.209352Z","caller":"traceutil/trace.go:171","msg":"trace[1911829903] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"249.721356ms","start":"2026-03-19T17:46:12.959593Z","end":"2026-03-19T17:46:13.209314Z","steps":["trace[1911829903] 'process raft request' (duration: 93.522307ms)","trace[1911829903] 'compare' (duration: 155.211211ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:13.20957Z","caller":"traceutil/trace.go:171","msg":"trace[1190076744] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"206.294669ms","start":"2026-03-19T17:46:13.003256Z","end":"2026-03-19T17:46:13.209551Z","steps":["trace[1190076744] 'process raft request' (duration: 206.254338ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:13.209619Z","caller":"traceutil/trace.go:171","msg":"trace[849543201] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"248.956323ms","start":"2026-03-19T17:46:12.960652Z","end":"2026-03-19T17:46:13.209608Z","steps":["trace[849543201] 'process raft request' (duration: 248.662595ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:13.209665Z","caller":"traceutil/trace.go:171","msg":"trace[933052372] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"248.957833ms","start":"2026-03-19T17:46:12.960674Z","end":"2026-03-19T17:46:13.209632Z","steps":["trace[933052372] 'process raft request' (duration: 248.668575ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:13.20971Z","caller":"traceutil/trace.go:171","msg":"trace[98702901] linearizableReadLoop","detail":"{readStateIndex:30; appliedIndex:24; }","duration":"229.871363ms","start":"2026-03-19T17:46:12.979795Z","end":"2026-03-19T17:46:13.209666Z","steps":["trace[98702901] 'read index received' (duration: 73.334835ms)","trace[98702901] 'applied index is now lower than readState.Index' (duration: 156.534008ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:13.209732Z","caller":"traceutil/trace.go:171","msg":"trace[369251698] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"248.881752ms","start":"2026-03-19T17:46:12.960842Z","end":"2026-03-19T17:46:13.209723Z","steps":["trace[369251698] 'process raft request' (duration: 248.635114ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:13.209664Z","caller":"traceutil/trace.go:171","msg":"trace[2093846803] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"248.878841ms","start":"2026-03-19T17:46:12.960645Z","end":"2026-03-19T17:46:13.209523Z","steps":["trace[2093846803] 'process raft request' (duration: 248.618284ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:13.209956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.498313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-19T17:46:13.210002Z","caller":"traceutil/trace.go:171","msg":"trace[991596811] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:27; }","duration":"227.624277ms","start":"2026-03-19T17:46:12.982363Z","end":"2026-03-19T17:46:13.209987Z","steps":["trace[991596811] 'agreement among raft nodes before linearized reading' (duration: 227.459693ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:13.210113Z","caller":"traceutil/trace.go:171","msg":"trace[187386965] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"249.282163ms","start":"2026-03-19T17:46:12.960806Z","end":"2026-03-19T17:46:13.210088Z","steps":["trace[187386965] 'process raft request' (duration: 248.561462ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:13.210283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.873286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-19T17:46:13.210337Z","caller":"traceutil/trace.go:171","msg":"trace[889516293] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:27; }","duration":"149.966399ms","start":"2026-03-19T17:46:13.060356Z","end":"2026-03-19T17:46:13.210322Z","steps":["trace[889516293] 'agreement among raft nodes before linearized reading' (duration: 149.637519ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:13.210753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.109631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-03-19T17:46:13.210818Z","caller":"traceutil/trace.go:171","msg":"trace[835363906] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:27; }","duration":"231.038868ms","start":"2026-03-19T17:46:12.979755Z","end":"2026-03-19T17:46:13.210794Z","steps":["trace[835363906] 'agreement among raft nodes before linearized reading' (duration: 230.050359ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:13.458256Z","caller":"traceutil/trace.go:171","msg":"trace[922267215] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"245.076376ms","start":"2026-03-19T17:46:13.213139Z","end":"2026-03-19T17:46:13.458216Z","steps":["trace[922267215] 'process raft request' (duration: 145.007912ms)","trace[922267215] 'compare' (duration: 99.855108ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:13.45831Z","caller":"traceutil/trace.go:171","msg":"trace[312713858] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"244.894161ms","start":"2026-03-19T17:46:13.21339Z","end":"2026-03-19T17:46:13.458284Z","steps":["trace[312713858] 'process raft request' (duration: 244.753017ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:13.45836Z","caller":"traceutil/trace.go:171","msg":"trace[1470399510] linearizableReadLoop","detail":"{readStateIndex:33; appliedIndex:32; }","duration":"245.040805ms","start":"2026-03-19T17:46:13.213302Z","end":"2026-03-19T17:46:13.458343Z","steps":["trace[1470399510] 'read index received' (duration: 38.616744ms)","trace[1470399510] 'applied index is now lower than readState.Index' (duration: 206.423061ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:13.458446Z","caller":"traceutil/trace.go:171","msg":"trace[1553400098] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"244.973284ms","start":"2026-03-19T17:46:13.213464Z","end":"2026-03-19T17:46:13.458438Z","steps":["trace[1553400098] 'process raft request' (duration: 244.723966ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:13.458581Z","caller":"traceutil/trace.go:171","msg":"trace[1337390486] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"244.790368ms","start":"2026-03-19T17:46:13.213775Z","end":"2026-03-19T17:46:13.458566Z","steps":["trace[1337390486] 'process raft request' (duration: 244.49937ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:13.458658Z","caller":"traceutil/trace.go:171","msg":"trace[1068760103] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"244.868181ms","start":"2026-03-19T17:46:13.21378Z","end":"2026-03-19T17:46:13.458648Z","steps":["trace[1068760103] 'process raft request' (duration: 244.527331ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:13.458748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.426297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-19T17:46:13.4588Z","caller":"traceutil/trace.go:171","msg":"trace[555679457] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:35; }","duration":"245.512219ms","start":"2026-03-19T17:46:13.213274Z","end":"2026-03-19T17:46:13.458786Z","steps":["trace[555679457] 'agreement among raft nodes before linearized reading' (duration: 245.379675ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:13.458803Z","caller":"traceutil/trace.go:171","msg":"trace[909619982] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"245.286023ms","start":"2026-03-19T17:46:13.213507Z","end":"2026-03-19T17:46:13.458793Z","steps":["trace[909619982] 'process raft request' (duration: 244.706326ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:13.458918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.615578ms","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-03-19T17:46:13.458983Z","caller":"traceutil/trace.go:171","msg":"trace[1957829142] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:35; }","duration":"204.751273ms","start":"2026-03-19T17:46:13.254217Z","end":"2026-03-19T17:46:13.458968Z","steps":["trace[1957829142] 'agreement among raft nodes before linearized reading' (duration: 204.623259ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:13.459108Z","caller":"traceutil/trace.go:171","msg":"trace[295789629] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"245.52317ms","start":"2026-03-19T17:46:13.213573Z","end":"2026-03-19T17:46:13.459097Z","steps":["trace[295789629] 'process raft request' (duration: 244.662585ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:14.06724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.497407ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-19T17:46:14.067408Z","caller":"traceutil/trace.go:171","msg":"trace[1236620702] transaction","detail":"{read_only:false; response_revision:60; number_of_response:1; }","duration":"290.541506ms","start":"2026-03-19T17:46:13.776837Z","end":"2026-03-19T17:46:14.067378Z","steps":["trace[1236620702] 'process raft request' (duration: 173.746541ms)","trace[1236620702] 'compare' (duration: 116.337612ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:14.067518Z","caller":"traceutil/trace.go:171","msg":"trace[2028502445] transaction","detail":"{read_only:false; response_revision:62; number_of_response:1; }","duration":"279.528405ms","start":"2026-03-19T17:46:13.787971Z","end":"2026-03-19T17:46:14.067499Z","steps":["trace[2028502445] 'process raft request' (duration: 279.480804ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:14.067537Z","caller":"traceutil/trace.go:171","msg":"trace[858248701] transaction","detail":"{read_only:false; response_revision:61; number_of_response:1; }","duration":"281.610495ms","start":"2026-03-19T17:46:13.785896Z","end":"2026-03-19T17:46:14.067506Z","steps":["trace[858248701] 'process raft request' (duration: 281.495322ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:14.067765Z","caller":"traceutil/trace.go:171","msg":"trace[573574954] linearizableReadLoop","detail":"{readStateIndex:64; appliedIndex:63; }","duration":"284.369827ms","start":"2026-03-19T17:46:13.783384Z","end":"2026-03-19T17:46:14.067754Z","steps":["trace[573574954] 'read index received' (duration: 167.273883ms)","trace[573574954] 'applied index is now lower than readState.Index' (duration: 117.094473ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:14.067939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.906598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-19T17:46:14.067977Z","caller":"traceutil/trace.go:171","msg":"trace[1667636090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:62; }","duration":"207.978251ms","start":"2026-03-19T17:46:13.859986Z","end":"2026-03-19T17:46:14.067964Z","steps":["trace[1667636090] 'agreement among raft nodes before linearized reading' (duration: 207.914979ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:14.067974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.795135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-19T17:46:14.068024Z","caller":"traceutil/trace.go:171","msg":"trace[1872598952] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:62; }","duration":"299.863017ms","start":"2026-03-19T17:46:13.768142Z","end":"2026-03-19T17:46:14.068005Z","steps":["trace[1872598952] 'agreement among raft nodes before linearized reading' (duration: 299.777094ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:14.068109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.182358ms","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-03-19T17:46:14.068166Z","caller":"traceutil/trace.go:171","msg":"trace[1618630727] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:62; }","duration":"139.292681ms","start":"2026-03-19T17:46:13.928851Z","end":"2026-03-19T17:46:14.068144Z","steps":["trace[1618630727] 'agreement among raft nodes before linearized reading' (duration: 139.198848ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:15.069987Z","caller":"traceutil/trace.go:171","msg":"trace[944769002] linearizableReadLoop","detail":"{readStateIndex:177; appliedIndex:176; }","duration":"110.44566ms","start":"2026-03-19T17:46:14.95951Z","end":"2026-03-19T17:46:15.069956Z","steps":["trace[944769002] 'read index received' (duration: 59.545914ms)","trace[944769002] 'applied index is now lower than readState.Index' (duration: 50.898836ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:15.070022Z","caller":"traceutil/trace.go:171","msg":"trace[767621014] transaction","detail":"{read_only:false; response_revision:172; number_of_response:1; }","duration":"116.564736ms","start":"2026-03-19T17:46:14.953423Z","end":"2026-03-19T17:46:15.069987Z","steps":["trace[767621014] 'process raft request' (duration: 65.723552ms)","trace[767621014] 'compare' (duration: 50.675559ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:15.070212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.650655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T17:46:15.070302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.520172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:basic-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:46:15.070394Z","caller":"traceutil/trace.go:171","msg":"trace[1683799541] range","detail":"{range_begin:/registry/clusterrolebindings/system:basic-user; range_end:; response_count:0; response_revision:172; }","duration":"109.621745ms","start":"2026-03-19T17:46:14.960723Z","end":"2026-03-19T17:46:15.070345Z","steps":["trace[1683799541] 'agreement among raft nodes before linearized reading' (duration: 109.506941ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:15.070405Z","caller":"traceutil/trace.go:171","msg":"trace[1433204112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:172; }","duration":"110.833191ms","start":"2026-03-19T17:46:14.959476Z","end":"2026-03-19T17:46:15.070309Z","steps":["trace[1433204112] 'agreement among raft nodes before linearized reading' (duration: 110.612114ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:15.665749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.242072ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:46:15.665949Z","caller":"traceutil/trace.go:171","msg":"trace[1918673802] transaction","detail":"{read_only:false; response_revision:201; number_of_response:1; }","duration":"155.884234ms","start":"2026-03-19T17:46:15.510044Z","end":"2026-03-19T17:46:15.665928Z","steps":["trace[1918673802] 'process raft request' (duration: 47.349033ms)","trace[1918673802] 'compare' (duration: 108.058647ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:15.95538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.715751ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:46:15.955511Z","caller":"traceutil/trace.go:171","msg":"trace[1307643277] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"237.123128ms","start":"2026-03-19T17:46:15.71836Z","end":"2026-03-19T17:46:15.955483Z","steps":["trace[1307643277] 'process raft request' (duration: 53.220701ms)","trace[1307643277] 'compare' (duration: 183.540486ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:16.66388Z","caller":"traceutil/trace.go:171","msg":"trace[1862905346] transaction","detail":"{read_only:false; response_revision:222; number_of_response:1; }","duration":"153.97946ms","start":"2026-03-19T17:46:16.509862Z","end":"2026-03-19T17:46:16.663841Z","steps":["trace[1862905346] 'process raft request' (duration: 72.957836ms)","trace[1862905346] 'compare' (duration: 80.827848ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:18.434749Z","caller":"traceutil/trace.go:171","msg":"trace[414538135] linearizableReadLoop","detail":"{readStateIndex:276; appliedIndex:275; }","duration":"162.781135ms","start":"2026-03-19T17:46:18.271937Z","end":"2026-03-19T17:46:18.434718Z","steps":["trace[414538135] 'read index received' (duration: 152.758836ms)","trace[414538135] 'applied index is now lower than readState.Index' (duration: 10.020689ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:18.434863Z","caller":"traceutil/trace.go:171","msg":"trace[1465556135] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"205.510744ms","start":"2026-03-19T17:46:18.229322Z","end":"2026-03-19T17:46:18.434832Z","steps":["trace[1465556135] 'process raft request' (duration: 195.316081ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:18.435085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.132045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-19T17:46:18.435174Z","caller":"traceutil/trace.go:171","msg":"trace[1164189696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:270; }","duration":"163.254898ms","start":"2026-03-19T17:46:18.271901Z","end":"2026-03-19T17:46:18.435155Z","steps":["trace[1164189696] 'agreement among raft nodes before linearized reading' (duration: 163.032032ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:18.435427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.914147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3708"} {"level":"info","ts":"2026-03-19T17:46:18.435517Z","caller":"traceutil/trace.go:171","msg":"trace[2078114498] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:270; }","duration":"141.073651ms","start":"2026-03-19T17:46:18.29442Z","end":"2026-03-19T17:46:18.435493Z","steps":["trace[2078114498] 'agreement among raft nodes before linearized reading' (duration: 140.752022ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:19.319617Z","caller":"traceutil/trace.go:171","msg":"trace[2081574773] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"161.509009ms","start":"2026-03-19T17:46:19.158082Z","end":"2026-03-19T17:46:19.319591Z","steps":["trace[2081574773] 'process raft request' (duration: 155.02473ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:19.342369Z","caller":"traceutil/trace.go:171","msg":"trace[90042523] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"181.987793ms","start":"2026-03-19T17:46:19.160355Z","end":"2026-03-19T17:46:19.342343Z","steps":["trace[90042523] 'process raft request' (duration: 181.773617ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:19.342764Z","caller":"traceutil/trace.go:171","msg":"trace[1730688052] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"139.885173ms","start":"2026-03-19T17:46:19.202837Z","end":"2026-03-19T17:46:19.342722Z","steps":["trace[1730688052] 'process raft request' (duration: 139.446801ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:19.3429Z","caller":"traceutil/trace.go:171","msg":"trace[549760057] transaction","detail":"{read_only:false; number_of_response:0; response_revision:300; }","duration":"124.247073ms","start":"2026-03-19T17:46:19.218624Z","end":"2026-03-19T17:46:19.342871Z","steps":["trace[549760057] 'process raft request' (duration: 124.11055ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:19.342994Z","caller":"traceutil/trace.go:171","msg":"trace[1204716452] transaction","detail":"{read_only:false; number_of_response:0; response_revision:300; }","duration":"123.908403ms","start":"2026-03-19T17:46:19.219051Z","end":"2026-03-19T17:46:19.342959Z","steps":["trace[1204716452] 'process raft request' (duration: 123.743178ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:19.342934Z","caller":"traceutil/trace.go:171","msg":"trace[1232471891] transaction","detail":"{read_only:false; number_of_response:0; response_revision:300; }","duration":"124.075857ms","start":"2026-03-19T17:46:19.218828Z","end":"2026-03-19T17:46:19.342904Z","steps":["trace[1232471891] 'process raft request' (duration: 123.935553ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:19.34286Z","caller":"traceutil/trace.go:171","msg":"trace[656309238] transaction","detail":"{read_only:false; number_of_response:0; response_revision:300; }","duration":"139.802511ms","start":"2026-03-19T17:46:19.203042Z","end":"2026-03-19T17:46:19.342845Z","steps":["trace[656309238] 'process raft request' (duration: 139.551974ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:19.343864Z","caller":"traceutil/trace.go:171","msg":"trace[415853568] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"124.624022ms","start":"2026-03-19T17:46:19.219221Z","end":"2026-03-19T17:46:19.343845Z","steps":["trace[415853568] 'process raft request' (duration: 123.615884ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:19.343859Z","caller":"traceutil/trace.go:171","msg":"trace[486824645] linearizableReadLoop","detail":"{readStateIndex:305; appliedIndex:303; }","duration":"166.401593ms","start":"2026-03-19T17:46:19.177417Z","end":"2026-03-19T17:46:19.343819Z","steps":["trace[486824645] 'read index received' (duration: 135.709427ms)","trace[486824645] 'applied index is now lower than readState.Index' (duration: 30.690066ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:19.344141Z","caller":"traceutil/trace.go:171","msg":"trace[1428646975] transaction","detail":"{read_only:false; number_of_response:0; response_revision:301; }","duration":"124.755295ms","start":"2026-03-19T17:46:19.219372Z","end":"2026-03-19T17:46:19.344127Z","steps":["trace[1428646975] 'process raft request' (duration: 124.354175ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:19.344268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.811874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-19T17:46:19.344336Z","caller":"traceutil/trace.go:171","msg":"trace[1723697246] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:301; }","duration":"166.934378ms","start":"2026-03-19T17:46:19.17738Z","end":"2026-03-19T17:46:19.344314Z","steps":["trace[1723697246] 'agreement among raft nodes before linearized reading' (duration: 166.754313ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:19.853535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.707552ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:46:19.853747Z","caller":"traceutil/trace.go:171","msg":"trace[948201647] linearizableReadLoop","detail":"{readStateIndex:326; appliedIndex:325; }","duration":"326.458722ms","start":"2026-03-19T17:46:19.527268Z","end":"2026-03-19T17:46:19.853727Z","steps":["trace[948201647] 'read index received' (duration: 86.373218ms)","trace[948201647] 'applied index is now lower than readState.Index' (duration: 240.084004ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:19.853862Z","caller":"traceutil/trace.go:171","msg":"trace[2043175279] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"343.215233ms","start":"2026-03-19T17:46:19.510606Z","end":"2026-03-19T17:46:19.853821Z","steps":["trace[2043175279] 'process raft request' (duration: 103.146281ms)","trace[2043175279] 'compare' (duration: 239.555718ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:19.854463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:19.510574Z","time spent":"343.808149ms","remote":"127.0.0.1:44686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":435,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T17:46:19.854074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:46:19.854757Z","caller":"traceutil/trace.go:171","msg":"trace[1189297851] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:0; response_revision:315; }","duration":"327.515901ms","start":"2026-03-19T17:46:19.527221Z","end":"2026-03-19T17:46:19.854737Z","steps":["trace[1189297851] 'agreement among raft nodes before linearized reading' (duration: 326.724319ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:19.854811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:19.527204Z","time spent":"327.581132ms","remote":"127.0.0.1:44558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"info","ts":"2026-03-19T17:46:20.360496Z","caller":"traceutil/trace.go:171","msg":"trace[628321431] transaction","detail":"{read_only:false; number_of_response:0; response_revision:319; }","duration":"221.408463ms","start":"2026-03-19T17:46:20.139057Z","end":"2026-03-19T17:46:20.360466Z","steps":["trace[628321431] 'process raft request' (duration: 221.231369ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:20.360568Z","caller":"traceutil/trace.go:171","msg":"trace[530959162] transaction","detail":"{read_only:false; number_of_response:0; response_revision:319; }","duration":"221.025543ms","start":"2026-03-19T17:46:20.139497Z","end":"2026-03-19T17:46:20.360523Z","steps":["trace[530959162] 'process raft request' (duration: 220.874679ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:20.360614Z","caller":"traceutil/trace.go:171","msg":"trace[2018477522] transaction","detail":"{read_only:false; number_of_response:0; response_revision:319; }","duration":"221.025803ms","start":"2026-03-19T17:46:20.13956Z","end":"2026-03-19T17:46:20.360586Z","steps":["trace[2018477522] 'process raft request' (duration: 220.838848ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:20.361022Z","caller":"traceutil/trace.go:171","msg":"trace[1594562780] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"206.391893ms","start":"2026-03-19T17:46:20.15462Z","end":"2026-03-19T17:46:20.361012Z","steps":["trace[1594562780] 'process raft request' (duration: 205.821358ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:20.36117Z","caller":"traceutil/trace.go:171","msg":"trace[1816397337] linearizableReadLoop","detail":"{readStateIndex:335; appliedIndex:330; }","duration":"184.237499ms","start":"2026-03-19T17:46:20.176643Z","end":"2026-03-19T17:46:20.36088Z","steps":["trace[1816397337] 'read index received' (duration: 14.721422ms)","trace[1816397337] 'applied index is now lower than readState.Index' (duration: 169.515087ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:20.361278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.62782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-19T17:46:20.361315Z","caller":"traceutil/trace.go:171","msg":"trace[760507620] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:320; }","duration":"184.727242ms","start":"2026-03-19T17:46:20.176573Z","end":"2026-03-19T17:46:20.361301Z","steps":["trace[760507620] 'agreement among raft nodes before linearized reading' (duration: 184.65167ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:20.361671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.7673ms","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-03-19T17:46:20.36172Z","caller":"traceutil/trace.go:171","msg":"trace[28216766] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:320; }","duration":"167.840761ms","start":"2026-03-19T17:46:20.193868Z","end":"2026-03-19T17:46:20.361709Z","steps":["trace[28216766] 'agreement among raft nodes before linearized reading' (duration: 167.704366ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:35.045211Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975766119366717426,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-19T17:46:35.119584Z","caller":"traceutil/trace.go:171","msg":"trace[1931148963] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:480; }","duration":"574.916432ms","start":"2026-03-19T17:46:34.54464Z","end":"2026-03-19T17:46:35.119556Z","steps":["trace[1931148963] 'read index received' (duration: 574.793709ms)","trace[1931148963] 'applied index is now lower than readState.Index' (duration: 121.473µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:35.119676Z","caller":"traceutil/trace.go:171","msg":"trace[547976520] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"888.178917ms","start":"2026-03-19T17:46:34.231469Z","end":"2026-03-19T17:46:35.119647Z","steps":["trace[547976520] 'process raft request' (duration: 887.934212ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:35.119801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.129357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-19T17:46:35.119845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.059219ms","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-03-19T17:46:35.119901Z","caller":"traceutil/trace.go:171","msg":"trace[1392298688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:461; }","duration":"575.309462ms","start":"2026-03-19T17:46:34.54457Z","end":"2026-03-19T17:46:35.11988Z","steps":["trace[1392298688] 'agreement among raft nodes before linearized reading' (duration: 575.135508ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:35.11993Z","caller":"traceutil/trace.go:171","msg":"trace[735193741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:461; }","duration":"246.184792ms","start":"2026-03-19T17:46:34.873727Z","end":"2026-03-19T17:46:35.119912Z","steps":["trace[735193741] 'agreement among raft nodes before linearized reading' (duration: 246.034488ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:35.119965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:34.544547Z","time spent":"575.403274ms","remote":"127.0.0.1:44632","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-03-19T17:46:35.120068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:34.231439Z","time spent":"888.323241ms","remote":"127.0.0.1:44632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:46:39.970002Z","caller":"traceutil/trace.go:171","msg":"trace[456725530] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"181.127288ms","start":"2026-03-19T17:46:39.788856Z","end":"2026-03-19T17:46:39.969983Z","steps":["trace[456725530] 'process raft request' (duration: 181.011636ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:41.682479Z","caller":"traceutil/trace.go:171","msg":"trace[1010156420] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"137.904393ms","start":"2026-03-19T17:46:41.544525Z","end":"2026-03-19T17:46:41.682429Z","steps":["trace[1010156420] 'process raft request' (duration: 137.628097ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:42.021923Z","caller":"traceutil/trace.go:171","msg":"trace[2117549756] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"168.707856ms","start":"2026-03-19T17:46:41.853193Z","end":"2026-03-19T17:46:42.021901Z","steps":["trace[2117549756] 'process raft request' (duration: 168.664714ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:42.022023Z","caller":"traceutil/trace.go:171","msg":"trace[1888115907] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"169.060414ms","start":"2026-03-19T17:46:41.852929Z","end":"2026-03-19T17:46:42.021989Z","steps":["trace[1888115907] 'process raft request' (duration: 147.412901ms)","trace[1888115907] 'compare' (duration: 21.334875ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:42.482921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.120791ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:46:42.483076Z","caller":"traceutil/trace.go:171","msg":"trace[508743256] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"236.71203ms","start":"2026-03-19T17:46:42.246338Z","end":"2026-03-19T17:46:42.48305Z","steps":["trace[508743256] 'process raft request' (duration: 47.363714ms)","trace[508743256] 'compare' (duration: 188.928737ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:42.485505Z","caller":"traceutil/trace.go:171","msg":"trace[692974322] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"238.309277ms","start":"2026-03-19T17:46:42.247173Z","end":"2026-03-19T17:46:42.485482Z","steps":["trace[692974322] 'process raft request' (duration: 238.204174ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:42.706088Z","caller":"traceutil/trace.go:171","msg":"trace[53770563] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"170.811526ms","start":"2026-03-19T17:46:42.535247Z","end":"2026-03-19T17:46:42.706059Z","steps":["trace[53770563] 'process raft request' (duration: 170.474469ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:42.706113Z","caller":"traceutil/trace.go:171","msg":"trace[697178323] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"170.701484ms","start":"2026-03-19T17:46:42.535375Z","end":"2026-03-19T17:46:42.706076Z","steps":["trace[697178323] 'process raft request' (duration: 170.54877ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:42.706738Z","caller":"traceutil/trace.go:171","msg":"trace[422054117] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"167.993622ms","start":"2026-03-19T17:46:42.538722Z","end":"2026-03-19T17:46:42.706716Z","steps":["trace[422054117] 'process raft request' (duration: 167.275436ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:42.707096Z","caller":"traceutil/trace.go:171","msg":"trace[1586144405] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"121.846495ms","start":"2026-03-19T17:46:42.585233Z","end":"2026-03-19T17:46:42.707079Z","steps":["trace[1586144405] 'process raft request' (duration: 121.305173ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:43.078446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.82761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:46:43.079281Z","caller":"traceutil/trace.go:171","msg":"trace[430236048] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"364.14417ms","start":"2026-03-19T17:46:42.715104Z","end":"2026-03-19T17:46:43.079248Z","steps":["trace[430236048] 'process raft request' (duration: 364.067138ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:43.079361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:42.71508Z","time spent":"364.251751ms","remote":"127.0.0.1:44422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":671,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T17:46:43.079873Z","caller":"traceutil/trace.go:171","msg":"trace[23436078] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"460.586968ms","start":"2026-03-19T17:46:42.619245Z","end":"2026-03-19T17:46:43.079832Z","steps":["trace[23436078] 'process raft request' (duration: 459.304849ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:43.08026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:42.619224Z","time spent":"460.932485ms","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3622,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:46:43.080255Z","caller":"traceutil/trace.go:171","msg":"trace[283588290] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"460.623898ms","start":"2026-03-19T17:46:42.619203Z","end":"2026-03-19T17:46:43.079827Z","steps":["trace[283588290] 'process raft request' (duration: 230.344917ms)","trace[283588290] 'compare' (duration: 228.597914ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:43.080362Z","caller":"traceutil/trace.go:171","msg":"trace[1905181522] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"458.449729ms","start":"2026-03-19T17:46:42.621898Z","end":"2026-03-19T17:46:43.080347Z","steps":["trace[1905181522] 'process raft request' (duration: 457.186781ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:43.080429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:42.621887Z","time spent":"458.50113ms","remote":"127.0.0.1:44846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4294,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:46:43.08033Z","caller":"traceutil/trace.go:171","msg":"trace[572715638] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:519; }","duration":"459.024902ms","start":"2026-03-19T17:46:42.621234Z","end":"2026-03-19T17:46:43.080259Z","steps":["trace[572715638] 'read index received' (duration: 84.505418ms)","trace[572715638] 'applied index is now lower than readState.Index' (duration: 374.152596ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:43.08044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:42.619182Z","time spent":"461.15853ms","remote":"127.0.0.1:44422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":736,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T17:46:43.080737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.485513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"info","ts":"2026-03-19T17:46:43.080806Z","caller":"traceutil/trace.go:171","msg":"trace[1155728063] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:505; }","duration":"459.575004ms","start":"2026-03-19T17:46:42.621199Z","end":"2026-03-19T17:46:43.080774Z","steps":["trace[1155728063] 'agreement among raft nodes before linearized reading' (duration: 459.203856ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:43.080852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:42.621192Z","time spent":"459.649837ms","remote":"127.0.0.1:44832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":4359,"request content":"key:\"/registry/deployments/kube-system/cilium-operator\" "} {"level":"warn","ts":"2026-03-19T17:46:43.08099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.200882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7sjz8\" ","response":"range_response_count:1 size:15076"} {"level":"info","ts":"2026-03-19T17:46:43.081042Z","caller":"traceutil/trace.go:171","msg":"trace[1797831606] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7sjz8; range_end:; response_count:1; response_revision:505; }","duration":"368.280254ms","start":"2026-03-19T17:46:42.712736Z","end":"2026-03-19T17:46:43.081016Z","steps":["trace[1797831606] 'agreement among raft nodes before linearized reading' (duration: 368.140831ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:43.081068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:42.712718Z","time spent":"368.343315ms","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":15100,"request content":"key:\"/registry/pods/kube-system/cilium-7sjz8\" "} {"level":"warn","ts":"2026-03-19T17:46:43.081066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.412118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-03-19T17:46:43.081132Z","caller":"traceutil/trace.go:171","msg":"trace[284790263] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:505; }","duration":"220.544911ms","start":"2026-03-19T17:46:42.860569Z","end":"2026-03-19T17:46:43.081114Z","steps":["trace[284790263] 'agreement among raft nodes before linearized reading' (duration: 220.364667ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:43.083119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.553073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329956"} {"level":"info","ts":"2026-03-19T17:46:43.083188Z","caller":"traceutil/trace.go:171","msg":"trace[400165075] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:505; }","duration":"299.673486ms","start":"2026-03-19T17:46:42.78349Z","end":"2026-03-19T17:46:43.083164Z","steps":["trace[400165075] 'agreement among raft nodes before linearized reading' (duration: 297.528329ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:43.667753Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975766119366717520,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T17:46:43.769274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:43.324441Z","time spent":"444.821886ms","remote":"127.0.0.1:35016","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-19T17:46:43.769439Z","caller":"traceutil/trace.go:171","msg":"trace[1528152736] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"682.380174ms","start":"2026-03-19T17:46:43.087026Z","end":"2026-03-19T17:46:43.769406Z","steps":["trace[1528152736] 'process raft request' (duration: 680.075952ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:43.769576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:43.087003Z","time spent":"682.489927ms","remote":"127.0.0.1:44422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":751,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T17:46:43.774338Z","caller":"traceutil/trace.go:171","msg":"trace[1385391890] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"683.545991ms","start":"2026-03-19T17:46:43.090764Z","end":"2026-03-19T17:46:43.77431Z","steps":["trace[1385391890] 'process raft request' (duration: 683.353797ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:43.774371Z","caller":"traceutil/trace.go:171","msg":"trace[1257484508] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:527; }","duration":"606.878916ms","start":"2026-03-19T17:46:43.167472Z","end":"2026-03-19T17:46:43.774351Z","steps":["trace[1257484508] 'read index received' (duration: 599.581241ms)","trace[1257484508] 'applied index is now lower than readState.Index' (duration: 7.296425ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:43.774515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:43.090752Z","time spent":"683.667054ms","remote":"127.0.0.1:44832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4617,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:46:43.774663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.184752ms","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-03-19T17:46:43.77474Z","caller":"traceutil/trace.go:171","msg":"trace[310665171] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:509; }","duration":"607.296604ms","start":"2026-03-19T17:46:43.167431Z","end":"2026-03-19T17:46:43.774727Z","steps":["trace[310665171] 'agreement among raft nodes before linearized reading' (duration: 607.188122ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:43.774774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:43.167412Z","time spent":"607.354566ms","remote":"127.0.0.1:44632","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-03-19T17:46:43.774647Z","caller":"traceutil/trace.go:171","msg":"trace[1352243653] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"683.592512ms","start":"2026-03-19T17:46:43.091047Z","end":"2026-03-19T17:46:43.77464Z","steps":["trace[1352243653] 'process raft request' (duration: 683.176863ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:43.774909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:43.091034Z","time spent":"683.844868ms","remote":"127.0.0.1:44846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4339,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:46:43.775082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.262672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-19T17:46:43.775102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.50221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"warn","ts":"2026-03-19T17:46:43.775126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.55145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-19T17:46:43.775152Z","caller":"traceutil/trace.go:171","msg":"trace[856738133] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:509; }","duration":"512.591431ms","start":"2026-03-19T17:46:43.262546Z","end":"2026-03-19T17:46:43.775138Z","steps":["trace[856738133] 'agreement among raft nodes before linearized reading' (duration: 512.492649ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:43.775239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:43.262525Z","time spent":"512.703844ms","remote":"127.0.0.1:44558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":562,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" "} {"level":"info","ts":"2026-03-19T17:46:43.775164Z","caller":"traceutil/trace.go:171","msg":"trace[1245548295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:509; }","duration":"603.598291ms","start":"2026-03-19T17:46:43.171556Z","end":"2026-03-19T17:46:43.775154Z","steps":["trace[1245548295] 'agreement among raft nodes before linearized reading' (duration: 603.524709ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:43.775307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:43.171551Z","time spent":"603.747714ms","remote":"127.0.0.1:44632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-19T17:46:43.775506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.031779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329956"} {"level":"info","ts":"2026-03-19T17:46:43.77562Z","caller":"traceutil/trace.go:171","msg":"trace[940256687] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:509; }","duration":"596.162023ms","start":"2026-03-19T17:46:43.179431Z","end":"2026-03-19T17:46:43.775593Z","steps":["trace[940256687] 'agreement among raft nodes before linearized reading' (duration: 595.641951ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:43.775678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:43.179403Z","time spent":"596.264796ms","remote":"127.0.0.1:44442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329980,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"info","ts":"2026-03-19T17:46:43.775743Z","caller":"traceutil/trace.go:171","msg":"trace[528743907] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"679.602573ms","start":"2026-03-19T17:46:43.096118Z","end":"2026-03-19T17:46:43.775721Z","steps":["trace[528743907] 'process raft request' (duration: 678.16914ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:43.775152Z","caller":"traceutil/trace.go:171","msg":"trace[1343159234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:509; }","duration":"607.352925ms","start":"2026-03-19T17:46:43.167779Z","end":"2026-03-19T17:46:43.775132Z","steps":["trace[1343159234] 'agreement among raft nodes before linearized reading' (duration: 607.210432ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:43.775919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:43.167773Z","time spent":"608.128493ms","remote":"127.0.0.1:44632","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-03-19T17:46:43.778533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:43.096092Z","time spent":"679.696515ms","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16946,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:46:45.644087Z","caller":"traceutil/trace.go:171","msg":"trace[437432982] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"311.020458ms","start":"2026-03-19T17:46:45.333042Z","end":"2026-03-19T17:46:45.644062Z","steps":["trace[437432982] 'process raft request' (duration: 310.855734ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:45.644241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:45.333015Z","time spent":"311.146521ms","remote":"127.0.0.1:44632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:46:45.858979Z","caller":"traceutil/trace.go:171","msg":"trace[1931115374] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"190.653528ms","start":"2026-03-19T17:46:45.668308Z","end":"2026-03-19T17:46:45.858961Z","steps":["trace[1931115374] 'process raft request' (duration: 190.176718ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:52.026908Z","caller":"traceutil/trace.go:171","msg":"trace[1509573035] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"131.450356ms","start":"2026-03-19T17:46:51.895433Z","end":"2026-03-19T17:46:52.026883Z","steps":["trace[1509573035] 'process raft request' (duration: 131.184971ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:52.415384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.453054ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:46:52.415716Z","caller":"traceutil/trace.go:171","msg":"trace[962259100] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"519.931501ms","start":"2026-03-19T17:46:51.895753Z","end":"2026-03-19T17:46:52.415684Z","steps":["trace[962259100] 'process raft request' (duration: 328.071369ms)","trace[962259100] 'compare' (duration: 191.298881ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:52.415745Z","caller":"traceutil/trace.go:171","msg":"trace[1978906465] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"519.826739ms","start":"2026-03-19T17:46:51.8959Z","end":"2026-03-19T17:46:52.415726Z","steps":["trace[1978906465] 'process raft request' (duration: 519.706167ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:52.415823Z","caller":"traceutil/trace.go:171","msg":"trace[869300346] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:581; }","duration":"505.029915ms","start":"2026-03-19T17:46:51.910772Z","end":"2026-03-19T17:46:52.415802Z","steps":["trace[869300346] 'read index received' (duration: 115.978828ms)","trace[869300346] 'applied index is now lower than readState.Index' (duration: 389.049207ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:52.415837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:51.895732Z","time spent":"520.050744ms","remote":"127.0.0.1:44632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:46:52.415846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:51.895885Z","time spent":"519.8983ms","remote":"127.0.0.1:44632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:46:52.416017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.237549ms","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-03-19T17:46:52.416046Z","caller":"traceutil/trace.go:171","msg":"trace[865760591] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:561; }","duration":"505.306192ms","start":"2026-03-19T17:46:51.910728Z","end":"2026-03-19T17:46:52.416034Z","steps":["trace[865760591] 'agreement among raft nodes before linearized reading' (duration: 505.178869ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:52.416067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:51.910715Z","time spent":"505.347042ms","remote":"127.0.0.1:44632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-19T17:46:52.53342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.71161ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-19T17:46:52.533533Z","caller":"traceutil/trace.go:171","msg":"trace[1105749606] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:584; }","duration":"117.639799ms","start":"2026-03-19T17:46:52.415873Z","end":"2026-03-19T17:46:52.533513Z","steps":["trace[1105749606] 'read index received' (duration: 110.923µs)","trace[1105749606] 'applied index is now lower than readState.Index' (duration: 117.527506ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:52.533712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.740561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7sjz8\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-03-19T17:46:52.533751Z","caller":"traceutil/trace.go:171","msg":"trace[422052047] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7sjz8; range_end:; response_count:1; response_revision:561; }","duration":"313.819711ms","start":"2026-03-19T17:46:52.219921Z","end":"2026-03-19T17:46:52.53374Z","steps":["trace[422052047] 'agreement among raft nodes before linearized reading' (duration: 313.645108ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:52.533785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:52.219901Z","time spent":"313.876723ms","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-7sjz8\" "} {"level":"info","ts":"2026-03-19T17:46:52.84375Z","caller":"traceutil/trace.go:171","msg":"trace[1259929324] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"423.011974ms","start":"2026-03-19T17:46:52.420715Z","end":"2026-03-19T17:46:52.843727Z","steps":["trace[1259929324] 'process raft request' (duration: 422.842389ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:52.843904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:52.420694Z","time spent":"423.128676ms","remote":"127.0.0.1:44632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:46:53.529238Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7975766119366717631,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-19T17:46:53.783095Z","caller":"traceutil/trace.go:171","msg":"trace[625674795] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:586; }","duration":"754.554046ms","start":"2026-03-19T17:46:53.028519Z","end":"2026-03-19T17:46:53.783073Z","steps":["trace[625674795] 'read index received' (duration: 754.347032ms)","trace[625674795] 'applied index is now lower than readState.Index' (duration: 206.184µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:53.783213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.681169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:46:53.783246Z","caller":"traceutil/trace.go:171","msg":"trace[1477461284] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:563; }","duration":"754.765362ms","start":"2026-03-19T17:46:53.02847Z","end":"2026-03-19T17:46:53.783235Z","steps":["trace[1477461284] 'agreement among raft nodes before linearized reading' (duration: 754.69402ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:53.78328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:53.028449Z","time spent":"754.823632ms","remote":"127.0.0.1:37456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-19T17:46:53.783443Z","caller":"traceutil/trace.go:171","msg":"trace[1615003950] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"1.23612667s","start":"2026-03-19T17:46:52.547248Z","end":"2026-03-19T17:46:53.783374Z","steps":["trace[1615003950] 'process raft request' (duration: 1.235683881s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:53.78383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:52.547222Z","time spent":"1.236455007s","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:46:54.960235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.713423ms","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-03-19T17:46:54.960317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.328834ms","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-03-19T17:46:54.960345Z","caller":"traceutil/trace.go:171","msg":"trace[1348683746] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:563; }","duration":"539.878627ms","start":"2026-03-19T17:46:54.420439Z","end":"2026-03-19T17:46:54.960317Z","steps":["trace[1348683746] 'range keys from in-memory index tree' (duration: 539.416337ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:54.960343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.227772ms","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-03-19T17:46:54.960375Z","caller":"traceutil/trace.go:171","msg":"trace[97966637] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:563; }","duration":"112.444896ms","start":"2026-03-19T17:46:54.847916Z","end":"2026-03-19T17:46:54.960361Z","steps":["trace[97966637] 'range keys from in-memory index tree' (duration: 112.15439ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:54.960406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:54.420418Z","time spent":"539.975279ms","remote":"127.0.0.1:44632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-19T17:46:54.960411Z","caller":"traceutil/trace.go:171","msg":"trace[269340995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:563; }","duration":"539.355665ms","start":"2026-03-19T17:46:54.421038Z","end":"2026-03-19T17:46:54.960394Z","steps":["trace[269340995] 'range keys from in-memory index tree' (duration: 539.025638ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:54.960451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:54.421007Z","time spent":"539.436237ms","remote":"127.0.0.1:44632","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-03-19T17:46:54.960591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.030463ms","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-03-19T17:46:54.960614Z","caller":"traceutil/trace.go:171","msg":"trace[192453225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:563; }","duration":"930.053483ms","start":"2026-03-19T17:46:54.030554Z","end":"2026-03-19T17:46:54.960608Z","steps":["trace[192453225] 'range keys from in-memory index tree' (duration: 929.92895ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:54.960634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:54.030495Z","time spent":"930.133004ms","remote":"127.0.0.1:44632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-19T17:46:55.076302Z","caller":"traceutil/trace.go:171","msg":"trace[359780787] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"111.459776ms","start":"2026-03-19T17:46:54.964818Z","end":"2026-03-19T17:46:55.076277Z","steps":["trace[359780787] 'process raft request' (duration: 111.322582ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:56.272054Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.195993868s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-19T17:46:56.48196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.610496ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:46:56.482173Z","caller":"traceutil/trace.go:171","msg":"trace[562138573] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:587; }","duration":"1.452985119s","start":"2026-03-19T17:46:55.029172Z","end":"2026-03-19T17:46:56.482157Z","steps":["trace[562138573] 'read index received' (duration: 46.950398ms)","trace[562138573] 'applied index is now lower than readState.Index' (duration: 1.406033811s)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:56.482247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.453078691s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:46:56.48227Z","caller":"traceutil/trace.go:171","msg":"trace[749595401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:567; }","duration":"1.453145833s","start":"2026-03-19T17:46:55.029116Z","end":"2026-03-19T17:46:56.482262Z","steps":["trace[749595401] 'agreement among raft nodes before linearized reading' (duration: 1.453086181s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:56.482301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:55.029094Z","time spent":"1.453198714s","remote":"127.0.0.1:37456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-19T17:46:56.482479Z","caller":"traceutil/trace.go:171","msg":"trace[1008318100] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"1.516621109s","start":"2026-03-19T17:46:54.965829Z","end":"2026-03-19T17:46:56.48245Z","steps":["trace[1008318100] 'process raft request' (duration: 1.516218381s)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:56.482513Z","caller":"traceutil/trace.go:171","msg":"trace[404527312] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"1.516361314s","start":"2026-03-19T17:46:54.966124Z","end":"2026-03-19T17:46:56.482486Z","steps":["trace[404527312] 'process raft request' (duration: 1.515999097s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:56.482672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:54.966097Z","time spent":"1.516482177s","remote":"127.0.0.1:44632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:46:56.482979Z","caller":"traceutil/trace.go:171","msg":"trace[332561991] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"1.517921677s","start":"2026-03-19T17:46:54.965043Z","end":"2026-03-19T17:46:56.482965Z","steps":["trace[332561991] 'process raft request' (duration: 1.307217208s)","trace[332561991] 'compare' (duration: 209.206328ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:56.483046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:54.965034Z","time spent":"1.517974578s","remote":"127.0.0.1:44632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:46:56.482633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:54.965805Z","time spent":"1.516729011s","remote":"127.0.0.1:44632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:46:56.603178Z","caller":"traceutil/trace.go:171","msg":"trace[39426091] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"119.848175ms","start":"2026-03-19T17:46:56.483307Z","end":"2026-03-19T17:46:56.603155Z","steps":["trace[39426091] 'read index received' (duration: 111.75737ms)","trace[39426091] 'applied index is now lower than readState.Index' (duration: 8.089875ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:56.603311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.979579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:46:56.603294Z","caller":"traceutil/trace.go:171","msg":"trace[1591095377] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"765.701303ms","start":"2026-03-19T17:46:55.837557Z","end":"2026-03-19T17:46:56.603258Z","steps":["trace[1591095377] 'process raft request' (duration: 757.576197ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:56.603343Z","caller":"traceutil/trace.go:171","msg":"trace[161327863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:568; }","duration":"120.06048ms","start":"2026-03-19T17:46:56.483273Z","end":"2026-03-19T17:46:56.603333Z","steps":["trace[161327863] 'agreement among raft nodes before linearized reading' (duration: 119.987698ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:56.603482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:55.837533Z","time spent":"765.840047ms","remote":"127.0.0.1:44632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:46:58.80615Z","caller":"traceutil/trace.go:171","msg":"trace[127543560] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"319.312765ms","start":"2026-03-19T17:46:58.486813Z","end":"2026-03-19T17:46:58.806126Z","steps":["trace[127543560] 'read index received' (duration: 319.229883ms)","trace[127543560] 'applied index is now lower than readState.Index' (duration: 81.952µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:58.806194Z","caller":"traceutil/trace.go:171","msg":"trace[2128818794] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"444.718059ms","start":"2026-03-19T17:46:58.361446Z","end":"2026-03-19T17:46:58.806164Z","steps":["trace[2128818794] 'process raft request' (duration: 444.523615ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:58.806352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:58.361432Z","time spent":"444.859633ms","remote":"127.0.0.1:44382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T17:46:58.806392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.56713ms","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-03-19T17:46:58.806474Z","caller":"traceutil/trace.go:171","msg":"trace[568596494] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:595; }","duration":"319.693712ms","start":"2026-03-19T17:46:58.486767Z","end":"2026-03-19T17:46:58.806461Z","steps":["trace[568596494] 'agreement among raft nodes before linearized reading' (duration: 319.461948ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:58.806517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:58.486745Z","time spent":"319.750813ms","remote":"127.0.0.1:44632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-19T17:46:58.811523Z","caller":"traceutil/trace.go:171","msg":"trace[1474796914] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"248.429292ms","start":"2026-03-19T17:46:58.563085Z","end":"2026-03-19T17:46:58.811514Z","steps":["trace[1474796914] 'process raft request' (duration: 248.253049ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:58.811615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.726444ms","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-03-19T17:46:58.811653Z","caller":"traceutil/trace.go:171","msg":"trace[1807673204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:597; }","duration":"324.803465ms","start":"2026-03-19T17:46:58.486841Z","end":"2026-03-19T17:46:58.811644Z","steps":["trace[1807673204] 'agreement among raft nodes before linearized reading' (duration: 324.632502ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:58.811678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:58.486818Z","time spent":"324.855137ms","remote":"127.0.0.1:44632","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-03-19T17:46:58.811749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.744875ms","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-03-19T17:46:58.811802Z","caller":"traceutil/trace.go:171","msg":"trace[1449816243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:597; }","duration":"324.837566ms","start":"2026-03-19T17:46:58.486953Z","end":"2026-03-19T17:46:58.811791Z","steps":["trace[1449816243] 'agreement among raft nodes before linearized reading' (duration: 324.674763ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:58.811834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:58.486939Z","time spent":"324.888647ms","remote":"127.0.0.1:44632","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":"info","ts":"2026-03-19T17:47:08.858279Z","caller":"traceutil/trace.go:171","msg":"trace[545438088] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"204.796201ms","start":"2026-03-19T17:47:08.653459Z","end":"2026-03-19T17:47:08.858255Z","steps":["trace[545438088] 'process raft request' (duration: 174.523327ms)","trace[545438088] 'compare' (duration: 29.909608ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:47:15.048164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.108272ms","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-03-19T17:47:15.048257Z","caller":"traceutil/trace.go:171","msg":"trace[144408812] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:766; }","duration":"114.248455ms","start":"2026-03-19T17:47:14.933989Z","end":"2026-03-19T17:47:15.048238Z","steps":["trace[144408812] 'range keys from in-memory index tree' (duration: 113.935739ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:15.048427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.686491ms","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-03-19T17:47:15.048485Z","caller":"traceutil/trace.go:171","msg":"trace[787411462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:766; }","duration":"115.788592ms","start":"2026-03-19T17:47:14.932684Z","end":"2026-03-19T17:47:15.048473Z","steps":["trace[787411462] 'range keys from in-memory index tree' (duration: 115.538268ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:16.237403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.538681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:47:16.237488Z","caller":"traceutil/trace.go:171","msg":"trace[1188418559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:769; }","duration":"208.626362ms","start":"2026-03-19T17:47:16.028842Z","end":"2026-03-19T17:47:16.237469Z","steps":["trace[1188418559] 'range keys from in-memory index tree' (duration: 208.466509ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:47:16.947001Z","caller":"traceutil/trace.go:171","msg":"trace[1828490432] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"120.0066ms","start":"2026-03-19T17:47:16.826972Z","end":"2026-03-19T17:47:16.946979Z","steps":["trace[1828490432] 'process raft request' (duration: 119.061813ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:48:07.896903Z","caller":"traceutil/trace.go:171","msg":"trace[1131655251] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"186.762175ms","start":"2026-03-19T17:48:07.710123Z","end":"2026-03-19T17:48:07.896885Z","steps":["trace[1131655251] 'process raft request' (duration: 186.653103ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:48:11.950269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.629646ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:48:11.950394Z","caller":"traceutil/trace.go:171","msg":"trace[972714716] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:988; }","duration":"114.678132ms","start":"2026-03-19T17:48:11.835699Z","end":"2026-03-19T17:48:11.950377Z","steps":["trace[972714716] 'read index received' (duration: 76.511µs)","trace[972714716] 'applied index is now lower than readState.Index' (duration: 114.600471ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:48:11.950511Z","caller":"traceutil/trace.go:171","msg":"trace[2089494562] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"231.430382ms","start":"2026-03-19T17:48:11.719018Z","end":"2026-03-19T17:48:11.950448Z","steps":["trace[2089494562] 'process raft request' (duration: 94.523812ms)","trace[2089494562] 'compare' (duration: 136.505094ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:48:11.950857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.154388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-32c8a3f8-6184-4ec1-886f-b941255e5854\" ","response":"range_response_count:1 size:3578"} {"level":"info","ts":"2026-03-19T17:48:11.95091Z","caller":"traceutil/trace.go:171","msg":"trace[168838990] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-32c8a3f8-6184-4ec1-886f-b941255e5854; range_end:; response_count:1; response_revision:946; }","duration":"115.214489ms","start":"2026-03-19T17:48:11.835683Z","end":"2026-03-19T17:48:11.950897Z","steps":["trace[168838990] 'agreement among raft nodes before linearized reading' (duration: 114.777803ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:48:29.024Z","caller":"traceutil/trace.go:171","msg":"trace[1704384578] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"166.79251ms","start":"2026-03-19T17:48:28.857181Z","end":"2026-03-19T17:48:29.023973Z","steps":["trace[1704384578] 'process raft request' (duration: 166.643118ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:48:33.545365Z","caller":"traceutil/trace.go:171","msg":"trace[2046095750] linearizableReadLoop","detail":"{readStateIndex:1082; appliedIndex:1081; }","duration":"136.434873ms","start":"2026-03-19T17:48:33.408902Z","end":"2026-03-19T17:48:33.545337Z","steps":["trace[2046095750] 'read index received' (duration: 136.260891ms)","trace[2046095750] 'applied index is now lower than readState.Index' (duration: 172.912µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:48:33.545472Z","caller":"traceutil/trace.go:171","msg":"trace[912116437] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"166.837105ms","start":"2026-03-19T17:48:33.378598Z","end":"2026-03-19T17:48:33.545435Z","steps":["trace[912116437] 'process raft request' (duration: 166.592142ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:48:33.545576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.657526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:1 size:840"} {"level":"info","ts":"2026-03-19T17:48:33.545642Z","caller":"traceutil/trace.go:171","msg":"trace[1221395619] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:1; response_revision:1033; }","duration":"136.816518ms","start":"2026-03-19T17:48:33.408812Z","end":"2026-03-19T17:48:33.545628Z","steps":["trace[1221395619] 'agreement among raft nodes before linearized reading' (duration: 136.665016ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:48:33.545543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.230488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:1 size:2025"} {"level":"info","ts":"2026-03-19T17:48:33.545878Z","caller":"traceutil/trace.go:171","msg":"trace[817199857] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:1; response_revision:1033; }","duration":"121.577082ms","start":"2026-03-19T17:48:33.424286Z","end":"2026-03-19T17:48:33.545863Z","steps":["trace[817199857] 'agreement among raft nodes before linearized reading' (duration: 121.192377ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:48:33.714799Z","caller":"traceutil/trace.go:171","msg":"trace[1286261282] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"134.670338ms","start":"2026-03-19T17:48:33.580093Z","end":"2026-03-19T17:48:33.714763Z","steps":["trace[1286261282] 'process raft request' (duration: 128.87408ms)"],"step_count":1}