{"level":"warn","ts":"2026-03-15T02:00:48.421062Z","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-15T02:00:48.421179Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.70:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.70:2380","--initial-cluster=instance=https://162.253.55.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.70: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-15T02:00:48.421445Z","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-15T02:00:48.421472Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-03-15T02:00:48.421489Z","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-15T02:00:48.422114Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"]} {"level":"info","ts":"2026-03-15T02:00:48.422186Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.70: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-15T02:00:48.427225Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.713259ms"} {"level":"info","ts":"2026-03-15T02:00:48.434179Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"23887246632736aa","cluster-id":"62cfeca3ef46d3ef"} {"level":"info","ts":"2026-03-15T02:00:48.434262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=()"} {"level":"info","ts":"2026-03-15T02:00:48.434283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 0"} {"level":"info","ts":"2026-03-15T02:00:48.434303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 23887246632736aa [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-15T02:00:48.434312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 1"} {"level":"info","ts":"2026-03-15T02:00:48.434391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"warn","ts":"2026-03-15T02:00:48.443385Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-15T02:00:48.446284Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-15T02:00:48.447344Z","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-15T02:00:48.449567Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"23887246632736aa","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-15T02:00:48.449703Z","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-15T02:00:48.449804Z","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-15T02:00:48.449817Z","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-15T02:00:48.449771Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"23887246632736aa","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-15T02:00:48.455039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"info","ts":"2026-03-15T02:00:48.455526Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","added-peer-id":"23887246632736aa","added-peer-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-03-15T02:00:48.457395Z","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-15T02:00:48.457697Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"23887246632736aa","initial-advertise-peer-urls":["https://162.253.55.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-15T02:00:48.457735Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-15T02:00:48.45788Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-03-15T02:00:48.457899Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-03-15T02:00:48.955057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa is starting a new election at term 1"} {"level":"info","ts":"2026-03-15T02:00:48.955113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became pre-candidate at term 1"} {"level":"info","ts":"2026-03-15T02:00:48.955139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgPreVoteResp from 23887246632736aa at term 1"} {"level":"info","ts":"2026-03-15T02:00:48.955152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became candidate at term 2"} {"level":"info","ts":"2026-03-15T02:00:48.955158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgVoteResp from 23887246632736aa at term 2"} {"level":"info","ts":"2026-03-15T02:00:48.95517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became leader at term 2"} {"level":"info","ts":"2026-03-15T02:00:48.955178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 23887246632736aa elected leader 23887246632736aa at term 2"} {"level":"info","ts":"2026-03-15T02:00:48.961955Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"23887246632736aa","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.70:2379]}","request-path":"/0/members/23887246632736aa/attributes","cluster-id":"62cfeca3ef46d3ef","publish-timeout":"7s"} {"level":"info","ts":"2026-03-15T02:00:48.962005Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-15T02:00:48.962083Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-15T02:00:48.962192Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-15T02:00:48.962882Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-15T02:00:48.962932Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-15T02:00:48.962948Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","cluster-version":"3.5"} {"level":"info","ts":"2026-03-15T02:00:48.963033Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-15T02:00:48.963063Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-15T02:00:48.964146Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-15T02:00:48.967895Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.70:2379"} {"level":"warn","ts":"2026-03-15T02:00:53.123822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.051388925s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-03-15T02:00:53.124034Z","caller":"traceutil/trace.go:171","msg":"trace[1850004118] linearizableReadLoop","detail":"{readStateIndex:125; appliedIndex:123; }","duration":"825.075763ms","start":"2026-03-15T02:00:52.298942Z","end":"2026-03-15T02:00:53.124017Z","steps":["trace[1850004118] 'read index received' (duration: 42.711µs)","trace[1850004118] 'applied index is now lower than readState.Index' (duration: 825.032132ms)"],"step_count":2} {"level":"warn","ts":"2026-03-15T02:00:53.124217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.254048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-15T02:00:53.12426Z","caller":"traceutil/trace.go:171","msg":"trace[2090015223] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:120; }","duration":"825.35265ms","start":"2026-03-15T02:00:52.298897Z","end":"2026-03-15T02:00:53.12425Z","steps":["trace[2090015223] 'agreement among raft nodes before linearized reading' (duration: 825.183846ms)"],"step_count":1} {"level":"info","ts":"2026-03-15T02:00:53.124175Z","caller":"traceutil/trace.go:171","msg":"trace[1534915433] transaction","detail":"{read_only:false; response_revision:120; number_of_response:1; }","duration":"1.62035712s","start":"2026-03-15T02:00:51.503777Z","end":"2026-03-15T02:00:53.124134Z","steps":["trace[1534915433] 'process raft request' (duration: 1.620177236s)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:00:53.124299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:00:52.298876Z","time spent":"825.413962ms","remote":"127.0.0.1:45424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-15T02:00:53.1245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:00:51.503762Z","time spent":"1.620664508s","remote":"127.0.0.1:45744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":741,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-15T02:00:53.124756Z","caller":"traceutil/trace.go:171","msg":"trace[1649962152] transaction","detail":"{read_only:false; response_revision:119; number_of_response:1; }","duration":"1.623551332s","start":"2026-03-15T02:00:51.501162Z","end":"2026-03-15T02:00:53.124713Z","steps":["trace[1649962152] 'process raft request' (duration: 570.610288ms)","trace[1649962152] 'compare' (duration: 1.051247111s)"],"step_count":2} {"level":"warn","ts":"2026-03-15T02:00:53.12495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:00:51.501147Z","time spent":"1.623705885s","remote":"127.0.0.1:45478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-15T02:00:53.430544Z","caller":"traceutil/trace.go:171","msg":"trace[1517027312] transaction","detail":"{read_only:false; response_revision:122; number_of_response:1; }","duration":"299.667795ms","start":"2026-03-15T02:00:53.13085Z","end":"2026-03-15T02:00:53.430518Z","steps":["trace[1517027312] 'process raft request' (duration: 299.506011ms)"],"step_count":1} {"level":"info","ts":"2026-03-15T02:00:53.430558Z","caller":"traceutil/trace.go:171","msg":"trace[275290478] transaction","detail":"{read_only:false; response_revision:121; number_of_response:1; }","duration":"300.330071ms","start":"2026-03-15T02:00:53.130145Z","end":"2026-03-15T02:00:53.430475Z","steps":["trace[275290478] 'process raft request' (duration: 294.110913ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:00:53.430944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:00:53.130122Z","time spent":"300.737872ms","remote":"127.0.0.1:45478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":638,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-15T02:01:19.978647Z","caller":"traceutil/trace.go:171","msg":"trace[115996064] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"350.618719ms","start":"2026-03-15T02:01:19.628002Z","end":"2026-03-15T02:01:19.978621Z","steps":["trace[115996064] 'process raft request' (duration: 350.464745ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:01:19.978819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:01:19.627975Z","time spent":"350.756962ms","remote":"127.0.0.1:45678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-15T02:01:21.260055Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133375364609631,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-15T02:01:21.460292Z","caller":"traceutil/trace.go:171","msg":"trace[1665839334] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"829.821494ms","start":"2026-03-15T02:01:20.63045Z","end":"2026-03-15T02:01:21.460271Z","steps":["trace[1665839334] 'process raft request' (duration: 829.686851ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:01:21.460415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:01:20.630432Z","time spent":"829.920867ms","remote":"127.0.0.1:45678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-15T02:01:21.563662Z","caller":"traceutil/trace.go:171","msg":"trace[2113186696] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:528; }","duration":"804.250184ms","start":"2026-03-15T02:01:20.759387Z","end":"2026-03-15T02:01:21.563638Z","steps":["trace[2113186696] 'read index received' (duration: 700.768355ms)","trace[2113186696] 'applied index is now lower than readState.Index' (duration: 103.480839ms)"],"step_count":2} {"level":"info","ts":"2026-03-15T02:01:21.563727Z","caller":"traceutil/trace.go:171","msg":"trace[1790394138] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"602.16001ms","start":"2026-03-15T02:01:20.961526Z","end":"2026-03-15T02:01:21.563686Z","steps":["trace[1790394138] 'process raft request' (duration: 578.288135ms)","trace[1790394138] 'compare' (duration: 23.729452ms)"],"step_count":2} {"level":"warn","ts":"2026-03-15T02:01:21.56378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.372198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-15T02:01:21.563816Z","caller":"traceutil/trace.go:171","msg":"trace[2027494945] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:512; }","duration":"804.4704ms","start":"2026-03-15T02:01:20.759335Z","end":"2026-03-15T02:01:21.563805Z","steps":["trace[2027494945] 'agreement among raft nodes before linearized reading' (duration: 804.388768ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:01:21.563848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:01:20.759313Z","time spent":"804.527571ms","remote":"127.0.0.1:45424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-15T02:01:21.56389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:01:20.961497Z","time spent":"602.298913ms","remote":"127.0.0.1:45678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-15T02:01:26.312204Z","caller":"traceutil/trace.go:171","msg":"trace[566602142] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"127.963832ms","start":"2026-03-15T02:01:26.18417Z","end":"2026-03-15T02:01:26.312134Z","steps":["trace[566602142] 'process raft request' (duration: 127.607733ms)"],"step_count":1} {"level":"info","ts":"2026-03-15T02:01:27.092875Z","caller":"traceutil/trace.go:171","msg":"trace[1640512356] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"247.855348ms","start":"2026-03-15T02:01:26.84498Z","end":"2026-03-15T02:01:27.092835Z","steps":["trace[1640512356] 'process raft request' (duration: 247.610682ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:01:28.259524Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133375364609681,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-15T02:01:28.341218Z","caller":"traceutil/trace.go:171","msg":"trace[1079747779] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"582.303985ms","start":"2026-03-15T02:01:27.758873Z","end":"2026-03-15T02:01:28.341177Z","steps":["trace[1079747779] 'read index received' (duration: 582.1264ms)","trace[1079747779] 'applied index is now lower than readState.Index' (duration: 175.925µs)"],"step_count":2} {"level":"info","ts":"2026-03-15T02:01:28.341298Z","caller":"traceutil/trace.go:171","msg":"trace[1269094025] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"851.790343ms","start":"2026-03-15T02:01:27.489457Z","end":"2026-03-15T02:01:28.341248Z","steps":["trace[1269094025] 'process raft request' (duration: 851.495466ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:01:28.341424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.53574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-15T02:01:28.341503Z","caller":"traceutil/trace.go:171","msg":"trace[892589648] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"582.665965ms","start":"2026-03-15T02:01:27.758812Z","end":"2026-03-15T02:01:28.341478Z","steps":["trace[892589648] 'agreement among raft nodes before linearized reading' (duration: 582.534391ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:01:28.341523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:01:27.489404Z","time spent":"851.998388ms","remote":"127.0.0.1:45678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-15T02:01:28.341543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:01:27.758791Z","time spent":"582.743987ms","remote":"127.0.0.1:45424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-15T02:01:28.34176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.733664ms","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-15T02:01:28.341821Z","caller":"traceutil/trace.go:171","msg":"trace[2074254501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:530; }","duration":"330.908938ms","start":"2026-03-15T02:01:28.010895Z","end":"2026-03-15T02:01:28.341804Z","steps":["trace[2074254501] 'agreement among raft nodes before linearized reading' (duration: 330.506447ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:01:28.341871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:01:28.010878Z","time spent":"330.98144ms","remote":"127.0.0.1:45678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-15T02:01:28.859735Z","caller":"traceutil/trace.go:171","msg":"trace[1641791084] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"512.824221ms","start":"2026-03-15T02:01:28.346888Z","end":"2026-03-15T02:01:28.859712Z","steps":["trace[1641791084] 'process raft request' (duration: 512.421099ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:01:28.859903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:01:28.346871Z","time spent":"512.951354ms","remote":"127.0.0.1:45678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-15T02:01:28.862524Z","caller":"traceutil/trace.go:171","msg":"trace[1334680677] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"515.035546ms","start":"2026-03-15T02:01:28.347463Z","end":"2026-03-15T02:01:28.862498Z","steps":["trace[1334680677] 'process raft request' (duration: 514.792489ms)"],"step_count":1} {"level":"info","ts":"2026-03-15T02:01:28.862592Z","caller":"traceutil/trace.go:171","msg":"trace[1113178289] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"511.580239ms","start":"2026-03-15T02:01:28.350998Z","end":"2026-03-15T02:01:28.862578Z","steps":["trace[1113178289] 'process raft request' (duration: 511.332332ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:01:28.862666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:01:28.347446Z","time spent":"515.147719ms","remote":"127.0.0.1:45678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-15T02:01:28.862665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:01:28.350975Z","time spent":"511.65103ms","remote":"127.0.0.1:45678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-15T02:01:42.216339Z","caller":"traceutil/trace.go:171","msg":"trace[1687943667] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"100.991326ms","start":"2026-03-15T02:01:42.115327Z","end":"2026-03-15T02:01:42.216318Z","steps":["trace[1687943667] 'process raft request' (duration: 100.875793ms)"],"step_count":1} {"level":"info","ts":"2026-03-15T02:01:48.827949Z","caller":"traceutil/trace.go:171","msg":"trace[758330710] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:741; }","duration":"313.808477ms","start":"2026-03-15T02:01:48.514114Z","end":"2026-03-15T02:01:48.827923Z","steps":["trace[758330710] 'read index received' (duration: 313.777035ms)","trace[758330710] 'applied index is now lower than readState.Index' (duration: 30.412µs)"],"step_count":2} {"level":"info","ts":"2026-03-15T02:01:48.828059Z","caller":"traceutil/trace.go:171","msg":"trace[1064283582] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"348.242369ms","start":"2026-03-15T02:01:48.479785Z","end":"2026-03-15T02:01:48.828027Z","steps":["trace[1064283582] 'process raft request' (duration: 348.001742ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:01:48.828251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:01:48.479765Z","time spent":"348.409074ms","remote":"127.0.0.1:45678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-15T02:01:48.828254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.130625ms","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-03-15T02:01:48.828321Z","caller":"traceutil/trace.go:171","msg":"trace[277800576] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:717; }","duration":"314.247477ms","start":"2026-03-15T02:01:48.514055Z","end":"2026-03-15T02:01:48.828303Z","steps":["trace[277800576] 'agreement among raft nodes before linearized reading' (duration: 314.039542ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:01:48.828371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:01:48.514038Z","time spent":"314.32424ms","remote":"127.0.0.1:45678","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-03-15T02:01:49.197783Z","caller":"traceutil/trace.go:171","msg":"trace[1268091048] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"508.915181ms","start":"2026-03-15T02:01:48.688835Z","end":"2026-03-15T02:01:49.19775Z","steps":["trace[1268091048] 'process raft request' (duration: 503.385751ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:01:49.197921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:01:48.688811Z","time spent":"509.044174ms","remote":"127.0.0.1:45478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":813,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-15T02:01:49.203102Z","caller":"traceutil/trace.go:171","msg":"trace[1163473263] linearizableReadLoop","detail":"{readStateIndex:744; appliedIndex:742; }","duration":"240.173097ms","start":"2026-03-15T02:01:48.9629Z","end":"2026-03-15T02:01:49.203073Z","steps":["trace[1163473263] 'read index received' (duration: 229.293391ms)","trace[1163473263] 'applied index is now lower than readState.Index' (duration: 10.878806ms)"],"step_count":2} {"level":"info","ts":"2026-03-15T02:01:49.203197Z","caller":"traceutil/trace.go:171","msg":"trace[1305430408] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"371.087157ms","start":"2026-03-15T02:01:48.832081Z","end":"2026-03-15T02:01:49.203168Z","steps":["trace[1305430408] 'process raft request' (duration: 370.82075ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:01:49.203338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.418474ms","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-15T02:01:49.203359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.968073ms","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-15T02:01:49.203382Z","caller":"traceutil/trace.go:171","msg":"trace[1215175807] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:719; }","duration":"240.511307ms","start":"2026-03-15T02:01:48.962858Z","end":"2026-03-15T02:01:49.203369Z","steps":["trace[1215175807] 'agreement among raft nodes before linearized reading' (duration: 240.354142ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:01:49.203373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:01:48.83206Z","time spent":"371.22555ms","remote":"127.0.0.1:45678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-15T02:01:49.20339Z","caller":"traceutil/trace.go:171","msg":"trace[1183552633] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:719; }","duration":"240.044055ms","start":"2026-03-15T02:01:48.963338Z","end":"2026-03-15T02:01:49.203382Z","steps":["trace[1183552633] 'agreement among raft nodes before linearized reading' (duration: 239.930431ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:01:49.203962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.337143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-15T02:01:49.204154Z","caller":"traceutil/trace.go:171","msg":"trace[735360015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:719; }","duration":"240.567458ms","start":"2026-03-15T02:01:48.963561Z","end":"2026-03-15T02:01:49.204129Z","steps":["trace[735360015] 'agreement among raft nodes before linearized reading' (duration: 239.794409ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:01:50.260397Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133375364610046,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-15T02:01:50.343367Z","caller":"traceutil/trace.go:171","msg":"trace[961914865] linearizableReadLoop","detail":"{readStateIndex:748; appliedIndex:747; }","duration":"583.185884ms","start":"2026-03-15T02:01:49.760152Z","end":"2026-03-15T02:01:50.343338Z","steps":["trace[961914865] 'read index received' (duration: 500.329915ms)","trace[961914865] 'applied index is now lower than readState.Index' (duration: 82.854559ms)"],"step_count":2} {"level":"warn","ts":"2026-03-15T02:01:50.343455Z","caller":"etcdserver/v3_server.go:874","msg":"ignored out-of-date read index response; local node read indexes queueing up and waiting to be in sync with leader","sent-request-id":3939133375364610051,"received-request-id":3939133375364610046} {"level":"warn","ts":"2026-03-15T02:01:50.343526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.36524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-15T02:01:50.343591Z","caller":"traceutil/trace.go:171","msg":"trace[407901528] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:722; }","duration":"583.485643ms","start":"2026-03-15T02:01:49.760092Z","end":"2026-03-15T02:01:50.343577Z","steps":["trace[407901528] 'agreement among raft nodes before linearized reading' (duration: 583.356769ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:01:50.343624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:01:49.760068Z","time spent":"583.551224ms","remote":"127.0.0.1:45424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-15T02:01:50.343623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.496781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-15T02:01:50.34366Z","caller":"traceutil/trace.go:171","msg":"trace[1981798709] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:722; }","duration":"504.558902ms","start":"2026-03-15T02:01:49.839091Z","end":"2026-03-15T02:01:50.343649Z","steps":["trace[1981798709] 'agreement among raft nodes before linearized reading' (duration: 504.46398ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:01:50.343705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:01:49.839045Z","time spent":"504.646554ms","remote":"127.0.0.1:45716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-03-15T02:02:11.145971Z","caller":"traceutil/trace.go:171","msg":"trace[2072228353] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"193.457195ms","start":"2026-03-15T02:02:10.952478Z","end":"2026-03-15T02:02:11.145935Z","steps":["trace[2072228353] 'process raft request' (duration: 193.301371ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:11.694786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.222461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-15T02:02:11.694821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.683147ms","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-15T02:02:11.694819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.890463ms","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-03-15T02:02:11.694869Z","caller":"traceutil/trace.go:171","msg":"trace[1384202287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:785; }","duration":"384.339774ms","start":"2026-03-15T02:02:11.310508Z","end":"2026-03-15T02:02:11.694848Z","steps":["trace[1384202287] 'range keys from in-memory index tree' (duration: 384.069107ms)"],"step_count":1} {"level":"info","ts":"2026-03-15T02:02:11.694907Z","caller":"traceutil/trace.go:171","msg":"trace[1026505617] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:785; }","duration":"383.836041ms","start":"2026-03-15T02:02:11.311049Z","end":"2026-03-15T02:02:11.694885Z","steps":["trace[1026505617] 'range keys from in-memory index tree' (duration: 383.485002ms)"],"step_count":1} {"level":"info","ts":"2026-03-15T02:02:11.694922Z","caller":"traceutil/trace.go:171","msg":"trace[221582724] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:785; }","duration":"398.992615ms","start":"2026-03-15T02:02:11.295903Z","end":"2026-03-15T02:02:11.694895Z","steps":["trace[221582724] 'range keys from in-memory index tree' (duration: 398.716368ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:11.69493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:02:11.310498Z","time spent":"384.423416ms","remote":"127.0.0.1:45678","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-15T02:02:11.694951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:02:11.311029Z","time spent":"383.912473ms","remote":"127.0.0.1:45678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-15T02:02:11.694972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:02:11.295841Z","time spent":"399.116868ms","remote":"127.0.0.1:45678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-15T02:02:11.694946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.252181ms","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-15T02:02:11.695039Z","caller":"traceutil/trace.go:171","msg":"trace[358925862] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:785; }","duration":"384.405845ms","start":"2026-03-15T02:02:11.31061Z","end":"2026-03-15T02:02:11.695016Z","steps":["trace[358925862] 'range keys from in-memory index tree' (duration: 383.996935ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:11.69508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:02:11.310572Z","time spent":"384.500328ms","remote":"127.0.0.1:45678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-15T02:02:13.35104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.183769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-15T02:02:13.351083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.301862ms","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-15T02:02:13.351137Z","caller":"traceutil/trace.go:171","msg":"trace[2126425023] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:790; }","duration":"255.339403ms","start":"2026-03-15T02:02:13.095775Z","end":"2026-03-15T02:02:13.351115Z","steps":["trace[2126425023] 'count revisions from in-memory index tree' (duration: 255.053765ms)"],"step_count":1} {"level":"info","ts":"2026-03-15T02:02:13.351149Z","caller":"traceutil/trace.go:171","msg":"trace[1209523257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:790; }","duration":"201.400185ms","start":"2026-03-15T02:02:13.149732Z","end":"2026-03-15T02:02:13.351132Z","steps":["trace[1209523257] 'range keys from in-memory index tree' (duration: 201.172029ms)"],"step_count":1} {"level":"info","ts":"2026-03-15T02:02:23.580511Z","caller":"traceutil/trace.go:171","msg":"trace[1866146948] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"133.351501ms","start":"2026-03-15T02:02:23.447128Z","end":"2026-03-15T02:02:23.58048Z","steps":["trace[1866146948] 'process raft request' (duration: 133.160416ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:25.201744Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133375364610438,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-15T02:02:25.37019Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.366513564s","expected-duration":"1s"} {"level":"info","ts":"2026-03-15T02:02:25.370803Z","caller":"traceutil/trace.go:171","msg":"trace[696063810] linearizableReadLoop","detail":"{readStateIndex:909; appliedIndex:908; }","duration":"669.275828ms","start":"2026-03-15T02:02:24.701505Z","end":"2026-03-15T02:02:25.370781Z","steps":["trace[696063810] 'read index received' (duration: 668.831777ms)","trace[696063810] 'applied index is now lower than readState.Index' (duration: 442.711µs)"],"step_count":2} {"level":"warn","ts":"2026-03-15T02:02:25.370935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.401481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-15T02:02:25.370902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:02:24.003523Z","time spent":"1.367368716s","remote":"127.0.0.1:45434","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-15T02:02:25.370975Z","caller":"traceutil/trace.go:171","msg":"trace[1350276650] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:874; }","duration":"669.486452ms","start":"2026-03-15T02:02:24.701477Z","end":"2026-03-15T02:02:25.370964Z","steps":["trace[1350276650] 'agreement among raft nodes before linearized reading' (duration: 669.39554ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:25.37097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.56152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-15T02:02:25.371306Z","caller":"traceutil/trace.go:171","msg":"trace[1057708166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:874; }","duration":"614.93368ms","start":"2026-03-15T02:02:24.756356Z","end":"2026-03-15T02:02:25.37129Z","steps":["trace[1057708166] 'agreement among raft nodes before linearized reading' (duration: 614.54757ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:25.371283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:02:24.701463Z","time spent":"669.77131ms","remote":"127.0.0.1:45720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-03-15T02:02:25.371342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:02:24.756342Z","time spent":"614.993401ms","remote":"127.0.0.1:45424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-15T02:02:25.480166Z","caller":"traceutil/trace.go:171","msg":"trace[492649436] linearizableReadLoop","detail":"{readStateIndex:910; appliedIndex:909; }","duration":"107.809952ms","start":"2026-03-15T02:02:25.372328Z","end":"2026-03-15T02:02:25.480137Z","steps":["trace[492649436] 'read index received' (duration: 101.191625ms)","trace[492649436] 'applied index is now lower than readState.Index' (duration: 6.617137ms)"],"step_count":2} {"level":"info","ts":"2026-03-15T02:02:25.480197Z","caller":"traceutil/trace.go:171","msg":"trace[1324258539] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"108.014978ms","start":"2026-03-15T02:02:25.372135Z","end":"2026-03-15T02:02:25.48015Z","steps":["trace[1324258539] 'process raft request' (duration: 101.339529ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:25.480505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.171302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-15T02:02:25.480596Z","caller":"traceutil/trace.go:171","msg":"trace[634274926] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:875; }","duration":"108.299215ms","start":"2026-03-15T02:02:25.372267Z","end":"2026-03-15T02:02:25.480567Z","steps":["trace[634274926] 'agreement among raft nodes before linearized reading' (duration: 107.976977ms)"],"step_count":1} {"level":"info","ts":"2026-03-15T02:02:39.813978Z","caller":"traceutil/trace.go:171","msg":"trace[1791067110] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"166.697317ms","start":"2026-03-15T02:02:39.647249Z","end":"2026-03-15T02:02:39.813947Z","steps":["trace[1791067110] 'process raft request' (duration: 166.442061ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:40.830533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.541405ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-03-15T02:02:44.106056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.290479ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-15T02:02:44.106425Z","caller":"traceutil/trace.go:171","msg":"trace[1715491157] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:991; }","duration":"188.944949ms","start":"2026-03-15T02:02:43.917452Z","end":"2026-03-15T02:02:44.106397Z","steps":["trace[1715491157] 'read index received' (duration: 44.20091ms)","trace[1715491157] 'applied index is now lower than readState.Index' (duration: 144.743179ms)"],"step_count":2} {"level":"info","ts":"2026-03-15T02:02:44.106611Z","caller":"traceutil/trace.go:171","msg":"trace[141429667] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"205.172452ms","start":"2026-03-15T02:02:43.901399Z","end":"2026-03-15T02:02:44.106571Z","steps":["trace[141429667] 'process raft request' (duration: 204.933786ms)"],"step_count":1} {"level":"info","ts":"2026-03-15T02:02:44.106877Z","caller":"traceutil/trace.go:171","msg":"trace[184626104] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"206.073124ms","start":"2026-03-15T02:02:43.900741Z","end":"2026-03-15T02:02:44.106814Z","steps":["trace[184626104] 'process raft request' (duration: 60.857722ms)","trace[184626104] 'compare' (duration: 144.200717ms)"],"step_count":2} {"level":"warn","ts":"2026-03-15T02:02:44.107703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.193452ms","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-03-15T02:02:44.107768Z","caller":"traceutil/trace.go:171","msg":"trace[206799548] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:955; }","duration":"190.338805ms","start":"2026-03-15T02:02:43.917414Z","end":"2026-03-15T02:02:44.107753Z","steps":["trace[206799548] 'agreement among raft nodes before linearized reading' (duration: 189.072553ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:44.111425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.034114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.70\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-03-15T02:02:44.111505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.607655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-15T02:02:44.111543Z","caller":"traceutil/trace.go:171","msg":"trace[293906515] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:955; }","duration":"185.685938ms","start":"2026-03-15T02:02:43.925847Z","end":"2026-03-15T02:02:44.111533Z","steps":["trace[293906515] 'agreement among raft nodes before linearized reading' (duration: 185.398551ms)"],"step_count":1} {"level":"info","ts":"2026-03-15T02:02:44.111682Z","caller":"traceutil/trace.go:171","msg":"trace[441279884] range","detail":"{range_begin:/registry/masterleases/162.253.55.70; range_end:; response_count:1; response_revision:955; }","duration":"107.129195ms","start":"2026-03-15T02:02:44.004344Z","end":"2026-03-15T02:02:44.111473Z","steps":["trace[441279884] 'agreement among raft nodes before linearized reading' (duration: 107.015932ms)"],"step_count":1} {"level":"info","ts":"2026-03-15T02:02:44.316803Z","caller":"traceutil/trace.go:171","msg":"trace[104242072] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"203.373596ms","start":"2026-03-15T02:02:44.113403Z","end":"2026-03-15T02:02:44.316776Z","steps":["trace[104242072] 'process raft request' (duration: 129.516514ms)","trace[104242072] 'compare' (duration: 73.626286ms)"],"step_count":2} {"level":"info","ts":"2026-03-15T02:02:44.31716Z","caller":"traceutil/trace.go:171","msg":"trace[2115664173] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"201.829217ms","start":"2026-03-15T02:02:44.115304Z","end":"2026-03-15T02:02:44.317133Z","steps":["trace[2115664173] 'process raft request' (duration: 201.707944ms)"],"step_count":1} {"level":"info","ts":"2026-03-15T02:02:46.513304Z","caller":"traceutil/trace.go:171","msg":"trace[1829165906] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:997; }","duration":"399.917398ms","start":"2026-03-15T02:02:46.113359Z","end":"2026-03-15T02:02:46.513276Z","steps":["trace[1829165906] 'read index received' (duration: 399.699572ms)","trace[1829165906] 'applied index is now lower than readState.Index' (duration: 216.646µs)"],"step_count":2} {"level":"info","ts":"2026-03-15T02:02:46.513449Z","caller":"traceutil/trace.go:171","msg":"trace[111910668] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"615.635417ms","start":"2026-03-15T02:02:45.897799Z","end":"2026-03-15T02:02:46.513434Z","steps":["trace[111910668] 'process raft request' (duration: 615.330779ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:46.513597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:02:45.897774Z","time spent":"615.712129ms","remote":"127.0.0.1:45678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-15T02:02:46.514038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.558685ms","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-03-15T02:02:46.514119Z","caller":"traceutil/trace.go:171","msg":"trace[1684141633] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:959; }","duration":"400.693939ms","start":"2026-03-15T02:02:46.113407Z","end":"2026-03-15T02:02:46.514101Z","steps":["trace[1684141633] 'agreement among raft nodes before linearized reading' (duration: 400.495573ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:46.514165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:02:46.113376Z","time spent":"400.779981ms","remote":"127.0.0.1:45678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-15T02:02:46.514196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.88506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-15T02:02:46.514266Z","caller":"traceutil/trace.go:171","msg":"trace[986628605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:959; }","duration":"193.008213ms","start":"2026-03-15T02:02:46.321239Z","end":"2026-03-15T02:02:46.514247Z","steps":["trace[986628605] 'agreement among raft nodes before linearized reading' (duration: 192.824659ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:46.514427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.69734ms","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-03-15T02:02:46.514465Z","caller":"traceutil/trace.go:171","msg":"trace[2117684555] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:959; }","duration":"193.736071ms","start":"2026-03-15T02:02:46.32072Z","end":"2026-03-15T02:02:46.514456Z","steps":["trace[2117684555] 'agreement among raft nodes before linearized reading' (duration: 193.625839ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:46.514604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.200821ms","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-15T02:02:46.514735Z","caller":"traceutil/trace.go:171","msg":"trace[1411132586] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:959; }","duration":"401.388397ms","start":"2026-03-15T02:02:46.113324Z","end":"2026-03-15T02:02:46.514712Z","steps":["trace[1411132586] 'agreement among raft nodes before linearized reading' (duration: 401.017018ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:46.514871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:02:46.113309Z","time spent":"401.54467ms","remote":"127.0.0.1:45678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-15T02:02:46.810306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.609761ms","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-03-15T02:02:46.810444Z","caller":"traceutil/trace.go:171","msg":"trace[774196008] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:959; }","duration":"214.755294ms","start":"2026-03-15T02:02:46.59566Z","end":"2026-03-15T02:02:46.810416Z","steps":["trace[774196008] 'range keys from in-memory index tree' (duration: 214.59014ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:46.810706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.620024ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-15T02:02:46.810825Z","caller":"traceutil/trace.go:171","msg":"trace[1313376420] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"292.167977ms","start":"2026-03-15T02:02:46.51864Z","end":"2026-03-15T02:02:46.810808Z","steps":["trace[1313376420] 'process raft request' (duration: 181.481211ms)","trace[1313376420] 'compare' (duration: 110.212094ms)"],"step_count":2} {"level":"info","ts":"2026-03-15T02:02:46.887939Z","caller":"traceutil/trace.go:171","msg":"trace[693713121] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:998; }","duration":"289.480789ms","start":"2026-03-15T02:02:46.598432Z","end":"2026-03-15T02:02:46.887913Z","steps":["trace[693713121] 'read index received' (duration: 101.703698ms)","trace[693713121] 'applied index is now lower than readState.Index' (duration: 187.775811ms)"],"step_count":2} {"level":"info","ts":"2026-03-15T02:02:46.888198Z","caller":"traceutil/trace.go:171","msg":"trace[920378888] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"369.448036ms","start":"2026-03-15T02:02:46.518704Z","end":"2026-03-15T02:02:46.888152Z","steps":["trace[920378888] 'process raft request' (duration: 368.979624ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:46.888761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.026337ms","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-03-15T02:02:46.888801Z","caller":"traceutil/trace.go:171","msg":"trace[1620682674] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:963; }","duration":"276.082119ms","start":"2026-03-15T02:02:46.612709Z","end":"2026-03-15T02:02:46.888791Z","steps":["trace[1620682674] 'agreement among raft nodes before linearized reading' (duration: 276.016177ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:46.888908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:02:46.518678Z","time spent":"370.078442ms","remote":"127.0.0.1:45678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-15T02:02:46.889043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.162269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-15T02:02:46.889084Z","caller":"traceutil/trace.go:171","msg":"trace[1789492405] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:963; }","duration":"143.235701ms","start":"2026-03-15T02:02:46.745836Z","end":"2026-03-15T02:02:46.889072Z","steps":["trace[1789492405] 'agreement among raft nodes before linearized reading' (duration: 143.169249ms)"],"step_count":1} {"level":"info","ts":"2026-03-15T02:02:46.88831Z","caller":"traceutil/trace.go:171","msg":"trace[1504199763] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"368.883032ms","start":"2026-03-15T02:02:46.519391Z","end":"2026-03-15T02:02:46.888274Z","steps":["trace[1504199763] 'process raft request' (duration: 368.447171ms)"],"step_count":1} {"level":"info","ts":"2026-03-15T02:02:46.888487Z","caller":"traceutil/trace.go:171","msg":"trace[405090518] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"369.083457ms","start":"2026-03-15T02:02:46.519393Z","end":"2026-03-15T02:02:46.888476Z","steps":["trace[405090518] 'process raft request' (duration: 368.480442ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:46.889531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:02:46.519383Z","time spent":"369.96369ms","remote":"127.0.0.1:45678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-15T02:02:46.889648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:02:46.51938Z","time spent":"370.197296ms","remote":"127.0.0.1:45678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-15T02:02:46.888678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.195597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-03-15T02:02:46.88986Z","caller":"traceutil/trace.go:171","msg":"trace[2144759263] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:963; }","duration":"291.431868ms","start":"2026-03-15T02:02:46.598403Z","end":"2026-03-15T02:02:46.889835Z","steps":["trace[2144759263] 'agreement among raft nodes before linearized reading' (duration: 290.118514ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:47.133876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.658714ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-15T02:02:47.134047Z","caller":"traceutil/trace.go:171","msg":"trace[1153131009] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"236.25884ms","start":"2026-03-15T02:02:46.897761Z","end":"2026-03-15T02:02:47.13402Z","steps":["trace[1153131009] 'process raft request' (duration: 103.36252ms)","trace[1153131009] 'compare' (duration: 132.49423ms)"],"step_count":2} {"level":"info","ts":"2026-03-15T02:02:47.316525Z","caller":"traceutil/trace.go:171","msg":"trace[174666250] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"100.353624ms","start":"2026-03-15T02:02:47.216133Z","end":"2026-03-15T02:02:47.316487Z","steps":["trace[174666250] 'process raft request' (duration: 18.542871ms)","trace[174666250] 'compare' (duration: 81.646919ms)"],"step_count":2} {"level":"info","ts":"2026-03-15T02:02:47.746207Z","caller":"traceutil/trace.go:171","msg":"trace[1987647837] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"131.357491ms","start":"2026-03-15T02:02:47.614827Z","end":"2026-03-15T02:02:47.746185Z","steps":["trace[1987647837] 'process raft request' (duration: 131.209477ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:49.460885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.813259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-15T02:02:49.460967Z","caller":"traceutil/trace.go:171","msg":"trace[828560605] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:967; }","duration":"566.945713ms","start":"2026-03-15T02:02:48.894Z","end":"2026-03-15T02:02:49.460946Z","steps":["trace[828560605] 'range keys from in-memory index tree' (duration: 566.613834ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:49.461007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:02:48.893975Z","time spent":"567.022396ms","remote":"127.0.0.1:45678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-15T02:02:49.461022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.533824ms","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-03-15T02:02:49.46111Z","caller":"traceutil/trace.go:171","msg":"trace[140530494] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:967; }","duration":"566.673417ms","start":"2026-03-15T02:02:48.89442Z","end":"2026-03-15T02:02:49.461093Z","steps":["trace[140530494] 'range keys from in-memory index tree' (duration: 566.384759ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:49.461101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.731151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-15T02:02:49.461148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:02:48.894397Z","time spent":"566.742658ms","remote":"127.0.0.1:45678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-15T02:02:49.461146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.874006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-15T02:02:49.461169Z","caller":"traceutil/trace.go:171","msg":"trace[1579645850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:967; }","duration":"645.836574ms","start":"2026-03-15T02:02:48.815312Z","end":"2026-03-15T02:02:49.461149Z","steps":["trace[1579645850] 'range keys from in-memory index tree' (duration: 645.566616ms)"],"step_count":1} {"level":"info","ts":"2026-03-15T02:02:49.461194Z","caller":"traceutil/trace.go:171","msg":"trace[1592969169] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:967; }","duration":"565.939517ms","start":"2026-03-15T02:02:48.895241Z","end":"2026-03-15T02:02:49.46118Z","steps":["trace[1592969169] 'range keys from in-memory index tree' (duration: 565.775444ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:49.461231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:02:48.895233Z","time spent":"565.987588ms","remote":"127.0.0.1:45678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-15T02:02:49.461213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:02:48.815292Z","time spent":"645.909026ms","remote":"127.0.0.1:45678","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-15T02:02:49.524253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.995502ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-15T02:02:49.942691Z","caller":"traceutil/trace.go:171","msg":"trace[1021872178] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"476.394237ms","start":"2026-03-15T02:02:49.466271Z","end":"2026-03-15T02:02:49.942665Z","steps":["trace[1021872178] 'process raft request' (duration: 475.562136ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:49.942854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:02:49.466242Z","time spent":"476.524531ms","remote":"127.0.0.1:45678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-15T02:02:50.683208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.57364ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-15T02:02:50.683446Z","caller":"traceutil/trace.go:171","msg":"trace[1954770196] linearizableReadLoop","detail":"{readStateIndex:1011; appliedIndex:1007; }","duration":"925.348747ms","start":"2026-03-15T02:02:49.75808Z","end":"2026-03-15T02:02:50.683429Z","steps":["trace[1954770196] 'read index received' (duration: 183.854801ms)","trace[1954770196] 'applied index is now lower than readState.Index' (duration: 741.493146ms)"],"step_count":2} {"level":"info","ts":"2026-03-15T02:02:50.683552Z","caller":"traceutil/trace.go:171","msg":"trace[1318730732] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"1.217109953s","start":"2026-03-15T02:02:49.466419Z","end":"2026-03-15T02:02:50.683529Z","steps":["trace[1318730732] 'process raft request' (duration: 1.216886187s)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:50.683612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:02:49.466398Z","time spent":"1.217178915s","remote":"127.0.0.1:45678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-15T02:02:50.683653Z","caller":"traceutil/trace.go:171","msg":"trace[545720946] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"1.217220766s","start":"2026-03-15T02:02:49.466398Z","end":"2026-03-15T02:02:50.683619Z","steps":["trace[545720946] 'process raft request' (duration: 570.145945ms)","trace[545720946] 'compare' (duration: 646.284672ms)"],"step_count":2} {"level":"info","ts":"2026-03-15T02:02:50.683745Z","caller":"traceutil/trace.go:171","msg":"trace[1659719167] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"1.215667116s","start":"2026-03-15T02:02:49.468064Z","end":"2026-03-15T02:02:50.683731Z","steps":["trace[1659719167] 'process raft request' (duration: 1.215328068s)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:50.683765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.172202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-15T02:02:50.683814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:02:49.468051Z","time spent":"1.215725608s","remote":"127.0.0.1:45678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-15T02:02:50.683827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:02:49.466383Z","time spent":"1.217346579s","remote":"127.0.0.1:45678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-15T02:02:50.683839Z","caller":"traceutil/trace.go:171","msg":"trace[2054400951] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:971; }","duration":"327.296915ms","start":"2026-03-15T02:02:50.356522Z","end":"2026-03-15T02:02:50.683819Z","steps":["trace[2054400951] 'agreement among raft nodes before linearized reading' (duration: 327.161352ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:50.683682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.598002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-15T02:02:50.683895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:02:50.356504Z","time spent":"327.381238ms","remote":"127.0.0.1:45738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-03-15T02:02:50.68391Z","caller":"traceutil/trace.go:171","msg":"trace[2041544037] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:971; }","duration":"925.862059ms","start":"2026-03-15T02:02:49.758037Z","end":"2026-03-15T02:02:50.683899Z","steps":["trace[2041544037] 'agreement among raft nodes before linearized reading' (duration: 925.596733ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:50.683946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:02:49.758014Z","time spent":"925.92256ms","remote":"127.0.0.1:45424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-15T02:02:50.684007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.086644ms","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-15T02:02:50.684101Z","caller":"traceutil/trace.go:171","msg":"trace[1995962023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:971; }","duration":"124.258439ms","start":"2026-03-15T02:02:50.55982Z","end":"2026-03-15T02:02:50.684079Z","steps":["trace[1995962023] 'agreement among raft nodes before linearized reading' (duration: 123.936141ms)"],"step_count":1} {"level":"info","ts":"2026-03-15T02:02:52.876762Z","caller":"traceutil/trace.go:171","msg":"trace[284441655] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"181.835854ms","start":"2026-03-15T02:02:52.694896Z","end":"2026-03-15T02:02:52.876732Z","steps":["trace[284441655] 'process raft request' (duration: 174.208102ms)"],"step_count":1} {"level":"info","ts":"2026-03-15T02:02:52.876846Z","caller":"traceutil/trace.go:171","msg":"trace[1639197133] linearizableReadLoop","detail":"{readStateIndex:1030; appliedIndex:1028; }","duration":"101.049247ms","start":"2026-03-15T02:02:52.77577Z","end":"2026-03-15T02:02:52.876819Z","steps":["trace[1639197133] 'read index received' (duration: 93.326033ms)","trace[1639197133] 'applied index is now lower than readState.Index' (duration: 7.721184ms)"],"step_count":2} {"level":"info","ts":"2026-03-15T02:02:52.876862Z","caller":"traceutil/trace.go:171","msg":"trace[1408774100] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"180.826799ms","start":"2026-03-15T02:02:52.69599Z","end":"2026-03-15T02:02:52.876817Z","steps":["trace[1408774100] 'process raft request' (duration: 180.637854ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:52.877042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.118874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-15T02:02:52.877068Z","caller":"traceutil/trace.go:171","msg":"trace[1260832379] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:991; }","duration":"112.217927ms","start":"2026-03-15T02:02:52.764843Z","end":"2026-03-15T02:02:52.877061Z","steps":["trace[1260832379] 'agreement among raft nodes before linearized reading' (duration: 112.050863ms)"],"step_count":1} {"level":"info","ts":"2026-03-15T02:02:54.934492Z","caller":"traceutil/trace.go:171","msg":"trace[535923888] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"151.134174ms","start":"2026-03-15T02:02:54.783311Z","end":"2026-03-15T02:02:54.934445Z","steps":["trace[535923888] 'process raft request' (duration: 150.919808ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:55.754569Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133375364610747,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-15T02:02:56.255109Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133375364610747,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-15T02:02:56.336275Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.283897777s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-15T02:02:56.576651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.524155127s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-15T02:02:56.576859Z","caller":"traceutil/trace.go:171","msg":"trace[1875777795] linearizableReadLoop","detail":"{readStateIndex:1058; appliedIndex:1055; }","duration":"1.323261434s","start":"2026-03-15T02:02:55.25358Z","end":"2026-03-15T02:02:56.576841Z","steps":["trace[1875777795] 'read index received' (duration: 1.082813089s)","trace[1875777795] 'applied index is now lower than readState.Index' (duration: 240.447425ms)"],"step_count":2} {"level":"info","ts":"2026-03-15T02:02:56.576958Z","caller":"traceutil/trace.go:171","msg":"trace[1047906083] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"1.636843155s","start":"2026-03-15T02:02:54.940105Z","end":"2026-03-15T02:02:56.576948Z","steps":["trace[1047906083] 'process raft request' (duration: 1.63664451s)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:56.577028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:02:54.940083Z","time spent":"1.636907867s","remote":"127.0.0.1:45678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-15T02:02:56.577174Z","caller":"traceutil/trace.go:171","msg":"trace[1424554404] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"1.636345832s","start":"2026-03-15T02:02:54.940819Z","end":"2026-03-15T02:02:56.577165Z","steps":["trace[1424554404] 'process raft request' (duration: 1.635987884s)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:56.577228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:02:54.94081Z","time spent":"1.636391573s","remote":"127.0.0.1:45678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-15T02:02:56.577252Z","caller":"traceutil/trace.go:171","msg":"trace[776510256] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"1.637994334s","start":"2026-03-15T02:02:54.939226Z","end":"2026-03-15T02:02:56.57722Z","steps":["trace[776510256] 'process raft request' (duration: 113.143079ms)","trace[776510256] 'compare' (duration: 1.524031474s)"],"step_count":2} {"level":"warn","ts":"2026-03-15T02:02:56.577329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.340919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-03-15T02:02:56.577399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.142629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-03-15T02:02:56.577407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:02:54.939201Z","time spent":"1.638119827s","remote":"127.0.0.1:45678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-15T02:02:56.577439Z","caller":"traceutil/trace.go:171","msg":"trace[2113017241] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1017; }","duration":"803.18061ms","start":"2026-03-15T02:02:55.774246Z","end":"2026-03-15T02:02:56.577427Z","steps":["trace[2113017241] 'agreement among raft nodes before linearized reading' (duration: 803.054547ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:56.577447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.224957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-15T02:02:56.577475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:02:55.774178Z","time spent":"803.289593ms","remote":"127.0.0.1:45678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-15T02:02:56.577479Z","caller":"traceutil/trace.go:171","msg":"trace[965352789] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1017; }","duration":"818.35207ms","start":"2026-03-15T02:02:55.759118Z","end":"2026-03-15T02:02:56.57747Z","steps":["trace[965352789] 'agreement among raft nodes before linearized reading' (duration: 818.305569ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:56.577515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:02:55.759096Z","time spent":"818.412422ms","remote":"127.0.0.1:45424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-15T02:02:56.577399Z","caller":"traceutil/trace.go:171","msg":"trace[815558159] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1017; }","duration":"908.455922ms","start":"2026-03-15T02:02:55.668924Z","end":"2026-03-15T02:02:56.57738Z","steps":["trace[815558159] 'agreement among raft nodes before linearized reading' (duration: 908.271007ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:56.577557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:02:55.668902Z","time spent":"908.647757ms","remote":"127.0.0.1:45678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-03-15T02:02:56.577577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.324001262s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-15T02:02:56.577536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.436007ms","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-03-15T02:02:56.577614Z","caller":"traceutil/trace.go:171","msg":"trace[1797844333] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1017; }","duration":"1.324078014s","start":"2026-03-15T02:02:55.253523Z","end":"2026-03-15T02:02:56.577601Z","steps":["trace[1797844333] 'agreement among raft nodes before linearized reading' (duration: 1.323997642s)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:56.57765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:02:55.253501Z","time spent":"1.324140826s","remote":"127.0.0.1:45958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-03-15T02:02:56.577676Z","caller":"traceutil/trace.go:171","msg":"trace[909809358] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1017; }","duration":"613.649332ms","start":"2026-03-15T02:02:55.963987Z","end":"2026-03-15T02:02:56.577636Z","steps":["trace[909809358] 'agreement among raft nodes before linearized reading' (duration: 613.236022ms)"],"step_count":1} {"level":"warn","ts":"2026-03-15T02:02:56.577762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-15T02:02:55.963954Z","time spent":"613.776015ms","remote":"127.0.0.1:45678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-15T02:02:56.782955Z","caller":"traceutil/trace.go:171","msg":"trace[1278529933] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"200.287297ms","start":"2026-03-15T02:02:56.582645Z","end":"2026-03-15T02:02:56.782932Z","steps":["trace[1278529933] 'process raft request' (duration: 199.22212ms)"],"step_count":1} {"level":"info","ts":"2026-03-15T02:02:56.783663Z","caller":"traceutil/trace.go:171","msg":"trace[1509826056] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"200.619685ms","start":"2026-03-15T02:02:56.583019Z","end":"2026-03-15T02:02:56.783639Z","steps":["trace[1509826056] 'process raft request' (duration: 199.873907ms)"],"step_count":1} {"level":"info","ts":"2026-03-15T02:02:56.784093Z","caller":"traceutil/trace.go:171","msg":"trace[2094437825] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"200.939344ms","start":"2026-03-15T02:02:56.583119Z","end":"2026-03-15T02:02:56.784058Z","steps":["trace[2094437825] 'process raft request' (duration: 199.946108ms)"],"step_count":1} {"level":"info","ts":"2026-03-15T02:06:12.491542Z","caller":"traceutil/trace.go:171","msg":"trace[1077407132] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"247.127616ms","start":"2026-03-15T02:06:12.244378Z","end":"2026-03-15T02:06:12.491506Z","steps":["trace[1077407132] 'process raft request' (duration: 246.948462ms)"],"step_count":1}