{"level":"warn","ts":"2026-07-14T06:39:34.064609Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-14T06:39:34.064721Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.57: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.57:2380","--initial-cluster=instance=https://199.19.213.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.57: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-07-14T06:39:34.06481Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-14T06:39:34.064836Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.57:2380"]} {"level":"info","ts":"2026-07-14T06:39:34.064874Z","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-07-14T06:39:34.065914Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.57:2379"]} {"level":"info","ts":"2026-07-14T06:39:34.066038Z","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.57:2380"],"listen-peer-urls":["https://199.19.213.57:2380"],"advertise-client-urls":["https://199.19.213.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.57: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-07-14T06:39:34.070305Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.950327ms"} {"level":"info","ts":"2026-07-14T06:39:34.079743Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ef5ef2a9544a39ef","cluster-id":"58d35553a3d286c0"} {"level":"info","ts":"2026-07-14T06:39:34.079851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef switched to configuration voters=()"} {"level":"info","ts":"2026-07-14T06:39:34.079884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef became follower at term 0"} {"level":"info","ts":"2026-07-14T06:39:34.0799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ef5ef2a9544a39ef [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-14T06:39:34.079918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef became follower at term 1"} {"level":"info","ts":"2026-07-14T06:39:34.079973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef switched to configuration voters=(17248490431953123823)"} {"level":"warn","ts":"2026-07-14T06:39:34.089771Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-14T06:39:34.091275Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-14T06:39:34.094713Z","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-07-14T06:39:34.097691Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ef5ef2a9544a39ef","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-14T06:39:34.097863Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ef5ef2a9544a39ef","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-14T06:39:34.102345Z","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-07-14T06:39:34.103194Z","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-07-14T06:39:34.103223Z","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-07-14T06:39:34.103674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef switched to configuration voters=(17248490431953123823)"} {"level":"info","ts":"2026-07-14T06:39:34.104083Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"58d35553a3d286c0","local-member-id":"ef5ef2a9544a39ef","added-peer-id":"ef5ef2a9544a39ef","added-peer-peer-urls":["https://199.19.213.57:2380"]} {"level":"info","ts":"2026-07-14T06:39:34.107701Z","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-07-14T06:39:34.107877Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ef5ef2a9544a39ef","initial-advertise-peer-urls":["https://199.19.213.57:2380"],"listen-peer-urls":["https://199.19.213.57:2380"],"advertise-client-urls":["https://199.19.213.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-14T06:39:34.107901Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-14T06:39:34.107972Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.57:2380"} {"level":"info","ts":"2026-07-14T06:39:34.107981Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.57:2380"} {"level":"info","ts":"2026-07-14T06:39:34.586324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef is starting a new election at term 1"} {"level":"info","ts":"2026-07-14T06:39:34.586404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef became pre-candidate at term 1"} {"level":"info","ts":"2026-07-14T06:39:34.586444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef received MsgPreVoteResp from ef5ef2a9544a39ef at term 1"} {"level":"info","ts":"2026-07-14T06:39:34.586462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef became candidate at term 2"} {"level":"info","ts":"2026-07-14T06:39:34.58647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef received MsgVoteResp from ef5ef2a9544a39ef at term 2"} {"level":"info","ts":"2026-07-14T06:39:34.586482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef became leader at term 2"} {"level":"info","ts":"2026-07-14T06:39:34.586498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ef5ef2a9544a39ef elected leader ef5ef2a9544a39ef at term 2"} {"level":"info","ts":"2026-07-14T06:39:34.594071Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T06:39:34.594294Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ef5ef2a9544a39ef","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.57:2379]}","request-path":"/0/members/ef5ef2a9544a39ef/attributes","cluster-id":"58d35553a3d286c0","publish-timeout":"7s"} {"level":"info","ts":"2026-07-14T06:39:34.594422Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T06:39:34.594554Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T06:39:34.595024Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-14T06:39:34.59512Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-14T06:39:34.595673Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"58d35553a3d286c0","local-member-id":"ef5ef2a9544a39ef","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T06:39:34.595792Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T06:39:34.595829Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T06:39:34.596312Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.57:2379"} {"level":"info","ts":"2026-07-14T06:39:34.599454Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-14T06:39:38.274746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.010735ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T06:39:38.274963Z","caller":"traceutil/trace.go:171","msg":"trace[1320051699] linearizableReadLoop","detail":"{readStateIndex:211; appliedIndex:210; }","duration":"429.457971ms","start":"2026-07-14T06:39:37.845457Z","end":"2026-07-14T06:39:38.274915Z","steps":["trace[1320051699] 'read index received' (duration: 295.809652ms)","trace[1320051699] 'applied index is now lower than readState.Index' (duration: 133.630349ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T06:39:38.275181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.715488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T06:39:38.275268Z","caller":"traceutil/trace.go:171","msg":"trace[91355998] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:205; }","duration":"429.778878ms","start":"2026-07-14T06:39:37.845423Z","end":"2026-07-14T06:39:38.275202Z","steps":["trace[91355998] 'agreement among raft nodes before linearized reading' (duration: 429.578374ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:39:38.275308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T06:39:37.845409Z","time spent":"429.888562ms","remote":"127.0.0.1:45608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-14T06:39:38.275626Z","caller":"traceutil/trace.go:171","msg":"trace[588061514] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"527.860312ms","start":"2026-07-14T06:39:37.74774Z","end":"2026-07-14T06:39:38.2756Z","steps":["trace[588061514] 'process raft request' (duration: 393.585357ms)","trace[588061514] 'compare' (duration: 130.902622ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T06:39:38.275701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T06:39:37.747722Z","time spent":"527.932684ms","remote":"127.0.0.1:45690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":636,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T06:39:38.578811Z","caller":"traceutil/trace.go:171","msg":"trace[1537391598] linearizableReadLoop","detail":"{readStateIndex:212; appliedIndex:211; }","duration":"298.921588ms","start":"2026-07-14T06:39:38.279862Z","end":"2026-07-14T06:39:38.578784Z","steps":["trace[1537391598] 'read index received' (duration: 204.396221ms)","trace[1537391598] 'applied index is now lower than readState.Index' (duration: 94.524287ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T06:39:38.578858Z","caller":"traceutil/trace.go:171","msg":"trace[1750021494] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"298.95935ms","start":"2026-07-14T06:39:38.279847Z","end":"2026-07-14T06:39:38.578806Z","steps":["trace[1750021494] 'process raft request' (duration: 204.460863ms)","trace[1750021494] 'compare' (duration: 94.353133ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T06:39:38.579019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.133054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18c214b5c3b20f68\" ","response":"range_response_count:1 size:657"} {"level":"info","ts":"2026-07-14T06:39:38.579097Z","caller":"traceutil/trace.go:171","msg":"trace[375568245] range","detail":"{range_begin:/registry/events/default/instance.18c214b5c3b20f68; range_end:; response_count:1; response_revision:206; }","duration":"299.219966ms","start":"2026-07-14T06:39:38.279831Z","end":"2026-07-14T06:39:38.579051Z","steps":["trace[375568245] 'agreement among raft nodes before linearized reading' (duration: 299.098843ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T06:39:38.787257Z","caller":"traceutil/trace.go:171","msg":"trace[1460091945] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"205.927939ms","start":"2026-07-14T06:39:38.581296Z","end":"2026-07-14T06:39:38.787224Z","steps":["trace[1460091945] 'process raft request' (duration: 129.019026ms)","trace[1460091945] 'compare' (duration: 76.78876ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T06:39:38.788931Z","caller":"traceutil/trace.go:171","msg":"trace[1561728619] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"205.953121ms","start":"2026-07-14T06:39:38.582959Z","end":"2026-07-14T06:39:38.788912Z","steps":["trace[1561728619] 'process raft request' (duration: 205.627313ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T06:39:38.788997Z","caller":"traceutil/trace.go:171","msg":"trace[85937649] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"205.691514ms","start":"2026-07-14T06:39:38.583279Z","end":"2026-07-14T06:39:38.78897Z","steps":["trace[85937649] 'process raft request' (duration: 205.371326ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T06:39:38.78938Z","caller":"traceutil/trace.go:171","msg":"trace[845896065] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"109.788942ms","start":"2026-07-14T06:39:38.67956Z","end":"2026-07-14T06:39:38.789349Z","steps":["trace[845896065] 'process raft request' (duration: 109.126636ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:39:40.175147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.799686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-14T06:39:40.175214Z","caller":"traceutil/trace.go:171","msg":"trace[125358591] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:282; }","duration":"125.919359ms","start":"2026-07-14T06:39:40.049281Z","end":"2026-07-14T06:39:40.1752Z","steps":["trace[125358591] 'range keys from in-memory index tree' (duration: 125.679923ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T06:40:18.982644Z","caller":"traceutil/trace.go:171","msg":"trace[1405905787] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"103.052771ms","start":"2026-07-14T06:40:18.879554Z","end":"2026-07-14T06:40:18.982607Z","steps":["trace[1405905787] 'process raft request' (duration: 102.537216ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T06:40:18.982868Z","caller":"traceutil/trace.go:171","msg":"trace[674398124] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"103.090552ms","start":"2026-07-14T06:40:18.879711Z","end":"2026-07-14T06:40:18.982801Z","steps":["trace[674398124] 'process raft request' (duration: 102.549757ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T06:40:25.090087Z","caller":"traceutil/trace.go:171","msg":"trace[1685100484] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"126.819746ms","start":"2026-07-14T06:40:24.963244Z","end":"2026-07-14T06:40:25.090064Z","steps":["trace[1685100484] 'process raft request' (duration: 126.198999ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T06:40:25.962637Z","caller":"traceutil/trace.go:171","msg":"trace[165364425] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"383.262861ms","start":"2026-07-14T06:40:25.579347Z","end":"2026-07-14T06:40:25.96261Z","steps":["trace[165364425] 'process raft request' (duration: 382.745897ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:40:25.962787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T06:40:25.579316Z","time spent":"383.400285ms","remote":"127.0.0.1:45710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T06:40:26.311221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.094357ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T06:40:26.311355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.749288ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T06:40:26.311445Z","caller":"traceutil/trace.go:171","msg":"trace[1901931385] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:657; }","duration":"129.352513ms","start":"2026-07-14T06:40:26.182072Z","end":"2026-07-14T06:40:26.311424Z","steps":["trace[1901931385] 'range keys from in-memory index tree' (duration: 129.058655ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T06:40:26.311715Z","caller":"traceutil/trace.go:171","msg":"trace[913992071] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"295.079032ms","start":"2026-07-14T06:40:26.016616Z","end":"2026-07-14T06:40:26.311695Z","steps":["trace[913992071] 'process raft request' (duration: 294.965829ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T06:40:26.311746Z","caller":"traceutil/trace.go:171","msg":"trace[153694246] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"298.150443ms","start":"2026-07-14T06:40:26.013579Z","end":"2026-07-14T06:40:26.31173Z","steps":["trace[153694246] 'process raft request' (duration: 98.928643ms)","trace[153694246] 'compare' (duration: 198.568293ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T06:40:27.865894Z","caller":"traceutil/trace.go:171","msg":"trace[1941502467] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"216.624049ms","start":"2026-07-14T06:40:27.64924Z","end":"2026-07-14T06:40:27.865864Z","steps":["trace[1941502467] 'process raft request' (duration: 191.362374ms)","trace[1941502467] 'compare' (duration: 25.141752ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T06:40:41.298815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.023955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T06:40:41.298916Z","caller":"traceutil/trace.go:171","msg":"trace[1150938177] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:818; }","duration":"329.187909ms","start":"2026-07-14T06:40:40.96971Z","end":"2026-07-14T06:40:41.298897Z","steps":["trace[1150938177] 'count revisions from in-memory index tree' (duration: 328.88464ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:40:41.298965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T06:40:40.969685Z","time spent":"329.26364ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-07-14T06:40:41.299054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.992186ms","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-07-14T06:40:41.299153Z","caller":"traceutil/trace.go:171","msg":"trace[963343205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:818; }","duration":"120.14917ms","start":"2026-07-14T06:40:41.178985Z","end":"2026-07-14T06:40:41.299134Z","steps":["trace[963343205] 'range keys from in-memory index tree' (duration: 119.75767ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T06:41:03.726086Z","caller":"traceutil/trace.go:171","msg":"trace[1557108483] linearizableReadLoop","detail":"{readStateIndex:1024; appliedIndex:1023; }","duration":"236.741222ms","start":"2026-07-14T06:41:03.489305Z","end":"2026-07-14T06:41:03.726046Z","steps":["trace[1557108483] 'read index received' (duration: 236.520937ms)","trace[1557108483] 'applied index is now lower than readState.Index' (duration: 219.325µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T06:41:03.726126Z","caller":"traceutil/trace.go:171","msg":"trace[1964365637] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"238.306275ms","start":"2026-07-14T06:41:03.487789Z","end":"2026-07-14T06:41:03.726096Z","steps":["trace[1964365637] 'process raft request' (duration: 238.11443ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:41:03.726362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.046942ms","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-07-14T06:41:03.726405Z","caller":"traceutil/trace.go:171","msg":"trace[725224168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:990; }","duration":"237.143915ms","start":"2026-07-14T06:41:03.48925Z","end":"2026-07-14T06:41:03.726394Z","steps":["trace[725224168] 'agreement among raft nodes before linearized reading' (duration: 236.941468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:41:03.726622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.117307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-14T06:41:03.726733Z","caller":"traceutil/trace.go:171","msg":"trace[1579832826] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:990; }","duration":"131.280861ms","start":"2026-07-14T06:41:03.595434Z","end":"2026-07-14T06:41:03.726715Z","steps":["trace[1579832826] 'agreement among raft nodes before linearized reading' (duration: 130.992003ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:41:04.57192Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4174730611479595078,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-14T06:41:04.66753Z","caller":"traceutil/trace.go:171","msg":"trace[2045858658] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"937.208252ms","start":"2026-07-14T06:41:03.7303Z","end":"2026-07-14T06:41:04.667508Z","steps":["trace[2045858658] 'process raft request' (duration: 934.553542ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:41:04.667728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T06:41:03.730279Z","time spent":"937.382287ms","remote":"127.0.0.1:45894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T06:41:04.668108Z","caller":"traceutil/trace.go:171","msg":"trace[2026134836] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"937.699056ms","start":"2026-07-14T06:41:03.730389Z","end":"2026-07-14T06:41:04.668088Z","steps":["trace[2026134836] 'process raft request' (duration: 937.542802ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T06:41:04.668149Z","caller":"traceutil/trace.go:171","msg":"trace[1879564043] linearizableReadLoop","detail":"{readStateIndex:1027; appliedIndex:1024; }","duration":"597.180145ms","start":"2026-07-14T06:41:04.070952Z","end":"2026-07-14T06:41:04.668132Z","steps":["trace[1879564043] 'read index received' (duration: 593.848066ms)","trace[1879564043] 'applied index is now lower than readState.Index' (duration: 3.325569ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T06:41:04.668226Z","caller":"traceutil/trace.go:171","msg":"trace[281351445] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"850.215962ms","start":"2026-07-14T06:41:03.818005Z","end":"2026-07-14T06:41:04.66822Z","steps":["trace[281351445] 'process raft request' (duration: 850.023867ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:41:04.668238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T06:41:03.730364Z","time spent":"937.818868ms","remote":"127.0.0.1:45894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T06:41:04.6683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T06:41:03.817983Z","time spent":"850.261614ms","remote":"127.0.0.1:45894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T06:41:04.668414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.731972ms","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-07-14T06:41:04.668424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.342643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-14T06:41:04.6685Z","caller":"traceutil/trace.go:171","msg":"trace[1141252007] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:993; }","duration":"200.454436ms","start":"2026-07-14T06:41:04.468028Z","end":"2026-07-14T06:41:04.668483Z","steps":["trace[1141252007] 'agreement among raft nodes before linearized reading' (duration: 200.277641ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T06:41:04.668513Z","caller":"traceutil/trace.go:171","msg":"trace[1669916410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:993; }","duration":"101.802244ms","start":"2026-07-14T06:41:04.566671Z","end":"2026-07-14T06:41:04.668473Z","steps":["trace[1669916410] 'agreement among raft nodes before linearized reading' (duration: 101.67403ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:41:04.668401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.440471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T06:41:04.66861Z","caller":"traceutil/trace.go:171","msg":"trace[1619390823] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:993; }","duration":"597.700548ms","start":"2026-07-14T06:41:04.0709Z","end":"2026-07-14T06:41:04.668601Z","steps":["trace[1619390823] 'agreement among raft nodes before linearized reading' (duration: 597.420341ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:41:04.668648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T06:41:04.070873Z","time spent":"597.76328ms","remote":"127.0.0.1:45828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-07-14T06:41:07.18078Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4174730611479595093,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T06:41:07.681933Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4174730611479595093,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T06:41:08.182742Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4174730611479595093,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T06:41:08.683086Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4174730611479595093,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T06:41:09.462153Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4174730611479595093,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T06:41:09.920821Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.243506313s","expected-duration":"1s"} {"level":"info","ts":"2026-07-14T06:41:09.925032Z","caller":"traceutil/trace.go:171","msg":"trace[163281073] linearizableReadLoop","detail":"{readStateIndex:1035; appliedIndex:1032; }","duration":"3.24493705s","start":"2026-07-14T06:41:06.680072Z","end":"2026-07-14T06:41:09.925009Z","steps":["trace[163281073] 'read index received' (duration: 3.241019506s)","trace[163281073] 'applied index is now lower than readState.Index' (duration: 3.916024ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T06:41:09.925267Z","caller":"traceutil/trace.go:171","msg":"trace[1148829483] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"1.24463025s","start":"2026-07-14T06:41:08.680605Z","end":"2026-07-14T06:41:09.925235Z","steps":["trace[1148829483] 'process raft request' (duration: 1.244368023s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:41:09.925443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T06:41:08.680587Z","time spent":"1.244742583s","remote":"127.0.0.1:45894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T06:41:09.925476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.372664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T06:41:09.925519Z","caller":"traceutil/trace.go:171","msg":"trace[1503285000] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1001; }","duration":"344.466495ms","start":"2026-07-14T06:41:09.581041Z","end":"2026-07-14T06:41:09.925507Z","steps":["trace[1503285000] 'agreement among raft nodes before linearized reading' (duration: 344.365933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:41:09.925564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T06:41:09.581024Z","time spent":"344.528228ms","remote":"127.0.0.1:46022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-07-14T06:41:09.925611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.244579729s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-14T06:41:09.925678Z","caller":"traceutil/trace.go:171","msg":"trace[1865692099] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1001; }","duration":"1.24466561s","start":"2026-07-14T06:41:08.680998Z","end":"2026-07-14T06:41:09.925664Z","steps":["trace[1865692099] 'agreement among raft nodes before linearized reading' (duration: 1.24427295s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:41:09.925726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T06:41:08.680992Z","time spent":"1.244723403s","remote":"127.0.0.1:45894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-14T06:41:09.925669Z","caller":"traceutil/trace.go:171","msg":"trace[710333309] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"1.449201175s","start":"2026-07-14T06:41:08.476453Z","end":"2026-07-14T06:41:09.925654Z","steps":["trace[710333309] 'process raft request' (duration: 1.448452616s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:41:09.925822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T06:41:08.476433Z","time spent":"1.44935661s","remote":"127.0.0.1:45894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T06:41:09.925953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.188609843s","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-07-14T06:41:09.925995Z","caller":"traceutil/trace.go:171","msg":"trace[353215901] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1001; }","duration":"2.188692525s","start":"2026-07-14T06:41:07.737295Z","end":"2026-07-14T06:41:09.925987Z","steps":["trace[353215901] 'agreement among raft nodes before linearized reading' (duration: 2.188598923s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:41:09.926024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T06:41:07.737274Z","time spent":"2.188744197s","remote":"127.0.0.1:45894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-14T06:41:09.926287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.468524981s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T06:41:09.926334Z","caller":"traceutil/trace.go:171","msg":"trace[569655897] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1001; }","duration":"1.468596203s","start":"2026-07-14T06:41:08.457732Z","end":"2026-07-14T06:41:09.926328Z","steps":["trace[569655897] 'agreement among raft nodes before linearized reading' (duration: 1.468528971s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:41:09.92636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T06:41:08.457716Z","time spent":"1.468637195s","remote":"127.0.0.1:45608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T06:41:09.926349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.386121369s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.57\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-14T06:41:09.926397Z","caller":"traceutil/trace.go:171","msg":"trace[1736258976] range","detail":"{range_begin:/registry/masterleases/199.19.213.57; range_end:; response_count:1; response_revision:1001; }","duration":"2.386213092s","start":"2026-07-14T06:41:07.540174Z","end":"2026-07-14T06:41:09.926387Z","steps":["trace[1736258976] 'agreement among raft nodes before linearized reading' (duration: 2.386075388s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:41:09.926447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T06:41:07.540152Z","time spent":"2.386279944s","remote":"127.0.0.1:45648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.57\" "} {"level":"warn","ts":"2026-07-14T06:41:09.92592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.245786135s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-14T06:41:09.92751Z","caller":"traceutil/trace.go:171","msg":"trace[1345200125] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1001; }","duration":"3.247445438s","start":"2026-07-14T06:41:06.680047Z","end":"2026-07-14T06:41:09.927492Z","steps":["trace[1345200125] 'agreement among raft nodes before linearized reading' (duration: 3.245622909s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:41:09.928611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T06:41:06.680035Z","time spent":"3.248537337s","remote":"127.0.0.1:45894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-14T06:41:09.925289Z","caller":"traceutil/trace.go:171","msg":"trace[162147948] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"3.249025209s","start":"2026-07-14T06:41:06.676246Z","end":"2026-07-14T06:41:09.925271Z","steps":["trace[162147948] 'process raft request' (duration: 3.244830188s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:41:09.928758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T06:41:06.676234Z","time spent":"3.252441302s","remote":"127.0.0.1:45894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T06:41:09.92776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.245340581s","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-07-14T06:41:09.92886Z","caller":"traceutil/trace.go:171","msg":"trace[752420205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1001; }","duration":"3.248780594s","start":"2026-07-14T06:41:06.680067Z","end":"2026-07-14T06:41:09.928847Z","steps":["trace[752420205] 'agreement among raft nodes before linearized reading' (duration: 3.245193698s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:41:09.928917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T06:41:06.680059Z","time spent":"3.248824735s","remote":"127.0.0.1:45894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-14T06:41:17.767539Z","caller":"traceutil/trace.go:171","msg":"trace[55749051] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"114.511044ms","start":"2026-07-14T06:41:17.653Z","end":"2026-07-14T06:41:17.767511Z","steps":["trace[55749051] 'process raft request' (duration: 113.811605ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T06:41:32.495229Z","caller":"traceutil/trace.go:171","msg":"trace[2140366635] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"135.74211ms","start":"2026-07-14T06:41:32.359463Z","end":"2026-07-14T06:41:32.495205Z","steps":["trace[2140366635] 'process raft request' (duration: 135.584576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:41:38.781021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.752187ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T06:41:38.781465Z","caller":"traceutil/trace.go:171","msg":"trace[1869228276] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"713.98075ms","start":"2026-07-14T06:41:38.06744Z","end":"2026-07-14T06:41:38.781421Z","steps":["trace[1869228276] 'process raft request' (duration: 333.75356ms)","trace[1869228276] 'compare' (duration: 379.637404ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T06:41:38.781561Z","caller":"traceutil/trace.go:171","msg":"trace[175934082] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"712.632754ms","start":"2026-07-14T06:41:38.068901Z","end":"2026-07-14T06:41:38.781534Z","steps":["trace[175934082] 'process raft request' (duration: 712.214713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:41:38.781642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T06:41:38.067424Z","time spent":"714.121534ms","remote":"127.0.0.1:45894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T06:41:38.781685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T06:41:38.06889Z","time spent":"712.725756ms","remote":"127.0.0.1:45894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T06:41:38.781728Z","caller":"traceutil/trace.go:171","msg":"trace[257382825] linearizableReadLoop","detail":"{readStateIndex:1141; appliedIndex:1139; }","duration":"678.791311ms","start":"2026-07-14T06:41:38.102829Z","end":"2026-07-14T06:41:38.78162Z","steps":["trace[257382825] 'read index received' (duration: 298.323505ms)","trace[257382825] 'applied index is now lower than readState.Index' (duration: 380.463846ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T06:41:38.781858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.256857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-14T06:41:38.781903Z","caller":"traceutil/trace.go:171","msg":"trace[1340678951] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1101; }","duration":"711.316759ms","start":"2026-07-14T06:41:38.070574Z","end":"2026-07-14T06:41:38.781891Z","steps":["trace[1340678951] 'agreement among raft nodes before linearized reading' (duration: 711.175725ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:41:38.781915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.909431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T06:41:38.781918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.86812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T06:41:38.781939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T06:41:38.070568Z","time spent":"711.36299ms","remote":"127.0.0.1:45894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-14T06:41:38.781955Z","caller":"traceutil/trace.go:171","msg":"trace[388471870] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1101; }","duration":"325.997283ms","start":"2026-07-14T06:41:38.455944Z","end":"2026-07-14T06:41:38.781941Z","steps":["trace[388471870] 'agreement among raft nodes before linearized reading' (duration: 325.906281ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T06:41:38.781956Z","caller":"traceutil/trace.go:171","msg":"trace[1746452170] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1101; }","duration":"259.953732ms","start":"2026-07-14T06:41:38.521993Z","end":"2026-07-14T06:41:38.781947Z","steps":["trace[1746452170] 'agreement among raft nodes before linearized reading' (duration: 259.88122ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:41:38.781991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T06:41:38.45592Z","time spent":"326.062974ms","remote":"127.0.0.1:45608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-14T06:41:38.781699Z","caller":"traceutil/trace.go:171","msg":"trace[1563464511] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"674.858177ms","start":"2026-07-14T06:41:38.106675Z","end":"2026-07-14T06:41:38.781534Z","steps":["trace[1563464511] 'process raft request' (duration: 674.808795ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:41:38.782327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T06:41:38.106644Z","time spent":"675.643467ms","remote":"127.0.0.1:45894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T06:41:39.007728Z","caller":"traceutil/trace.go:171","msg":"trace[631066568] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"221.360003ms","start":"2026-07-14T06:41:38.786349Z","end":"2026-07-14T06:41:39.007709Z","steps":["trace[631066568] 'process raft request' (duration: 221.24342ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T06:41:40.15182Z","caller":"traceutil/trace.go:171","msg":"trace[831066046] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"124.798677ms","start":"2026-07-14T06:41:40.026986Z","end":"2026-07-14T06:41:40.151785Z","steps":["trace[831066046] 'process raft request' (duration: 124.55032ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T06:41:40.470384Z","caller":"traceutil/trace.go:171","msg":"trace[860763444] linearizableReadLoop","detail":"{readStateIndex:1145; appliedIndex:1144; }","duration":"241.713645ms","start":"2026-07-14T06:41:40.228651Z","end":"2026-07-14T06:41:40.470365Z","steps":["trace[860763444] 'read index received' (duration: 199.346216ms)","trace[860763444] 'applied index is now lower than readState.Index' (duration: 42.366729ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T06:41:40.470529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.858669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T06:41:40.47048Z","caller":"traceutil/trace.go:171","msg":"trace[1510648544] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"313.105239ms","start":"2026-07-14T06:41:40.157336Z","end":"2026-07-14T06:41:40.470441Z","steps":["trace[1510648544] 'process raft request' (duration: 270.728339ms)","trace[1510648544] 'compare' (duration: 42.136294ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T06:41:40.470554Z","caller":"traceutil/trace.go:171","msg":"trace[975311076] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1104; }","duration":"241.970562ms","start":"2026-07-14T06:41:40.228577Z","end":"2026-07-14T06:41:40.470548Z","steps":["trace[975311076] 'agreement among raft nodes before linearized reading' (duration: 241.88491ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:41:40.470658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T06:41:40.157309Z","time spent":"313.262174ms","remote":"127.0.0.1:45894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T06:41:42.946673Z","caller":"traceutil/trace.go:171","msg":"trace[1944187084] linearizableReadLoop","detail":"{readStateIndex:1154; appliedIndex:1153; }","duration":"149.384363ms","start":"2026-07-14T06:41:42.797266Z","end":"2026-07-14T06:41:42.94665Z","steps":["trace[1944187084] 'read index received' (duration: 149.23853ms)","trace[1944187084] 'applied index is now lower than readState.Index' (duration: 144.943µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T06:41:42.946771Z","caller":"traceutil/trace.go:171","msg":"trace[789962381] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"149.656652ms","start":"2026-07-14T06:41:42.797075Z","end":"2026-07-14T06:41:42.946731Z","steps":["trace[789962381] 'process raft request' (duration: 149.426256ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:41:42.946964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.65126ms","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-07-14T06:41:42.947086Z","caller":"traceutil/trace.go:171","msg":"trace[1871789332] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1113; }","duration":"149.796694ms","start":"2026-07-14T06:41:42.797246Z","end":"2026-07-14T06:41:42.947043Z","steps":["trace[1871789332] 'agreement among raft nodes before linearized reading' (duration: 149.491726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:41:42.947213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.764353ms","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-07-14T06:41:42.947272Z","caller":"traceutil/trace.go:171","msg":"trace[739810173] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1113; }","duration":"149.913867ms","start":"2026-07-14T06:41:42.797345Z","end":"2026-07-14T06:41:42.947259Z","steps":["trace[739810173] 'agreement among raft nodes before linearized reading' (duration: 149.64741ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T06:41:43.264364Z","caller":"traceutil/trace.go:171","msg":"trace[620713933] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"313.061217ms","start":"2026-07-14T06:41:42.951274Z","end":"2026-07-14T06:41:43.264336Z","steps":["trace[620713933] 'process raft request' (duration: 292.108889ms)","trace[620713933] 'compare' (duration: 20.836145ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T06:41:43.26488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T06:41:42.951251Z","time spent":"313.259263ms","remote":"127.0.0.1:45894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T06:41:43.291454Z","caller":"traceutil/trace.go:171","msg":"trace[1611231520] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"339.802831ms","start":"2026-07-14T06:41:42.951628Z","end":"2026-07-14T06:41:43.291431Z","steps":["trace[1611231520] 'process raft request' (duration: 339.635067ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:41:43.291721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T06:41:42.951609Z","time spent":"339.918184ms","remote":"127.0.0.1:45894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T06:41:43.639114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.691602ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-14T06:41:43.639208Z","caller":"traceutil/trace.go:171","msg":"trace[1632899417] linearizableReadLoop","detail":"{readStateIndex:1157; appliedIndex:1154; }","duration":"619.072199ms","start":"2026-07-14T06:41:43.02012Z","end":"2026-07-14T06:41:43.639192Z","steps":["trace[1632899417] 'read index received' (duration: 223.393638ms)","trace[1632899417] 'applied index is now lower than readState.Index' (duration: 395.6772ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T06:41:43.639366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.244873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-14T06:41:43.639389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.803555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T06:41:43.639411Z","caller":"traceutil/trace.go:171","msg":"trace[382343858] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1115; }","duration":"619.355516ms","start":"2026-07-14T06:41:43.020044Z","end":"2026-07-14T06:41:43.639399Z","steps":["trace[382343858] 'agreement among raft nodes before linearized reading' (duration: 619.196112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:41:43.639434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T06:41:43.01999Z","time spent":"619.440648ms","remote":"127.0.0.1:45894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-14T06:41:43.63943Z","caller":"traceutil/trace.go:171","msg":"trace[1336347792] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1115; }","duration":"248.897947ms","start":"2026-07-14T06:41:43.390519Z","end":"2026-07-14T06:41:43.639417Z","steps":["trace[1336347792] 'agreement among raft nodes before linearized reading' (duration: 248.802005ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T06:41:43.823639Z","caller":"traceutil/trace.go:171","msg":"trace[1080187329] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"179.824476ms","start":"2026-07-14T06:41:43.643788Z","end":"2026-07-14T06:41:43.823612Z","steps":["trace[1080187329] 'process raft request' (duration: 179.667292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:41:58.957502Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4174730611479595611,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T06:41:59.441561Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.235704799s","expected-duration":"1s"} {"level":"info","ts":"2026-07-14T06:41:59.441898Z","caller":"traceutil/trace.go:171","msg":"trace[2027891110] linearizableReadLoop","detail":"{readStateIndex:1261; appliedIndex:1260; }","duration":"984.745423ms","start":"2026-07-14T06:41:58.457132Z","end":"2026-07-14T06:41:59.441877Z","steps":["trace[2027891110] 'read index received' (duration: 984.558048ms)","trace[2027891110] 'applied index is now lower than readState.Index' (duration: 186.485µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T06:41:59.441968Z","caller":"traceutil/trace.go:171","msg":"trace[2133936831] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"1.23616328s","start":"2026-07-14T06:41:58.205784Z","end":"2026-07-14T06:41:59.441948Z","steps":["trace[2133936831] 'process raft request' (duration: 1.235957974s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:41:59.442009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"984.855746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T06:41:59.442039Z","caller":"traceutil/trace.go:171","msg":"trace[2132121532] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1214; }","duration":"984.938758ms","start":"2026-07-14T06:41:58.457091Z","end":"2026-07-14T06:41:59.442029Z","steps":["trace[2132121532] 'agreement among raft nodes before linearized reading' (duration: 984.871506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:41:59.442072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T06:41:58.457074Z","time spent":"984.98935ms","remote":"127.0.0.1:45608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T06:41:59.442074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T06:41:58.205767Z","time spent":"1.236241912s","remote":"127.0.0.1:45894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T06:41:59.44229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.314373ms","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-07-14T06:41:59.442318Z","caller":"traceutil/trace.go:171","msg":"trace[57810482] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1214; }","duration":"897.365835ms","start":"2026-07-14T06:41:58.544944Z","end":"2026-07-14T06:41:59.442309Z","steps":["trace[57810482] 'agreement among raft nodes before linearized reading' (duration: 897.234362ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:41:59.442358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T06:41:58.544931Z","time spent":"897.420356ms","remote":"127.0.0.1:45894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-14T06:41:59.442513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.517097ms","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-07-14T06:41:59.442557Z","caller":"traceutil/trace.go:171","msg":"trace[1969644128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1214; }","duration":"113.593588ms","start":"2026-07-14T06:41:59.328952Z","end":"2026-07-14T06:41:59.442546Z","steps":["trace[1969644128] 'agreement among raft nodes before linearized reading' (duration: 113.489205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:41:59.442622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.199201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-14T06:41:59.442688Z","caller":"traceutil/trace.go:171","msg":"trace[1105133350] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1214; }","duration":"434.303294ms","start":"2026-07-14T06:41:59.008366Z","end":"2026-07-14T06:41:59.442669Z","steps":["trace[1105133350] 'agreement among raft nodes before linearized reading' (duration: 434.13866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:41:59.442722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T06:41:59.008348Z","time spent":"434.366336ms","remote":"127.0.0.1:45894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-14T06:41:59.568722Z","caller":"traceutil/trace.go:171","msg":"trace[692888217] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"122.291754ms","start":"2026-07-14T06:41:59.446409Z","end":"2026-07-14T06:41:59.5687Z","steps":["trace[692888217] 'process raft request' (duration: 122.220252ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T06:41:59.568741Z","caller":"traceutil/trace.go:171","msg":"trace[1344510269] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"122.551001ms","start":"2026-07-14T06:41:59.446157Z","end":"2026-07-14T06:41:59.568708Z","steps":["trace[1344510269] 'process raft request' (duration: 118.732452ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T06:41:59.568876Z","caller":"traceutil/trace.go:171","msg":"trace[1307831421] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"122.349505ms","start":"2026-07-14T06:41:59.446502Z","end":"2026-07-14T06:41:59.568851Z","steps":["trace[1307831421] 'process raft request' (duration: 122.157651ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T06:41:59.569034Z","caller":"traceutil/trace.go:171","msg":"trace[506976452] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"122.607543ms","start":"2026-07-14T06:41:59.446409Z","end":"2026-07-14T06:41:59.569016Z","steps":["trace[506976452] 'process raft request' (duration: 122.169461ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:42:16.34007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.100324ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T06:42:16.340149Z","caller":"traceutil/trace.go:171","msg":"trace[1339403661] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1288; }","duration":"158.188536ms","start":"2026-07-14T06:42:16.18195Z","end":"2026-07-14T06:42:16.340139Z","steps":["trace[1339403661] 'range keys from in-memory index tree' (duration: 158.090164ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T06:42:16.47004Z","caller":"traceutil/trace.go:171","msg":"trace[2014255037] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"483.090121ms","start":"2026-07-14T06:42:15.986907Z","end":"2026-07-14T06:42:16.469997Z","steps":["trace[2014255037] 'process raft request' (duration: 482.822623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:42:16.470314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T06:42:15.986875Z","time spent":"483.290876ms","remote":"127.0.0.1:45828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11371,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T06:42:16.610969Z","caller":"traceutil/trace.go:171","msg":"trace[826018726] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"133.555418ms","start":"2026-07-14T06:42:16.47738Z","end":"2026-07-14T06:42:16.610935Z","steps":["trace[826018726] 'process raft request' (duration: 133.27373ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T06:42:16.610946Z","caller":"traceutil/trace.go:171","msg":"trace[1615944270] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"128.465735ms","start":"2026-07-14T06:42:16.482454Z","end":"2026-07-14T06:42:16.61092Z","steps":["trace[1615944270] 'process raft request' (duration: 128.378203ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T06:42:17.901927Z","caller":"traceutil/trace.go:171","msg":"trace[644299485] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"180.790004ms","start":"2026-07-14T06:42:17.721098Z","end":"2026-07-14T06:42:17.901888Z","steps":["trace[644299485] 'process raft request' (duration: 179.438718ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T06:42:17.90198Z","caller":"traceutil/trace.go:171","msg":"trace[609452046] linearizableReadLoop","detail":"{readStateIndex:1348; appliedIndex:1346; }","duration":"180.745853ms","start":"2026-07-14T06:42:17.721206Z","end":"2026-07-14T06:42:17.901951Z","steps":["trace[609452046] 'read index received' (duration: 24.123667ms)","trace[609452046] 'applied index is now lower than readState.Index' (duration: 156.620976ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T06:42:17.902189Z","caller":"traceutil/trace.go:171","msg":"trace[1128370950] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"180.771423ms","start":"2026-07-14T06:42:17.721402Z","end":"2026-07-14T06:42:17.902173Z","steps":["trace[1128370950] 'process raft request' (duration: 180.435185ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:42:17.902776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.517602ms","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-07-14T06:42:17.902907Z","caller":"traceutil/trace.go:171","msg":"trace[1885293383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1298; }","duration":"181.698637ms","start":"2026-07-14T06:42:17.721189Z","end":"2026-07-14T06:42:17.902888Z","steps":["trace[1885293383] 'agreement among raft nodes before linearized reading' (duration: 180.865445ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T06:42:18.105421Z","caller":"traceutil/trace.go:171","msg":"trace[1873732736] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"130.456616ms","start":"2026-07-14T06:42:17.974927Z","end":"2026-07-14T06:42:18.105383Z","steps":["trace[1873732736] 'process raft request' (duration: 119.766749ms)","trace[1873732736] 'compare' (duration: 10.515853ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T06:42:27.986009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.659834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.57\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-14T06:42:27.98608Z","caller":"traceutil/trace.go:171","msg":"trace[1242054834] range","detail":"{range_begin:/registry/masterleases/199.19.213.57; range_end:; response_count:1; response_revision:1371; }","duration":"441.790067ms","start":"2026-07-14T06:42:27.544272Z","end":"2026-07-14T06:42:27.986062Z","steps":["trace[1242054834] 'range keys from in-memory index tree' (duration: 441.415778ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:42:27.986125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T06:42:27.54425Z","time spent":"441.8658ms","remote":"127.0.0.1:45648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.57\" "} {"level":"info","ts":"2026-07-14T06:42:27.9864Z","caller":"traceutil/trace.go:171","msg":"trace[127009511] linearizableReadLoop","detail":"{readStateIndex:1424; appliedIndex:1423; }","duration":"218.460361ms","start":"2026-07-14T06:42:27.767908Z","end":"2026-07-14T06:42:27.986368Z","steps":["trace[127009511] 'read index received' (duration: 195.692249ms)","trace[127009511] 'applied index is now lower than readState.Index' (duration: 22.765632ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T06:42:27.986504Z","caller":"traceutil/trace.go:171","msg":"trace[1293369877] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"439.707495ms","start":"2026-07-14T06:42:27.546753Z","end":"2026-07-14T06:42:27.98646Z","steps":["trace[1293369877] 'process raft request' (duration: 416.82753ms)","trace[1293369877] 'compare' (duration: 22.310649ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T06:42:27.986562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.641406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T06:42:27.986599Z","caller":"traceutil/trace.go:171","msg":"trace[203055404] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1372; }","duration":"218.721498ms","start":"2026-07-14T06:42:27.767866Z","end":"2026-07-14T06:42:27.986588Z","steps":["trace[203055404] 'agreement among raft nodes before linearized reading' (duration: 218.648306ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T06:42:27.986701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.818889ms","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-07-14T06:42:27.986701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T06:42:27.546723Z","time spent":"439.870868ms","remote":"127.0.0.1:45894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T06:42:27.986749Z","caller":"traceutil/trace.go:171","msg":"trace[230256946] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1372; }","duration":"196.914713ms","start":"2026-07-14T06:42:27.789821Z","end":"2026-07-14T06:42:27.986735Z","steps":["trace[230256946] 'agreement among raft nodes before linearized reading' (duration: 196.723438ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T06:42:28.119029Z","caller":"traceutil/trace.go:171","msg":"trace[1557548552] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"128.394863ms","start":"2026-07-14T06:42:27.99061Z","end":"2026-07-14T06:42:28.119005Z","steps":["trace[1557548552] 'process raft request' (duration: 128.209038ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T06:42:28.119231Z","caller":"traceutil/trace.go:171","msg":"trace[1434015310] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"127.963472ms","start":"2026-07-14T06:42:27.991251Z","end":"2026-07-14T06:42:28.119214Z","steps":["trace[1434015310] 'process raft request' (duration: 127.709026ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T06:42:28.119291Z","caller":"traceutil/trace.go:171","msg":"trace[372916559] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"127.806408ms","start":"2026-07-14T06:42:27.991461Z","end":"2026-07-14T06:42:28.119267Z","steps":["trace[372916559] 'process raft request' (duration: 127.673944ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T06:42:28.119316Z","caller":"traceutil/trace.go:171","msg":"trace[894559550] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"127.755877ms","start":"2026-07-14T06:42:27.991543Z","end":"2026-07-14T06:42:28.119299Z","steps":["trace[894559550] 'process raft request' (duration: 127.671944ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T06:43:36.42104Z","caller":"traceutil/trace.go:171","msg":"trace[409867130] linearizableReadLoop","detail":"{readStateIndex:1752; appliedIndex:1751; }","duration":"152.198836ms","start":"2026-07-14T06:43:36.268823Z","end":"2026-07-14T06:43:36.421022Z","steps":["trace[409867130] 'read index received' (duration: 151.997211ms)","trace[409867130] 'applied index is now lower than readState.Index' (duration: 200.815µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T06:43:36.421368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.522484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-07-14T06:43:36.421462Z","caller":"traceutil/trace.go:171","msg":"trace[2008311623] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1686; }","duration":"152.622876ms","start":"2026-07-14T06:43:36.268814Z","end":"2026-07-14T06:43:36.421437Z","steps":["trace[2008311623] 'agreement among raft nodes before linearized reading' (duration: 152.303228ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T06:43:36.421772Z","caller":"traceutil/trace.go:171","msg":"trace[1136597178] transaction","detail":"{read_only:false; response_revision:1686; number_of_response:1; }","duration":"218.926886ms","start":"2026-07-14T06:43:36.202155Z","end":"2026-07-14T06:43:36.421081Z","steps":["trace[1136597178] 'process raft request' (duration: 218.719011ms)"],"step_count":1}