{"level":"warn","ts":"2026-02-04T05:14:30.75196Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-04T05:14:30.752235Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.210:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.210:2380","--initial-cluster=instance=https://199.204.45.210:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.210:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.210: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-02-04T05:14:30.752429Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-04T05:14:30.752443Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-02-04T05:14:30.752585Z","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-02-04T05:14:30.755442Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"]} {"level":"info","ts":"2026-02-04T05:14:30.755595Z","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":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.210: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-02-04T05:14:30.765469Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.640534ms"} {"level":"info","ts":"2026-02-04T05:14:30.78329Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1cac722af8b2b8a6","cluster-id":"ba0a336ca61ee41d"} {"level":"info","ts":"2026-02-04T05:14:30.783369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=()"} {"level":"info","ts":"2026-02-04T05:14:30.783428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 0"} {"level":"info","ts":"2026-02-04T05:14:30.783464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1cac722af8b2b8a6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-04T05:14:30.783486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 1"} {"level":"info","ts":"2026-02-04T05:14:30.783536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"warn","ts":"2026-02-04T05:14:30.800681Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-04T05:14:30.804054Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-04T05:14:30.806337Z","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-02-04T05:14:30.81209Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1cac722af8b2b8a6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-04T05:14:30.818992Z","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-02-04T05:14:30.819235Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1cac722af8b2b8a6","initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-04T05:14:30.819256Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-04T05:14:30.819384Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1cac722af8b2b8a6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-04T05:14:30.819488Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-02-04T05:14:30.819498Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-02-04T05:14:30.820553Z","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-02-04T05:14:30.82084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"info","ts":"2026-02-04T05:14:30.820603Z","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-02-04T05:14:30.820923Z","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-02-04T05:14:30.821119Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","added-peer-id":"1cac722af8b2b8a6","added-peer-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-02-04T05:14:31.789389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 is starting a new election at term 1"} {"level":"info","ts":"2026-02-04T05:14:31.789477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-04T05:14:31.789501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgPreVoteResp from 1cac722af8b2b8a6 at term 1"} {"level":"info","ts":"2026-02-04T05:14:31.789521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became candidate at term 2"} {"level":"info","ts":"2026-02-04T05:14:31.789548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgVoteResp from 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-02-04T05:14:31.789568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became leader at term 2"} {"level":"info","ts":"2026-02-04T05:14:31.789584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1cac722af8b2b8a6 elected leader 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-02-04T05:14:31.800927Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1cac722af8b2b8a6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.210:2379]}","request-path":"/0/members/1cac722af8b2b8a6/attributes","cluster-id":"ba0a336ca61ee41d","publish-timeout":"7s"} {"level":"info","ts":"2026-02-04T05:14:31.800966Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-04T05:14:31.801001Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T05:14:31.801442Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-04T05:14:31.801469Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-04T05:14:31.806784Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.210:2379"} {"level":"info","ts":"2026-02-04T05:14:31.801022Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-04T05:14:31.807535Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T05:14:31.80832Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T05:14:31.808369Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T05:14:31.809634Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-04T05:14:33.369106Z","caller":"traceutil/trace.go:171","msg":"trace[1833094105] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"100.177852ms","start":"2026-02-04T05:14:33.268907Z","end":"2026-02-04T05:14:33.369085Z","steps":["trace[1833094105] 'process raft request' (duration: 99.987721ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:33.369678Z","caller":"traceutil/trace.go:171","msg":"trace[471878405] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"108.100982ms","start":"2026-02-04T05:14:33.261561Z","end":"2026-02-04T05:14:33.369662Z","steps":["trace[471878405] 'process raft request' (duration: 59.084764ms)","trace[471878405] 'compare' (duration: 48.140943ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:14:33.369671Z","caller":"traceutil/trace.go:171","msg":"trace[519151208] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"100.583184ms","start":"2026-02-04T05:14:33.269071Z","end":"2026-02-04T05:14:33.369654Z","steps":["trace[519151208] 'process raft request' (duration: 99.86866ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:33.370274Z","caller":"traceutil/trace.go:171","msg":"trace[760794834] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"101.144448ms","start":"2026-02-04T05:14:33.269117Z","end":"2026-02-04T05:14:33.370261Z","steps":["trace[760794834] 'process raft request' (duration: 99.859049ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:33.370624Z","caller":"traceutil/trace.go:171","msg":"trace[958347354] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"101.48368ms","start":"2026-02-04T05:14:33.269129Z","end":"2026-02-04T05:14:33.370612Z","steps":["trace[958347354] 'process raft request' (duration: 99.90995ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:33.370968Z","caller":"traceutil/trace.go:171","msg":"trace[1748763306] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"101.807602ms","start":"2026-02-04T05:14:33.269147Z","end":"2026-02-04T05:14:33.370954Z","steps":["trace[1748763306] 'process raft request' (duration: 99.91316ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:33.371303Z","caller":"traceutil/trace.go:171","msg":"trace[86617641] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"102.090324ms","start":"2026-02-04T05:14:33.269198Z","end":"2026-02-04T05:14:33.371288Z","steps":["trace[86617641] 'process raft request' (duration: 99.89005ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:33.371448Z","caller":"traceutil/trace.go:171","msg":"trace[1052982642] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:5; }","duration":"107.784931ms","start":"2026-02-04T05:14:33.26365Z","end":"2026-02-04T05:14:33.371435Z","steps":["trace[1052982642] 'read index received' (duration: 5.98198ms)","trace[1052982642] 'applied index is now lower than readState.Index' (duration: 101.801051ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:33.371973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.265224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-04T05:14:33.372244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.143991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-04T05:14:33.37363Z","caller":"traceutil/trace.go:171","msg":"trace[52645662] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"104.49531ms","start":"2026-02-04T05:14:33.269076Z","end":"2026-02-04T05:14:33.373571Z","steps":["trace[52645662] 'agreement among raft nodes before linearized reading' (duration: 103.141871ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:33.374012Z","caller":"traceutil/trace.go:171","msg":"trace[874637803] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:0; response_revision:11; }","duration":"110.382978ms","start":"2026-02-04T05:14:33.263617Z","end":"2026-02-04T05:14:33.374Z","steps":["trace[874637803] 'agreement among raft nodes before linearized reading' (duration: 108.198214ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:33.973413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.43901ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-02-04T05:14:33.973603Z","caller":"traceutil/trace.go:171","msg":"trace[452132145] transaction","detail":"{read_only:false; response_revision:89; number_of_response:1; }","duration":"174.172272ms","start":"2026-02-04T05:14:33.79941Z","end":"2026-02-04T05:14:33.973582Z","steps":["trace[452132145] 'process raft request' (duration: 34.984137ms)","trace[452132145] 'compare' (duration: 138.33755ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:34.228268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.658503ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-02-04T05:14:34.228519Z","caller":"traceutil/trace.go:171","msg":"trace[1133138801] transaction","detail":"{read_only:false; response_revision:94; number_of_response:1; }","duration":"163.217766ms","start":"2026-02-04T05:14:34.065283Z","end":"2026-02-04T05:14:34.228501Z","steps":["trace[1133138801] 'process raft request' (duration: 54.257101ms)","trace[1133138801] 'compare' (duration: 108.571223ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:14:34.441351Z","caller":"traceutil/trace.go:171","msg":"trace[106704955] transaction","detail":"{read_only:false; response_revision:101; number_of_response:1; }","duration":"119.388123ms","start":"2026-02-04T05:14:34.321927Z","end":"2026-02-04T05:14:34.441315Z","steps":["trace[106704955] 'process raft request' (duration: 49.565991ms)","trace[106704955] 'compare' (duration: 69.54203ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:14:34.441735Z","caller":"traceutil/trace.go:171","msg":"trace[1579969997] transaction","detail":"{read_only:false; response_revision:102; number_of_response:1; }","duration":"112.741229ms","start":"2026-02-04T05:14:34.328965Z","end":"2026-02-04T05:14:34.441706Z","steps":["trace[1579969997] 'process raft request' (duration: 112.199536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:34.903954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.217697ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-04T05:14:34.904402Z","caller":"traceutil/trace.go:171","msg":"trace[1378906996] transaction","detail":"{read_only:false; response_revision:108; number_of_response:1; }","duration":"399.586426ms","start":"2026-02-04T05:14:34.504795Z","end":"2026-02-04T05:14:34.904381Z","steps":["trace[1378906996] 'process raft request' (duration: 399.510816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:34.904659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:34.504791Z","time spent":"399.742347ms","remote":"127.0.0.1:52228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":777,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T05:14:34.9047Z","caller":"traceutil/trace.go:171","msg":"trace[1087426475] transaction","detail":"{read_only:false; response_revision:107; number_of_response:1; }","duration":"399.966549ms","start":"2026-02-04T05:14:34.504703Z","end":"2026-02-04T05:14:34.904669Z","steps":["trace[1087426475] 'process raft request' (duration: 130.959298ms)","trace[1087426475] 'compare' (duration: 267.980695ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:34.904938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:34.504692Z","time spent":"400.18861ms","remote":"127.0.0.1:52466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":621,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T05:14:35.22912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.787443ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-04T05:14:35.229448Z","caller":"traceutil/trace.go:171","msg":"trace[1132366219] transaction","detail":"{read_only:false; response_revision:111; number_of_response:1; }","duration":"308.571191ms","start":"2026-02-04T05:14:34.920854Z","end":"2026-02-04T05:14:35.229425Z","steps":["trace[1132366219] 'process raft request' (duration: 308.44126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:35.229548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:34.920846Z","time spent":"308.654771ms","remote":"127.0.0.1:52228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T05:14:35.229805Z","caller":"traceutil/trace.go:171","msg":"trace[1855388621] transaction","detail":"{read_only:false; response_revision:110; number_of_response:1; }","duration":"309.291276ms","start":"2026-02-04T05:14:34.920463Z","end":"2026-02-04T05:14:35.229755Z","steps":["trace[1855388621] 'process raft request' (duration: 87.797778ms)","trace[1855388621] 'compare' (duration: 220.665663ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:35.230009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:34.920451Z","time spent":"309.494087ms","remote":"127.0.0.1:52466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":665,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T05:14:35.506983Z","caller":"traceutil/trace.go:171","msg":"trace[222342513] linearizableReadLoop","detail":"{readStateIndex:122; appliedIndex:121; }","duration":"182.595657ms","start":"2026-02-04T05:14:35.324369Z","end":"2026-02-04T05:14:35.506964Z","steps":["trace[222342513] 'read index received' (duration: 2.542706ms)","trace[222342513] 'applied index is now lower than readState.Index' (duration: 180.052011ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:14:35.507027Z","caller":"traceutil/trace.go:171","msg":"trace[1113628108] transaction","detail":"{read_only:false; response_revision:117; number_of_response:1; }","duration":"199.181664ms","start":"2026-02-04T05:14:35.307822Z","end":"2026-02-04T05:14:35.507004Z","steps":["trace[1113628108] 'process raft request' (duration: 107.373853ms)","trace[1113628108] 'compare' (duration: 91.66527ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:35.507217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.830638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-04T05:14:35.507265Z","caller":"traceutil/trace.go:171","msg":"trace[778512022] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:117; }","duration":"182.913929ms","start":"2026-02-04T05:14:35.324339Z","end":"2026-02-04T05:14:35.507253Z","steps":["trace[778512022] 'agreement among raft nodes before linearized reading' (duration: 182.806568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:35.507356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.944543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-04T05:14:35.50739Z","caller":"traceutil/trace.go:171","msg":"trace[985722059] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:117; }","duration":"179.001174ms","start":"2026-02-04T05:14:35.328378Z","end":"2026-02-04T05:14:35.507379Z","steps":["trace[985722059] 'agreement among raft nodes before linearized reading' (duration: 178.947733ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:35.648581Z","caller":"traceutil/trace.go:171","msg":"trace[1896547101] transaction","detail":"{read_only:false; response_revision:119; number_of_response:1; }","duration":"135.922957ms","start":"2026-02-04T05:14:35.512626Z","end":"2026-02-04T05:14:35.648549Z","steps":["trace[1896547101] 'process raft request' (duration: 107.439504ms)","trace[1896547101] 'compare' (duration: 28.264982ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:14:35.648754Z","caller":"traceutil/trace.go:171","msg":"trace[1851443480] linearizableReadLoop","detail":"{readStateIndex:126; appliedIndex:123; }","duration":"119.826232ms","start":"2026-02-04T05:14:35.528912Z","end":"2026-02-04T05:14:35.648738Z","steps":["trace[1851443480] 'read index received' (duration: 91.115548ms)","trace[1851443480] 'applied index is now lower than readState.Index' (duration: 28.709864ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:35.649027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.057304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-04T05:14:35.650474Z","caller":"traceutil/trace.go:171","msg":"trace[1521367192] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:121; }","duration":"121.520863ms","start":"2026-02-04T05:14:35.528886Z","end":"2026-02-04T05:14:35.650407Z","steps":["trace[1521367192] 'agreement among raft nodes before linearized reading' (duration: 120.032044ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:35.649114Z","caller":"traceutil/trace.go:171","msg":"trace[1654494737] transaction","detail":"{read_only:false; response_revision:120; number_of_response:1; }","duration":"134.691079ms","start":"2026-02-04T05:14:35.514409Z","end":"2026-02-04T05:14:35.6491Z","steps":["trace[1654494737] 'process raft request' (duration: 134.007214ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:35.649364Z","caller":"traceutil/trace.go:171","msg":"trace[895257317] transaction","detail":"{read_only:false; response_revision:121; number_of_response:1; }","duration":"134.800859ms","start":"2026-02-04T05:14:35.514549Z","end":"2026-02-04T05:14:35.64935Z","steps":["trace[895257317] 'process raft request' (duration: 134.131525ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:35.766086Z","caller":"traceutil/trace.go:171","msg":"trace[1695987155] transaction","detail":"{read_only:false; response_revision:123; number_of_response:1; }","duration":"106.182925ms","start":"2026-02-04T05:14:35.659866Z","end":"2026-02-04T05:14:35.766049Z","steps":["trace[1695987155] 'process raft request' (duration: 93.701404ms)","trace[1695987155] 'compare' (duration: 12.009958ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:36.35685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.9863ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:14:36.356923Z","caller":"traceutil/trace.go:171","msg":"trace[1198186201] linearizableReadLoop","detail":"{readStateIndex:133; appliedIndex:132; }","duration":"349.425974ms","start":"2026-02-04T05:14:36.007485Z","end":"2026-02-04T05:14:36.356911Z","steps":["trace[1198186201] 'read index received' (duration: 66.14µs)","trace[1198186201] 'applied index is now lower than readState.Index' (duration: 349.357254ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:14:36.357092Z","caller":"traceutil/trace.go:171","msg":"trace[1401244727] transaction","detail":"{read_only:false; response_revision:128; number_of_response:1; }","duration":"532.330242ms","start":"2026-02-04T05:14:35.824754Z","end":"2026-02-04T05:14:36.357084Z","steps":["trace[1401244727] 'process raft request' (duration: 59.04603ms)","trace[1401244727] 'compare' (duration: 472.867349ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:36.357227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:35.824739Z","time spent":"532.379613ms","remote":"127.0.0.1:52466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1917,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T05:14:36.357418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.936417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:14:36.357443Z","caller":"traceutil/trace.go:171","msg":"trace[2092411836] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:128; }","duration":"350.016737ms","start":"2026-02-04T05:14:36.007417Z","end":"2026-02-04T05:14:36.357434Z","steps":["trace[2092411836] 'agreement among raft nodes before linearized reading' (duration: 349.956396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:36.357508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:36.007379Z","time spent":"350.122708ms","remote":"127.0.0.1:52270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"info","ts":"2026-02-04T05:14:36.607053Z","caller":"traceutil/trace.go:171","msg":"trace[646432047] transaction","detail":"{read_only:false; response_revision:130; number_of_response:1; }","duration":"229.241502ms","start":"2026-02-04T05:14:36.377777Z","end":"2026-02-04T05:14:36.607019Z","steps":["trace[646432047] 'process raft request' (duration: 178.696198ms)","trace[646432047] 'compare' (duration: 49.460238ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:14:37.006701Z","caller":"traceutil/trace.go:171","msg":"trace[967923319] transaction","detail":"{read_only:false; response_revision:137; number_of_response:1; }","duration":"212.261243ms","start":"2026-02-04T05:14:36.794412Z","end":"2026-02-04T05:14:37.006673Z","steps":["trace[967923319] 'process raft request' (duration: 116.649348ms)","trace[967923319] 'compare' (duration: 95.434624ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:14:37.803372Z","caller":"traceutil/trace.go:171","msg":"trace[1031660840] linearizableReadLoop","detail":"{readStateIndex:158; appliedIndex:157; }","duration":"143.234166ms","start":"2026-02-04T05:14:37.660115Z","end":"2026-02-04T05:14:37.803349Z","steps":["trace[1031660840] 'read index received' (duration: 143.046905ms)","trace[1031660840] 'applied index is now lower than readState.Index' (duration: 186.501µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:37.803823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.039365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-04T05:14:37.803941Z","caller":"traceutil/trace.go:171","msg":"trace[757735899] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:153; }","duration":"143.211286ms","start":"2026-02-04T05:14:37.660711Z","end":"2026-02-04T05:14:37.803923Z","steps":["trace[757735899] 'agreement among raft nodes before linearized reading' (duration: 142.937964ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:37.803856Z","caller":"traceutil/trace.go:171","msg":"trace[1903240802] transaction","detail":"{read_only:false; response_revision:153; number_of_response:1; }","duration":"154.029985ms","start":"2026-02-04T05:14:37.64981Z","end":"2026-02-04T05:14:37.80384Z","steps":["trace[1903240802] 'process raft request' (duration: 153.402282ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:37.804299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.177552ms","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-02-04T05:14:37.804385Z","caller":"traceutil/trace.go:171","msg":"trace[808846062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:153; }","duration":"144.294353ms","start":"2026-02-04T05:14:37.66008Z","end":"2026-02-04T05:14:37.804375Z","steps":["trace[808846062] 'agreement among raft nodes before linearized reading' (duration: 144.152942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:38.126546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.519934ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:14:38.126964Z","caller":"traceutil/trace.go:171","msg":"trace[1348454733] linearizableReadLoop","detail":"{readStateIndex:161; appliedIndex:159; }","duration":"312.103764ms","start":"2026-02-04T05:14:37.814844Z","end":"2026-02-04T05:14:38.126947Z","steps":["trace[1348454733] 'read index received' (duration: 151.153137ms)","trace[1348454733] 'applied index is now lower than readState.Index' (duration: 160.949177ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:38.127201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.348195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:deployment-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:14:38.127243Z","caller":"traceutil/trace.go:171","msg":"trace[1135556787] range","detail":"{range_begin:/registry/clusterroles/system:controller:deployment-controller; range_end:; response_count:0; response_revision:156; }","duration":"312.407075ms","start":"2026-02-04T05:14:37.814823Z","end":"2026-02-04T05:14:38.12723Z","steps":["trace[1135556787] 'agreement among raft nodes before linearized reading' (duration: 312.180424ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:38.127692Z","caller":"traceutil/trace.go:171","msg":"trace[415955069] transaction","detail":"{read_only:false; response_revision:155; number_of_response:1; }","duration":"316.783113ms","start":"2026-02-04T05:14:37.810885Z","end":"2026-02-04T05:14:38.127668Z","steps":["trace[415955069] 'process raft request' (duration: 155.071161ms)","trace[415955069] 'compare' (duration: 160.430153ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:38.127995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:37.810865Z","time spent":"317.053825ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:14:38.128056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:37.814816Z","time spent":"312.605757ms","remote":"127.0.0.1:52466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/system:controller:deployment-controller\" "} {"level":"info","ts":"2026-02-04T05:14:38.128504Z","caller":"traceutil/trace.go:171","msg":"trace[876643220] transaction","detail":"{read_only:false; response_revision:156; number_of_response:1; }","duration":"315.506195ms","start":"2026-02-04T05:14:37.812982Z","end":"2026-02-04T05:14:38.128488Z","steps":["trace[876643220] 'process raft request' (duration: 313.641993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:38.12872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:37.812976Z","time spent":"315.564655ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:14:38.89027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.97286ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:14:38.890371Z","caller":"traceutil/trace.go:171","msg":"trace[1485321632] transaction","detail":"{read_only:false; response_revision:183; number_of_response:1; }","duration":"164.445598ms","start":"2026-02-04T05:14:38.725904Z","end":"2026-02-04T05:14:38.89035Z","steps":["trace[1485321632] 'process raft request' (duration: 62.329528ms)","trace[1485321632] 'compare' (duration: 101.705448ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:39.437974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.526796ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:14:39.438352Z","caller":"traceutil/trace.go:171","msg":"trace[219978234] linearizableReadLoop","detail":"{readStateIndex:196; appliedIndex:195; }","duration":"136.723785ms","start":"2026-02-04T05:14:39.30161Z","end":"2026-02-04T05:14:39.438334Z","steps":["trace[219978234] 'read index received' (duration: 27.713903ms)","trace[219978234] 'applied index is now lower than readState.Index' (duration: 109.008392ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:39.438643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.034566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2898"} {"level":"info","ts":"2026-02-04T05:14:39.438792Z","caller":"traceutil/trace.go:171","msg":"trace[1299907675] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:191; }","duration":"137.196031ms","start":"2026-02-04T05:14:39.301555Z","end":"2026-02-04T05:14:39.438751Z","steps":["trace[1299907675] 'agreement among raft nodes before linearized reading' (duration: 137.002914ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:39.439234Z","caller":"traceutil/trace.go:171","msg":"trace[1745798529] transaction","detail":"{read_only:false; response_revision:191; number_of_response:1; }","duration":"205.789787ms","start":"2026-02-04T05:14:39.233429Z","end":"2026-02-04T05:14:39.439219Z","steps":["trace[1745798529] 'process raft request' (duration: 95.951255ms)","trace[1745798529] 'compare' (duration: 108.372009ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:39.822149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.523447ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:14:39.822542Z","caller":"traceutil/trace.go:171","msg":"trace[645451497] transaction","detail":"{read_only:false; response_revision:196; number_of_response:1; }","duration":"240.154537ms","start":"2026-02-04T05:14:39.582361Z","end":"2026-02-04T05:14:39.822515Z","steps":["trace[645451497] 'process raft request' (duration: 122.205894ms)","trace[645451497] 'compare' (duration: 117.426314ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:14:39.823256Z","caller":"traceutil/trace.go:171","msg":"trace[735844380] transaction","detail":"{read_only:false; response_revision:197; number_of_response:1; }","duration":"232.68256ms","start":"2026-02-04T05:14:39.590561Z","end":"2026-02-04T05:14:39.823244Z","steps":["trace[735844380] 'process raft request' (duration: 231.680944ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:39.823479Z","caller":"traceutil/trace.go:171","msg":"trace[1774534072] linearizableReadLoop","detail":"{readStateIndex:201; appliedIndex:200; }","duration":"237.395778ms","start":"2026-02-04T05:14:39.586071Z","end":"2026-02-04T05:14:39.823467Z","steps":["trace[1774534072] 'read index received' (duration: 118.508063ms)","trace[1774534072] 'applied index is now lower than readState.Index' (duration: 118.886245ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:39.82451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.074576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-04T05:14:39.824555Z","caller":"traceutil/trace.go:171","msg":"trace[2035206583] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:197; }","duration":"242.15695ms","start":"2026-02-04T05:14:39.582386Z","end":"2026-02-04T05:14:39.824543Z","steps":["trace[2035206583] 'agreement among raft nodes before linearized reading' (duration: 242.052145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:39.825596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.770841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:volume-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:14:39.825638Z","caller":"traceutil/trace.go:171","msg":"trace[23137076] range","detail":"{range_begin:/registry/clusterrolebindings/system:volume-scheduler; range_end:; response_count:0; response_revision:197; }","duration":"235.838542ms","start":"2026-02-04T05:14:39.58979Z","end":"2026-02-04T05:14:39.825628Z","steps":["trace[23137076] 'agreement among raft nodes before linearized reading' (duration: 235.77523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:39.826465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.087529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:14:39.826591Z","caller":"traceutil/trace.go:171","msg":"trace[1044644814] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:197; }","duration":"101.217054ms","start":"2026-02-04T05:14:39.725357Z","end":"2026-02-04T05:14:39.826574Z","steps":["trace[1044644814] 'agreement among raft nodes before linearized reading' (duration: 101.060368ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:40.853667Z","caller":"traceutil/trace.go:171","msg":"trace[1529520116] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"105.471772ms","start":"2026-02-04T05:14:40.748174Z","end":"2026-02-04T05:14:40.853645Z","steps":["trace[1529520116] 'process raft request' (duration: 105.013856ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:42.125873Z","caller":"traceutil/trace.go:171","msg":"trace[510909263] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"107.970072ms","start":"2026-02-04T05:14:42.017887Z","end":"2026-02-04T05:14:42.125857Z","steps":["trace[510909263] 'process raft request' (duration: 107.363611ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:42.35829Z","caller":"traceutil/trace.go:171","msg":"trace[1759506647] linearizableReadLoop","detail":"{readStateIndex:297; appliedIndex:296; }","duration":"213.598811ms","start":"2026-02-04T05:14:42.14468Z","end":"2026-02-04T05:14:42.358279Z","steps":["trace[1759506647] 'read index received' (duration: 212.076048ms)","trace[1759506647] 'applied index is now lower than readState.Index' (duration: 1.521813ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:14:42.358352Z","caller":"traceutil/trace.go:171","msg":"trace[45157727] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"227.99674ms","start":"2026-02-04T05:14:42.130349Z","end":"2026-02-04T05:14:42.358346Z","steps":["trace[45157727] 'process raft request' (duration: 226.359603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:42.358537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.84539ms","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-02-04T05:14:42.358568Z","caller":"traceutil/trace.go:171","msg":"trace[1444842441] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:292; }","duration":"213.909812ms","start":"2026-02-04T05:14:42.144649Z","end":"2026-02-04T05:14:42.358559Z","steps":["trace[1444842441] 'agreement among raft nodes before linearized reading' (duration: 213.831569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:42.35872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.990415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-04T05:14:42.358273Z","caller":"traceutil/trace.go:171","msg":"trace[533416362] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"211.020892ms","start":"2026-02-04T05:14:42.147231Z","end":"2026-02-04T05:14:42.358252Z","steps":["trace[533416362] 'process raft request' (duration: 210.979001ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:42.358842Z","caller":"traceutil/trace.go:171","msg":"trace[1076769141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:292; }","duration":"214.119759ms","start":"2026-02-04T05:14:42.144707Z","end":"2026-02-04T05:14:42.358827Z","steps":["trace[1076769141] 'agreement among raft nodes before linearized reading' (duration: 213.972004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:42.923832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.889115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-02-04T05:14:42.923885Z","caller":"traceutil/trace.go:171","msg":"trace[789198867] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:300; }","duration":"426.976398ms","start":"2026-02-04T05:14:42.496898Z","end":"2026-02-04T05:14:42.923874Z","steps":["trace[789198867] 'range keys from in-memory index tree' (duration: 426.830942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:42.923909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:42.496883Z","time spent":"427.020899ms","remote":"127.0.0.1:52330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":239,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"warn","ts":"2026-02-04T05:14:42.924034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.478599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:14:42.924051Z","caller":"traceutil/trace.go:171","msg":"trace[401228285] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:300; }","duration":"393.518501ms","start":"2026-02-04T05:14:42.530528Z","end":"2026-02-04T05:14:42.924047Z","steps":["trace[401228285] 'range keys from in-memory index tree' (duration: 393.436398ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:42.924063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:42.530516Z","time spent":"393.544122ms","remote":"127.0.0.1:52170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T05:14:42.924169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.934878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-04T05:14:42.924188Z","caller":"traceutil/trace.go:171","msg":"trace[1914047000] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:300; }","duration":"376.974789ms","start":"2026-02-04T05:14:42.547209Z","end":"2026-02-04T05:14:42.924183Z","steps":["trace[1914047000] 'range keys from in-memory index tree' (duration: 376.824524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:42.924218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:42.547196Z","time spent":"377.0022ms","remote":"127.0.0.1:52248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-04T05:14:42.92453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.928867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:14:42.92455Z","caller":"traceutil/trace.go:171","msg":"trace[1609118802] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:300; }","duration":"216.983549ms","start":"2026-02-04T05:14:42.707562Z","end":"2026-02-04T05:14:42.924546Z","steps":["trace[1609118802] 'range keys from in-memory index tree' (duration: 216.875095ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:43.229117Z","caller":"traceutil/trace.go:171","msg":"trace[129871727] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"400.831132ms","start":"2026-02-04T05:14:42.828266Z","end":"2026-02-04T05:14:43.229098Z","steps":["trace[129871727] 'process raft request' (duration: 400.753739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:43.229297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:42.828258Z","time spent":"400.995648ms","remote":"127.0.0.1:52228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T05:14:43.229606Z","caller":"traceutil/trace.go:171","msg":"trace[627566513] linearizableReadLoop","detail":"{readStateIndex:307; appliedIndex:307; }","duration":"390.985582ms","start":"2026-02-04T05:14:42.838548Z","end":"2026-02-04T05:14:43.229534Z","steps":["trace[627566513] 'read index received' (duration: 390.976282ms)","trace[627566513] 'applied index is now lower than readState.Index' (duration: 7.38µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:43.229724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.126387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:14:43.229768Z","caller":"traceutil/trace.go:171","msg":"trace[700511686] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:301; }","duration":"391.237621ms","start":"2026-02-04T05:14:42.838522Z","end":"2026-02-04T05:14:43.229759Z","steps":["trace[700511686] 'agreement among raft nodes before linearized reading' (duration: 391.112647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:43.229837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:42.83851Z","time spent":"391.320603ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-02-04T05:14:43.491177Z","caller":"traceutil/trace.go:171","msg":"trace[177528863] transaction","detail":"{read_only:false; number_of_response:0; response_revision:302; }","duration":"548.75761ms","start":"2026-02-04T05:14:42.942398Z","end":"2026-02-04T05:14:43.491156Z","steps":["trace[177528863] 'process raft request' (duration: 548.618426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:43.491509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:42.942372Z","time spent":"548.872314ms","remote":"127.0.0.1:52294","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T05:14:43.49121Z","caller":"traceutil/trace.go:171","msg":"trace[1826071328] linearizableReadLoop","detail":"{readStateIndex:314; appliedIndex:307; }","duration":"261.508754ms","start":"2026-02-04T05:14:43.229684Z","end":"2026-02-04T05:14:43.491193Z","steps":["trace[1826071328] 'read index received' (duration: 260.888443ms)","trace[1826071328] 'applied index is now lower than readState.Index' (duration: 619.781µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:14:43.49194Z","caller":"traceutil/trace.go:171","msg":"trace[394548824] transaction","detail":"{read_only:false; number_of_response:0; response_revision:302; }","duration":"335.708069ms","start":"2026-02-04T05:14:43.156224Z","end":"2026-02-04T05:14:43.491932Z","steps":["trace[394548824] 'process raft request' (duration: 334.842698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:43.492085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:43.156206Z","time spent":"335.814723ms","remote":"127.0.0.1:52308","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T05:14:43.492384Z","caller":"traceutil/trace.go:171","msg":"trace[1988499188] transaction","detail":"{read_only:false; number_of_response:0; response_revision:302; }","duration":"336.061431ms","start":"2026-02-04T05:14:43.156315Z","end":"2026-02-04T05:14:43.492377Z","steps":["trace[1988499188] 'process raft request' (duration: 334.775956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:43.492473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:43.15631Z","time spent":"336.142603ms","remote":"127.0.0.1:52308","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T05:14:43.492689Z","caller":"traceutil/trace.go:171","msg":"trace[1424310980] transaction","detail":"{read_only:false; number_of_response:0; response_revision:302; }","duration":"336.33385ms","start":"2026-02-04T05:14:43.156349Z","end":"2026-02-04T05:14:43.492683Z","steps":["trace[1424310980] 'process raft request' (duration: 334.756516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:43.492771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:43.156344Z","time spent":"336.404332ms","remote":"127.0.0.1:52308","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T05:14:43.492995Z","caller":"traceutil/trace.go:171","msg":"trace[168310052] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"563.468498ms","start":"2026-02-04T05:14:42.929508Z","end":"2026-02-04T05:14:43.492976Z","steps":["trace[168310052] 'process raft request' (duration: 560.877119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:43.493295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:42.92949Z","time spent":"563.54682ms","remote":"127.0.0.1:52330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":186,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T05:14:43.494215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.381204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3705"} {"level":"info","ts":"2026-02-04T05:14:43.494266Z","caller":"traceutil/trace.go:171","msg":"trace[1080039777] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:304; }","duration":"260.464968ms","start":"2026-02-04T05:14:43.233789Z","end":"2026-02-04T05:14:43.494254Z","steps":["trace[1080039777] 'agreement among raft nodes before linearized reading' (duration: 260.352314ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:43.49496Z","caller":"traceutil/trace.go:171","msg":"trace[355040066] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"338.139891ms","start":"2026-02-04T05:14:43.156809Z","end":"2026-02-04T05:14:43.494949Z","steps":["trace[355040066] 'process raft request' (duration: 334.30879ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:43.49506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:43.156802Z","time spent":"338.236796ms","remote":"127.0.0.1:52308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3431,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T05:14:43.495265Z","caller":"traceutil/trace.go:171","msg":"trace[1897965677] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"259.449293ms","start":"2026-02-04T05:14:43.235556Z","end":"2026-02-04T05:14:43.495006Z","steps":["trace[1897965677] 'process raft request' (duration: 258.372456ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:43.495734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.463228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:14:43.495787Z","caller":"traceutil/trace.go:171","msg":"trace[1736796532] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:304; }","duration":"224.548521ms","start":"2026-02-04T05:14:43.271224Z","end":"2026-02-04T05:14:43.495773Z","steps":["trace[1736796532] 'agreement among raft nodes before linearized reading' (duration: 224.414717ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:43.495277Z","caller":"traceutil/trace.go:171","msg":"trace[1000093597] transaction","detail":"{read_only:false; number_of_response:0; response_revision:303; }","duration":"337.011952ms","start":"2026-02-04T05:14:43.158258Z","end":"2026-02-04T05:14:43.49527Z","steps":["trace[1000093597] 'process raft request' (duration: 332.905632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:43.497006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:43.158251Z","time spent":"338.433641ms","remote":"127.0.0.1:52308","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T05:14:43.49744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.90754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-02-04T05:14:43.497522Z","caller":"traceutil/trace.go:171","msg":"trace[1037254846] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:304; }","duration":"566.029404ms","start":"2026-02-04T05:14:42.931483Z","end":"2026-02-04T05:14:43.497513Z","steps":["trace[1037254846] 'agreement among raft nodes before linearized reading' (duration: 563.808998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:43.497565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:42.931459Z","time spent":"566.098237ms","remote":"127.0.0.1:52330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":239,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"warn","ts":"2026-02-04T05:14:43.498074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.750679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-04T05:14:43.498135Z","caller":"traceutil/trace.go:171","msg":"trace[458006512] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:304; }","duration":"566.811981ms","start":"2026-02-04T05:14:42.931315Z","end":"2026-02-04T05:14:43.498127Z","steps":["trace[458006512] 'agreement among raft nodes before linearized reading' (duration: 566.709887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:43.498289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:42.931294Z","time spent":"566.881382ms","remote":"127.0.0.1:52514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-02-04T05:14:43.498596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.350194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:14:43.498691Z","caller":"traceutil/trace.go:171","msg":"trace[1678902495] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:0; response_revision:304; }","duration":"568.462598ms","start":"2026-02-04T05:14:42.930218Z","end":"2026-02-04T05:14:43.498681Z","steps":["trace[1678902495] 'agreement among raft nodes before linearized reading' (duration: 568.347914ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:43.498773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:42.93021Z","time spent":"568.51654ms","remote":"127.0.0.1:52242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/configmaps/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-04T05:14:45.209705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.394102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-04T05:14:45.209789Z","caller":"traceutil/trace.go:171","msg":"trace[835880186] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:352; }","duration":"112.566038ms","start":"2026-02-04T05:14:45.097204Z","end":"2026-02-04T05:14:45.20977Z","steps":["trace[835880186] 'range keys from in-memory index tree' (duration: 112.289238ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:48.203393Z","caller":"traceutil/trace.go:171","msg":"trace[1892292656] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"526.654676ms","start":"2026-02-04T05:14:47.676713Z","end":"2026-02-04T05:14:48.203367Z","steps":["trace[1892292656] 'process raft request' (duration: 526.526472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:48.203717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:47.676699Z","time spent":"526.76475ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:14:48.204514Z","caller":"traceutil/trace.go:171","msg":"trace[1918386934] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:419; }","duration":"309.002196ms","start":"2026-02-04T05:14:47.895497Z","end":"2026-02-04T05:14:48.2045Z","steps":["trace[1918386934] 'read index received' (duration: 307.671253ms)","trace[1918386934] 'applied index is now lower than readState.Index' (duration: 1.329763ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:48.204717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.204732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:14:48.204863Z","caller":"traceutil/trace.go:171","msg":"trace[867796810] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:408; }","duration":"309.328366ms","start":"2026-02-04T05:14:47.895466Z","end":"2026-02-04T05:14:48.204794Z","steps":["trace[867796810] 'agreement among raft nodes before linearized reading' (duration: 309.210583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:48.204964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:47.895455Z","time spent":"309.44789ms","remote":"127.0.0.1:52170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T05:14:48.205673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.278598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-04T05:14:48.205744Z","caller":"traceutil/trace.go:171","msg":"trace[1910163068] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:408; }","duration":"276.372841ms","start":"2026-02-04T05:14:47.929361Z","end":"2026-02-04T05:14:48.205734Z","steps":["trace[1910163068] 'agreement among raft nodes before linearized reading' (duration: 276.271288ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:48.505141Z","caller":"traceutil/trace.go:171","msg":"trace[1316217593] linearizableReadLoop","detail":"{readStateIndex:421; appliedIndex:420; }","duration":"290.801749ms","start":"2026-02-04T05:14:48.214315Z","end":"2026-02-04T05:14:48.505117Z","steps":["trace[1316217593] 'read index received' (duration: 259.360605ms)","trace[1316217593] 'applied index is now lower than readState.Index' (duration: 31.439684ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:14:48.505266Z","caller":"traceutil/trace.go:171","msg":"trace[1096619342] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"292.08727ms","start":"2026-02-04T05:14:48.213168Z","end":"2026-02-04T05:14:48.505256Z","steps":["trace[1096619342] 'process raft request' (duration: 260.500361ms)","trace[1096619342] 'compare' (duration: 30.976289ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:48.50558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.240913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:14:48.505628Z","caller":"traceutil/trace.go:171","msg":"trace[534707408] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:409; }","duration":"291.320485ms","start":"2026-02-04T05:14:48.214299Z","end":"2026-02-04T05:14:48.50562Z","steps":["trace[534707408] 'agreement among raft nodes before linearized reading' (duration: 290.980725ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:48.585117Z","caller":"traceutil/trace.go:171","msg":"trace[607722091] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"368.922149ms","start":"2026-02-04T05:14:48.216178Z","end":"2026-02-04T05:14:48.5851Z","steps":["trace[607722091] 'process raft request' (duration: 368.824116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:48.58537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:48.216171Z","time spent":"369.098484ms","remote":"127.0.0.1:52308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7030,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:14:48.586282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.05741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-04T05:14:48.587857Z","caller":"traceutil/trace.go:171","msg":"trace[377107783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:410; }","duration":"200.662742ms","start":"2026-02-04T05:14:48.38718Z","end":"2026-02-04T05:14:48.587842Z","steps":["trace[377107783] 'agreement among raft nodes before linearized reading' (duration: 199.06343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:48.588147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.139334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-04T05:14:48.590059Z","caller":"traceutil/trace.go:171","msg":"trace[320524915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:410; }","duration":"197.070705ms","start":"2026-02-04T05:14:48.392962Z","end":"2026-02-04T05:14:48.590033Z","steps":["trace[320524915] 'agreement among raft nodes before linearized reading' (duration: 193.822221ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:48.723344Z","caller":"traceutil/trace.go:171","msg":"trace[170475672] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"129.207998ms","start":"2026-02-04T05:14:48.594117Z","end":"2026-02-04T05:14:48.723325Z","steps":["trace[170475672] 'process raft request' (duration: 129.113915ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:48.725785Z","caller":"traceutil/trace.go:171","msg":"trace[1485136516] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"130.80051ms","start":"2026-02-04T05:14:48.594957Z","end":"2026-02-04T05:14:48.725758Z","steps":["trace[1485136516] 'process raft request' (duration: 130.646085ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:53.766705Z","caller":"traceutil/trace.go:171","msg":"trace[913069286] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"798.476578ms","start":"2026-02-04T05:14:52.968204Z","end":"2026-02-04T05:14:53.76668Z","steps":["trace[913069286] 'process raft request' (duration: 798.338254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:53.770495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:52.968175Z","time spent":"802.171121ms","remote":"127.0.0.1:52308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4583,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:14:53.774476Z","caller":"traceutil/trace.go:171","msg":"trace[1032975330] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"211.859468ms","start":"2026-02-04T05:14:53.562603Z","end":"2026-02-04T05:14:53.774462Z","steps":["trace[1032975330] 'process raft request' (duration: 211.782596ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:54.35Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.558877ms","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-02-04T05:14:54.350056Z","caller":"traceutil/trace.go:171","msg":"trace[1708358592] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:424; }","duration":"123.67056ms","start":"2026-02-04T05:14:54.226371Z","end":"2026-02-04T05:14:54.350042Z","steps":["trace[1708358592] 'range keys from in-memory index tree' (duration: 123.434893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:14:56.472524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.715734ms","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-02-04T05:14:56.472586Z","caller":"traceutil/trace.go:171","msg":"trace[1837160913] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:429; }","duration":"115.814096ms","start":"2026-02-04T05:14:56.356755Z","end":"2026-02-04T05:14:56.472569Z","steps":["trace[1837160913] 'range keys from in-memory index tree' (duration: 115.642091ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:56.578828Z","caller":"traceutil/trace.go:171","msg":"trace[1389255875] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"103.465733ms","start":"2026-02-04T05:14:56.475338Z","end":"2026-02-04T05:14:56.578804Z","steps":["trace[1389255875] 'process raft request' (duration: 103.215225ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:57.742149Z","caller":"traceutil/trace.go:171","msg":"trace[1907902018] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"122.120423ms","start":"2026-02-04T05:14:57.620008Z","end":"2026-02-04T05:14:57.742129Z","steps":["trace[1907902018] 'process raft request' (duration: 115.199061ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:14:57.881815Z","caller":"traceutil/trace.go:171","msg":"trace[1287803655] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"102.287694ms","start":"2026-02-04T05:14:57.779504Z","end":"2026-02-04T05:14:57.881792Z","steps":["trace[1287803655] 'process raft request' (duration: 74.630977ms)","trace[1287803655] 'compare' (duration: 27.444481ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:14:59.116459Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305493840746119920,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T05:14:59.617098Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305493840746119920,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T05:15:00.036065Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.451307827s","expected-duration":"1s"} {"level":"info","ts":"2026-02-04T05:15:00.036291Z","caller":"traceutil/trace.go:171","msg":"trace[466158014] linearizableReadLoop","detail":"{readStateIndex:478; appliedIndex:477; }","duration":"1.41985132s","start":"2026-02-04T05:14:58.616426Z","end":"2026-02-04T05:15:00.036277Z","steps":["trace[466158014] 'read index received' (duration: 1.419722756s)","trace[466158014] 'applied index is now lower than readState.Index' (duration: 128.003µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:15:00.036443Z","caller":"traceutil/trace.go:171","msg":"trace[2082491571] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"1.4517343s","start":"2026-02-04T05:14:58.584703Z","end":"2026-02-04T05:15:00.036437Z","steps":["trace[2082491571] 'process raft request' (duration: 1.451495163s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:00.036509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:58.584694Z","time spent":"1.451767991s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:15:00.036616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.42019025s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-04T05:15:00.036632Z","caller":"traceutil/trace.go:171","msg":"trace[151272336] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:464; }","duration":"1.420233421s","start":"2026-02-04T05:14:58.616394Z","end":"2026-02-04T05:15:00.036627Z","steps":["trace[151272336] 'agreement among raft nodes before linearized reading' (duration: 1.42020335s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:00.036645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:58.616378Z","time spent":"1.420264202s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-04T05:15:00.036707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.264398794s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-04T05:15:00.036719Z","caller":"traceutil/trace.go:171","msg":"trace[134131659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:464; }","duration":"1.264442866s","start":"2026-02-04T05:14:58.772272Z","end":"2026-02-04T05:15:00.036715Z","steps":["trace[134131659] 'agreement among raft nodes before linearized reading' (duration: 1.264421315s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:00.036731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:58.772257Z","time spent":"1.264471656s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-04T05:15:00.036866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.253826379s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-04T05:15:00.036879Z","caller":"traceutil/trace.go:171","msg":"trace[693287470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:464; }","duration":"1.25386005s","start":"2026-02-04T05:14:58.783015Z","end":"2026-02-04T05:15:00.036875Z","steps":["trace[693287470] 'agreement among raft nodes before linearized reading' (duration: 1.25383772s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:00.036893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:14:58.783005Z","time spent":"1.253885031s","remote":"127.0.0.1:52404","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-02-04T05:15:00.827447Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305493840746119924,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T05:15:01.070055Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.028290076s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-04T05:15:01.463105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.766727ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:15:01.46325Z","caller":"traceutil/trace.go:171","msg":"trace[1776575963] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:478; }","duration":"1.136129503s","start":"2026-02-04T05:15:00.327109Z","end":"2026-02-04T05:15:01.463239Z","steps":["trace[1776575963] 'read index received' (duration: 743.191283ms)","trace[1776575963] 'applied index is now lower than readState.Index' (duration: 392.93755ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:15:01.463299Z","caller":"traceutil/trace.go:171","msg":"trace[1298054367] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"1.421620828s","start":"2026-02-04T05:15:00.041671Z","end":"2026-02-04T05:15:01.463292Z","steps":["trace[1298054367] 'process raft request' (duration: 1.028609074s)","trace[1298054367] 'compare' (duration: 392.681404ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:15:01.463334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:00.041648Z","time spent":"1.421665989s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:15:01.463491Z","caller":"traceutil/trace.go:171","msg":"trace[196710011] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"1.386068903s","start":"2026-02-04T05:15:00.077416Z","end":"2026-02-04T05:15:01.463485Z","steps":["trace[196710011] 'process raft request' (duration: 1.385770795s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:01.463524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:00.077409Z","time spent":"1.386095463s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:15:01.463586Z","caller":"traceutil/trace.go:171","msg":"trace[771552807] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"1.386114185s","start":"2026-02-04T05:15:00.077467Z","end":"2026-02-04T05:15:01.463581Z","steps":["trace[771552807] 'process raft request' (duration: 1.385750564s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:01.463617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:00.077463Z","time spent":"1.386135595s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:15:01.463729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.136617796s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-04T05:15:01.463756Z","caller":"traceutil/trace.go:171","msg":"trace[1548677162] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:467; }","duration":"1.136656618s","start":"2026-02-04T05:15:00.327083Z","end":"2026-02-04T05:15:01.46374Z","steps":["trace[1548677162] 'agreement among raft nodes before linearized reading' (duration: 1.136621697s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:01.463773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:00.32707Z","time spent":"1.136698069s","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.210\" "} {"level":"warn","ts":"2026-02-04T05:15:01.463888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.318975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:15:01.463907Z","caller":"traceutil/trace.go:171","msg":"trace[145679920] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:467; }","duration":"477.339275ms","start":"2026-02-04T05:15:00.986562Z","end":"2026-02-04T05:15:01.463902Z","steps":["trace[145679920] 'agreement among raft nodes before linearized reading' (duration: 477.308074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:01.463922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:00.986517Z","time spent":"477.402097ms","remote":"127.0.0.1:52154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T05:15:01.837705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.083996ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:15:01.837798Z","caller":"traceutil/trace.go:171","msg":"trace[803209312] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"319.031374ms","start":"2026-02-04T05:15:01.518749Z","end":"2026-02-04T05:15:01.83778Z","steps":["trace[803209312] 'process raft request' (duration: 57.801334ms)","trace[803209312] 'compare' (duration: 260.966263ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:15:01.837856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:01.518737Z","time spent":"319.091496ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:15:04.137234Z","caller":"traceutil/trace.go:171","msg":"trace[96691436] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"126.894315ms","start":"2026-02-04T05:15:04.010301Z","end":"2026-02-04T05:15:04.137195Z","steps":["trace[96691436] 'process raft request' (duration: 126.672609ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:04.315022Z","caller":"traceutil/trace.go:171","msg":"trace[1986672702] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"174.193755ms","start":"2026-02-04T05:15:04.140799Z","end":"2026-02-04T05:15:04.314992Z","steps":["trace[1986672702] 'process raft request' (duration: 137.948884ms)","trace[1986672702] 'compare' (duration: 36.132638ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:15:05.743661Z","caller":"traceutil/trace.go:171","msg":"trace[1712356523] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:490; }","duration":"267.771584ms","start":"2026-02-04T05:15:05.47587Z","end":"2026-02-04T05:15:05.743641Z","steps":["trace[1712356523] 'read index received' (duration: 233.65886ms)","trace[1712356523] 'applied index is now lower than readState.Index' (duration: 34.111784ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:15:05.743798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.916387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-04T05:15:05.743826Z","caller":"traceutil/trace.go:171","msg":"trace[1396258221] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:475; }","duration":"267.995159ms","start":"2026-02-04T05:15:05.475824Z","end":"2026-02-04T05:15:05.743819Z","steps":["trace[1396258221] 'agreement among raft nodes before linearized reading' (duration: 267.921817ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:05.744019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.968927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-04T05:15:05.744041Z","caller":"traceutil/trace.go:171","msg":"trace[1484079146] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:475; }","duration":"261.992018ms","start":"2026-02-04T05:15:05.482044Z","end":"2026-02-04T05:15:05.744036Z","steps":["trace[1484079146] 'agreement among raft nodes before linearized reading' (duration: 261.955307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:05.74415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.918987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-04T05:15:05.744174Z","caller":"traceutil/trace.go:171","msg":"trace[2011427967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:475; }","duration":"261.950097ms","start":"2026-02-04T05:15:05.482216Z","end":"2026-02-04T05:15:05.744166Z","steps":["trace[2011427967] 'agreement among raft nodes before linearized reading' (duration: 261.918157ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:11.961233Z","caller":"traceutil/trace.go:171","msg":"trace[2138902840] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"120.972636ms","start":"2026-02-04T05:15:11.840226Z","end":"2026-02-04T05:15:11.961199Z","steps":["trace[2138902840] 'process raft request' (duration: 119.078719ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:11.962195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.297585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-04T05:15:11.962228Z","caller":"traceutil/trace.go:171","msg":"trace[414807053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:525; }","duration":"121.345856ms","start":"2026-02-04T05:15:11.840874Z","end":"2026-02-04T05:15:11.96222Z","steps":["trace[414807053] 'agreement among raft nodes before linearized reading' (duration: 121.287315ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:11.962722Z","caller":"traceutil/trace.go:171","msg":"trace[1749955028] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:541; }","duration":"119.674514ms","start":"2026-02-04T05:15:11.840884Z","end":"2026-02-04T05:15:11.960559Z","steps":["trace[1749955028] 'read index received' (duration: 118.35406ms)","trace[1749955028] 'applied index is now lower than readState.Index' (duration: 1.319934ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:15:13.277478Z","caller":"traceutil/trace.go:171","msg":"trace[1170300963] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:543; }","duration":"291.653762ms","start":"2026-02-04T05:15:12.985808Z","end":"2026-02-04T05:15:13.277462Z","steps":["trace[1170300963] 'read index received' (duration: 291.511899ms)","trace[1170300963] 'applied index is now lower than readState.Index' (duration: 141.293µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:15:13.27758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.753975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:15:13.277604Z","caller":"traceutil/trace.go:171","msg":"trace[1180070735] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:527; }","duration":"291.818256ms","start":"2026-02-04T05:15:12.985777Z","end":"2026-02-04T05:15:13.277595Z","steps":["trace[1180070735] 'agreement among raft nodes before linearized reading' (duration: 291.749345ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:13.277848Z","caller":"traceutil/trace.go:171","msg":"trace[1428945459] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"916.940949ms","start":"2026-02-04T05:15:12.3609Z","end":"2026-02-04T05:15:13.277841Z","steps":["trace[1428945459] 'process raft request' (duration: 916.468398ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:13.277906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:12.360891Z","time spent":"916.97449ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:15:14.90702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.889401ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:15:14.907103Z","caller":"traceutil/trace.go:171","msg":"trace[628668264] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"230.40869ms","start":"2026-02-04T05:15:14.676678Z","end":"2026-02-04T05:15:14.907087Z","steps":["trace[628668264] 'process raft request' (duration: 85.408566ms)","trace[628668264] 'compare' (duration: 144.785478ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:15:14.908253Z","caller":"traceutil/trace.go:171","msg":"trace[1331364322] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"213.698082ms","start":"2026-02-04T05:15:14.694516Z","end":"2026-02-04T05:15:14.908214Z","steps":["trace[1331364322] 'process raft request' (duration: 213.443696ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:14.908816Z","caller":"traceutil/trace.go:171","msg":"trace[1963377867] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"232.118821ms","start":"2026-02-04T05:15:14.676685Z","end":"2026-02-04T05:15:14.908804Z","steps":["trace[1963377867] 'process raft request' (duration: 231.188388ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:20.003729Z","caller":"traceutil/trace.go:171","msg":"trace[1857264552] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"159.001259ms","start":"2026-02-04T05:15:19.844715Z","end":"2026-02-04T05:15:20.003716Z","steps":["trace[1857264552] 'process raft request' (duration: 158.783314ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:20.57034Z","caller":"traceutil/trace.go:171","msg":"trace[1220053136] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"558.742483ms","start":"2026-02-04T05:15:20.01158Z","end":"2026-02-04T05:15:20.570322Z","steps":["trace[1220053136] 'process raft request' (duration: 558.579319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:20.570448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:20.011564Z","time spent":"558.827424ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:15:22.550214Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.980337239s","expected-duration":"1s"} {"level":"info","ts":"2026-02-04T05:15:22.550553Z","caller":"traceutil/trace.go:171","msg":"trace[967153996] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:586; }","duration":"2.221962915s","start":"2026-02-04T05:15:20.328573Z","end":"2026-02-04T05:15:22.550536Z","steps":["trace[967153996] 'read index received' (duration: 242.131439ms)","trace[967153996] 'applied index is now lower than readState.Index' (duration: 1.979830806s)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:15:22.550806Z","caller":"traceutil/trace.go:171","msg":"trace[96565255] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"2.533660426s","start":"2026-02-04T05:15:20.017138Z","end":"2026-02-04T05:15:22.550798Z","steps":["trace[96565255] 'process raft request' (duration: 2.533207626s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:22.550876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:20.017124Z","time spent":"2.533704187s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:15:22.551055Z","caller":"traceutil/trace.go:171","msg":"trace[1690862774] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"2.52167156s","start":"2026-02-04T05:15:20.029377Z","end":"2026-02-04T05:15:22.551048Z","steps":["trace[1690862774] 'process raft request' (duration: 2.521093197s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:22.551086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:20.02936Z","time spent":"2.521709301s","remote":"127.0.0.1:52308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:15:22.551202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.22262474s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-04T05:15:22.551223Z","caller":"traceutil/trace.go:171","msg":"trace[1444123833] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:570; }","duration":"2.22267092s","start":"2026-02-04T05:15:20.328546Z","end":"2026-02-04T05:15:22.551217Z","steps":["trace[1444123833] 'agreement among raft nodes before linearized reading' (duration: 2.22263068s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:22.55124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:20.328534Z","time spent":"2.222702942s","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.210\" "} {"level":"warn","ts":"2026-02-04T05:15:22.551339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.56677912s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:15:22.551355Z","caller":"traceutil/trace.go:171","msg":"trace[688518352] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:570; }","duration":"1.56679495s","start":"2026-02-04T05:15:20.984555Z","end":"2026-02-04T05:15:22.55135Z","steps":["trace[688518352] 'agreement among raft nodes before linearized reading' (duration: 1.56676935s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:22.55137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:20.98452Z","time spent":"1.566846092s","remote":"127.0.0.1:52154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T05:15:22.5516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.226966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-04T05:15:22.551618Z","caller":"traceutil/trace.go:171","msg":"trace[873640870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:570; }","duration":"497.266836ms","start":"2026-02-04T05:15:22.054346Z","end":"2026-02-04T05:15:22.551612Z","steps":["trace[873640870] 'agreement among raft nodes before linearized reading' (duration: 497.196985ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:22.551633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:22.054332Z","time spent":"497.297158ms","remote":"127.0.0.1:52404","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-02-04T05:15:22.551726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.242842543s","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-02-04T05:15:22.551742Z","caller":"traceutil/trace.go:171","msg":"trace[133629226] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:570; }","duration":"1.242894104s","start":"2026-02-04T05:15:21.308843Z","end":"2026-02-04T05:15:22.551737Z","steps":["trace[133629226] 'agreement among raft nodes before linearized reading' (duration: 1.242848293s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:22.551757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:21.308824Z","time spent":"1.242928955s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-04T05:15:23.728559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.172863ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:15:23.728716Z","caller":"traceutil/trace.go:171","msg":"trace[2118485759] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"1.099373987s","start":"2026-02-04T05:15:22.629332Z","end":"2026-02-04T05:15:23.728706Z","steps":["trace[2118485759] 'process raft request' (duration: 1.099348586s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:23.728751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:22.62932Z","time spent":"1.099414088s","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:15:23.728817Z","caller":"traceutil/trace.go:171","msg":"trace[998087624] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"1.163717001s","start":"2026-02-04T05:15:22.565074Z","end":"2026-02-04T05:15:23.728791Z","steps":["trace[998087624] 'process raft request' (duration: 1.163548587s)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:23.72886Z","caller":"traceutil/trace.go:171","msg":"trace[773055305] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:589; }","duration":"1.100339438s","start":"2026-02-04T05:15:22.628513Z","end":"2026-02-04T05:15:23.728852Z","steps":["trace[773055305] 'read index received' (duration: 107.884949ms)","trace[773055305] 'applied index is now lower than readState.Index' (duration: 992.453759ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:15:23.728939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:22.565065Z","time spent":"1.163807773s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:15:23.729009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.155207327s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-04T05:15:23.729025Z","caller":"traceutil/trace.go:171","msg":"trace[1757256281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:573; }","duration":"1.155253619s","start":"2026-02-04T05:15:22.573767Z","end":"2026-02-04T05:15:23.729021Z","steps":["trace[1757256281] 'agreement among raft nodes before linearized reading' (duration: 1.155155577s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:23.729043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:22.573756Z","time spent":"1.155284159s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-04T05:15:23.729095Z","caller":"traceutil/trace.go:171","msg":"trace[1572378667] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"1.169805268s","start":"2026-02-04T05:15:22.559277Z","end":"2026-02-04T05:15:23.729082Z","steps":["trace[1572378667] 'process raft request' (duration: 177.045202ms)","trace[1572378667] 'compare' (duration: 991.974479ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:15:23.729122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.095942898s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:15:23.729138Z","caller":"traceutil/trace.go:171","msg":"trace[1246912694] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:573; }","duration":"1.095971409s","start":"2026-02-04T05:15:22.633162Z","end":"2026-02-04T05:15:23.729134Z","steps":["trace[1246912694] 'agreement among raft nodes before linearized reading' (duration: 1.095948559s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:23.729151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:22.633156Z","time spent":"1.09599155s","remote":"127.0.0.1:52154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T05:15:23.729148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:22.559268Z","time spent":"1.16985286s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:15:24.237796Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305493840746120158,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T05:15:24.738656Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305493840746120158,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T05:15:25.239741Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305493840746120158,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T05:15:25.740727Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305493840746120158,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T05:15:25.743335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000307383s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-04T05:15:25.743425Z","caller":"traceutil/trace.go:171","msg":"trace[1540258393] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000406335s","start":"2026-02-04T05:15:23.743003Z","end":"2026-02-04T05:15:25.74341Z","steps":["trace[1540258393] 'agreement among raft nodes before linearized reading' (duration: 2.000305172s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:25.743472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:23.742974Z","time spent":"2.000489457s","remote":"127.0.0.1:52154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T05:15:26.241303Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305493840746120158,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T05:15:26.742379Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305493840746120158,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T05:15:27.085284Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.276761369s","expected-duration":"1s"} {"level":"info","ts":"2026-02-04T05:15:27.102237Z","caller":"traceutil/trace.go:171","msg":"trace[1792865814] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"3.367930117s","start":"2026-02-04T05:15:23.734273Z","end":"2026-02-04T05:15:27.102203Z","steps":["trace[1792865814] 'process raft request' (duration: 3.351226647s)","trace[1792865814] 'compare' (duration: 14.322908ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:15:27.103754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:23.734264Z","time spent":"3.369418199s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:15:27.105818Z","caller":"traceutil/trace.go:171","msg":"trace[1564215417] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"2.814779997s","start":"2026-02-04T05:15:24.290998Z","end":"2026-02-04T05:15:27.105778Z","steps":["trace[1564215417] 'process raft request' (duration: 2.814071742s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:27.10616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:24.290981Z","time spent":"2.815116135s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:15:27.106419Z","caller":"traceutil/trace.go:171","msg":"trace[1500979722] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"2.513764511s","start":"2026-02-04T05:15:24.592645Z","end":"2026-02-04T05:15:27.106409Z","steps":["trace[1500979722] 'process raft request' (duration: 2.512502053s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:27.106561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:24.592627Z","time spent":"2.513867463s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:15:27.206032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.833135ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-04T05:15:27.206111Z","caller":"traceutil/trace.go:171","msg":"trace[917163845] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:592; }","duration":"3.468419272s","start":"2026-02-04T05:15:23.737676Z","end":"2026-02-04T05:15:27.206096Z","steps":["trace[917163845] 'read index received' (duration: 3.347833201s)","trace[917163845] 'applied index is now lower than readState.Index' (duration: 120.584881ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:15:27.206447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.468760519s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-04T05:15:27.206473Z","caller":"traceutil/trace.go:171","msg":"trace[1407850985] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:576; }","duration":"3.468920703s","start":"2026-02-04T05:15:23.737544Z","end":"2026-02-04T05:15:27.206465Z","steps":["trace[1407850985] 'agreement among raft nodes before linearized reading' (duration: 3.468819671s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:27.206497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:23.737528Z","time spent":"3.468963153s","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-02-04T05:15:27.206648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.644664203s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-04T05:15:27.206686Z","caller":"traceutil/trace.go:171","msg":"trace[1359470497] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:576; }","duration":"2.644702353s","start":"2026-02-04T05:15:24.561978Z","end":"2026-02-04T05:15:27.20668Z","steps":["trace[1359470497] 'agreement among raft nodes before linearized reading' (duration: 2.644627972s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:27.206706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:24.561911Z","time spent":"2.644788996s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-04T05:15:27.206833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.463098845s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:15:27.206864Z","caller":"traceutil/trace.go:171","msg":"trace[2039731329] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"1.463146627s","start":"2026-02-04T05:15:25.743711Z","end":"2026-02-04T05:15:27.206858Z","steps":["trace[2039731329] 'agreement among raft nodes before linearized reading' (duration: 1.463106836s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:27.20688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:25.743702Z","time spent":"1.463174638s","remote":"127.0.0.1:52170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T05:15:27.207004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.470535089s","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-02-04T05:15:27.207022Z","caller":"traceutil/trace.go:171","msg":"trace[1442898734] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:576; }","duration":"1.470574209s","start":"2026-02-04T05:15:25.736442Z","end":"2026-02-04T05:15:27.207016Z","steps":["trace[1442898734] 'agreement among raft nodes before linearized reading' (duration: 1.470521578s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:27.207038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:25.736435Z","time spent":"1.47059898s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-04T05:15:27.207138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.470716964s","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-02-04T05:15:27.207154Z","caller":"traceutil/trace.go:171","msg":"trace[1005266939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:576; }","duration":"1.470775855s","start":"2026-02-04T05:15:25.736374Z","end":"2026-02-04T05:15:27.207149Z","steps":["trace[1005266939] 'agreement among raft nodes before linearized reading' (duration: 1.470728834s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:27.20717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:25.736352Z","time spent":"1.470814367s","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-04T05:15:28.194989Z","caller":"traceutil/trace.go:171","msg":"trace[122711735] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"155.790369ms","start":"2026-02-04T05:15:28.039182Z","end":"2026-02-04T05:15:28.194972Z","steps":["trace[122711735] 'process raft request' (duration: 80.06014ms)","trace[122711735] 'compare' (duration: 75.654857ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:15:29.393441Z","caller":"traceutil/trace.go:171","msg":"trace[387742687] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"165.303261ms","start":"2026-02-04T05:15:29.228118Z","end":"2026-02-04T05:15:29.393421Z","steps":["trace[387742687] 'process raft request' (duration: 165.191589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:29.77422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.155138ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:15:29.774355Z","caller":"traceutil/trace.go:171","msg":"trace[250775164] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"544.047904ms","start":"2026-02-04T05:15:29.230293Z","end":"2026-02-04T05:15:29.774341Z","steps":["trace[250775164] 'process raft request' (duration: 543.999143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:29.77441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:29.230283Z","time spent":"544.101715ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:15:29.77463Z","caller":"traceutil/trace.go:171","msg":"trace[122638425] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"545.190398ms","start":"2026-02-04T05:15:29.229431Z","end":"2026-02-04T05:15:29.774622Z","steps":["trace[122638425] 'process raft request' (duration: 270.51827ms)","trace[122638425] 'compare' (duration: 274.085856ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:15:29.774666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:29.229419Z","time spent":"545.228099ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:15:31.274897Z","caller":"traceutil/trace.go:171","msg":"trace[88593114] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:615; }","duration":"153.31923ms","start":"2026-02-04T05:15:31.12156Z","end":"2026-02-04T05:15:31.274879Z","steps":["trace[88593114] 'read index received' (duration: 74.456078ms)","trace[88593114] 'applied index is now lower than readState.Index' (duration: 78.862602ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:15:31.275105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.526804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-04T05:15:31.275127Z","caller":"traceutil/trace.go:171","msg":"trace[2108572972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:595; }","duration":"153.594386ms","start":"2026-02-04T05:15:31.121527Z","end":"2026-02-04T05:15:31.275121Z","steps":["trace[2108572972] 'agreement among raft nodes before linearized reading' (duration: 153.480134ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:31.275335Z","caller":"traceutil/trace.go:171","msg":"trace[888481776] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"225.109783ms","start":"2026-02-04T05:15:31.050219Z","end":"2026-02-04T05:15:31.275329Z","steps":["trace[888481776] 'process raft request' (duration: 145.839363ms)","trace[888481776] 'compare' (duration: 78.747119ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:15:32.013815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.869894ms","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-02-04T05:15:32.013872Z","caller":"traceutil/trace.go:171","msg":"trace[1433151632] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:602; }","duration":"234.976426ms","start":"2026-02-04T05:15:31.778881Z","end":"2026-02-04T05:15:32.013858Z","steps":["trace[1433151632] 'range keys from in-memory index tree' (duration: 233.820302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:32.014213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.977955ms","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-02-04T05:15:32.014242Z","caller":"traceutil/trace.go:171","msg":"trace[559478465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:602; }","duration":"234.026936ms","start":"2026-02-04T05:15:31.780209Z","end":"2026-02-04T05:15:32.014236Z","steps":["trace[559478465] 'range keys from in-memory index tree' (duration: 233.908774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:32.372282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.850221ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:15:32.372377Z","caller":"traceutil/trace.go:171","msg":"trace[1065996869] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"174.065089ms","start":"2026-02-04T05:15:32.198291Z","end":"2026-02-04T05:15:32.372356Z","steps":["trace[1065996869] 'process raft request' (duration: 69.065204ms)","trace[1065996869] 'compare' (duration: 104.475474ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:15:33.549865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.439006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-04T05:15:33.549936Z","caller":"traceutil/trace.go:171","msg":"trace[94702501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:630; }","duration":"201.62412ms","start":"2026-02-04T05:15:33.348288Z","end":"2026-02-04T05:15:33.549912Z","steps":["trace[94702501] 'range keys from in-memory index tree' (duration: 201.268543ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:33.55359Z","caller":"traceutil/trace.go:171","msg":"trace[614544173] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"164.567783ms","start":"2026-02-04T05:15:33.389012Z","end":"2026-02-04T05:15:33.55358Z","steps":["trace[614544173] 'process raft request' (duration: 164.048743ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:39.005508Z","caller":"traceutil/trace.go:171","msg":"trace[852742440] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"221.320841ms","start":"2026-02-04T05:15:38.784174Z","end":"2026-02-04T05:15:39.005495Z","steps":["trace[852742440] 'process raft request' (duration: 221.063496ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:39.15101Z","caller":"traceutil/trace.go:171","msg":"trace[45072162] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"127.595005ms","start":"2026-02-04T05:15:39.023395Z","end":"2026-02-04T05:15:39.15099Z","steps":["trace[45072162] 'process raft request' (duration: 98.538182ms)","trace[45072162] 'compare' (duration: 28.985102ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:15:41.254597Z","caller":"traceutil/trace.go:171","msg":"trace[9377124] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"164.413737ms","start":"2026-02-04T05:15:41.090166Z","end":"2026-02-04T05:15:41.25458Z","steps":["trace[9377124] 'process raft request' (duration: 164.125532ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:41.460617Z","caller":"traceutil/trace.go:171","msg":"trace[2050660810] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"139.317419ms","start":"2026-02-04T05:15:41.320402Z","end":"2026-02-04T05:15:41.45972Z","steps":["trace[2050660810] 'process raft request' (duration: 139.158536ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:41.640325Z","caller":"traceutil/trace.go:171","msg":"trace[1793194531] linearizableReadLoop","detail":"{readStateIndex:756; appliedIndex:754; }","duration":"318.893264ms","start":"2026-02-04T05:15:41.321417Z","end":"2026-02-04T05:15:41.64031Z","steps":["trace[1793194531] 'read index received' (duration: 138.204017ms)","trace[1793194531] 'applied index is now lower than readState.Index' (duration: 180.688617ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:15:41.640596Z","caller":"traceutil/trace.go:171","msg":"trace[953193237] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"319.228121ms","start":"2026-02-04T05:15:41.321361Z","end":"2026-02-04T05:15:41.640589Z","steps":["trace[953193237] 'process raft request' (duration: 305.676192ms)","trace[953193237] 'compare' (duration: 13.162421ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:15:41.640674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:41.321355Z","time spent":"319.265151ms","remote":"127.0.0.1:52618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3830,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:15:41.640849Z","caller":"traceutil/trace.go:171","msg":"trace[1193614298] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"318.737861ms","start":"2026-02-04T05:15:41.322106Z","end":"2026-02-04T05:15:41.640843Z","steps":["trace[1193614298] 'process raft request' (duration: 318.172301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:41.640884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:41.322085Z","time spent":"318.784083ms","remote":"127.0.0.1:52406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1453,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:15:41.641001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.587027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-n46sq\" ","response":"range_response_count:1 size:4733"} {"level":"info","ts":"2026-02-04T05:15:41.641023Z","caller":"traceutil/trace.go:171","msg":"trace[1666535945] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-n46sq; range_end:; response_count:1; response_revision:731; }","duration":"319.623079ms","start":"2026-02-04T05:15:41.321394Z","end":"2026-02-04T05:15:41.641017Z","steps":["trace[1666535945] 'agreement among raft nodes before linearized reading' (duration: 319.551307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:41.641038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:41.32139Z","time spent":"319.644259ms","remote":"127.0.0.1:52308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4756,"request content":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-n46sq\" "} {"level":"info","ts":"2026-02-04T05:15:41.969934Z","caller":"traceutil/trace.go:171","msg":"trace[493190443] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"314.515521ms","start":"2026-02-04T05:15:41.655403Z","end":"2026-02-04T05:15:41.969919Z","steps":["trace[493190443] 'process raft request' (duration: 314.421509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:41.970037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:41.655375Z","time spent":"314.607312ms","remote":"127.0.0.1:52596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:15:42.012774Z","caller":"traceutil/trace.go:171","msg":"trace[1096279332] linearizableReadLoop","detail":"{readStateIndex:760; appliedIndex:758; }","duration":"347.207223ms","start":"2026-02-04T05:15:41.665547Z","end":"2026-02-04T05:15:42.012754Z","steps":["trace[1096279332] 'read index received' (duration: 304.832656ms)","trace[1096279332] 'applied index is now lower than readState.Index' (duration: 42.373607ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:15:42.013365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.654802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-04T05:15:42.013559Z","caller":"traceutil/trace.go:171","msg":"trace[708462102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:734; }","duration":"348.025878ms","start":"2026-02-04T05:15:41.665516Z","end":"2026-02-04T05:15:42.013541Z","steps":["trace[708462102] 'agreement among raft nodes before linearized reading' (duration: 347.488658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:42.013822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:41.665501Z","time spent":"348.244053ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-04T05:15:42.015709Z","caller":"traceutil/trace.go:171","msg":"trace[693962081] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"358.334615ms","start":"2026-02-04T05:15:41.657359Z","end":"2026-02-04T05:15:42.015693Z","steps":["trace[693962081] 'process raft request' (duration: 354.574593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:42.016072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:41.657352Z","time spent":"358.668831ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:15:42.016745Z","caller":"traceutil/trace.go:171","msg":"trace[131921455] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"352.383521ms","start":"2026-02-04T05:15:41.664346Z","end":"2026-02-04T05:15:42.016729Z","steps":["trace[131921455] 'process raft request' (duration: 347.753843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:42.017119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:15:41.664336Z","time spent":"352.711788ms","remote":"127.0.0.1:52308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4833,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:15:42.367155Z","caller":"traceutil/trace.go:171","msg":"trace[1998382269] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"141.059019ms","start":"2026-02-04T05:15:42.22608Z","end":"2026-02-04T05:15:42.367139Z","steps":["trace[1998382269] 'process raft request' (duration: 141.020668ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:42.367168Z","caller":"traceutil/trace.go:171","msg":"trace[709096248] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"142.869244ms","start":"2026-02-04T05:15:42.224277Z","end":"2026-02-04T05:15:42.367146Z","steps":["trace[709096248] 'process raft request' (duration: 139.765705ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:42.419724Z","caller":"traceutil/trace.go:171","msg":"trace[2063098120] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"165.929089ms","start":"2026-02-04T05:15:42.253778Z","end":"2026-02-04T05:15:42.419708Z","steps":["trace[2063098120] 'process raft request' (duration: 165.650944ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:42.420056Z","caller":"traceutil/trace.go:171","msg":"trace[132012232] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"164.542984ms","start":"2026-02-04T05:15:42.255506Z","end":"2026-02-04T05:15:42.420049Z","steps":["trace[132012232] 'process raft request' (duration: 163.993833ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:42.420342Z","caller":"traceutil/trace.go:171","msg":"trace[853232775] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"164.655236ms","start":"2026-02-04T05:15:42.255679Z","end":"2026-02-04T05:15:42.420335Z","steps":["trace[853232775] 'process raft request' (duration: 163.861991ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:42.420582Z","caller":"traceutil/trace.go:171","msg":"trace[176056483] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"162.888302ms","start":"2026-02-04T05:15:42.257687Z","end":"2026-02-04T05:15:42.420575Z","steps":["trace[176056483] 'process raft request' (duration: 161.899904ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:42.539828Z","caller":"traceutil/trace.go:171","msg":"trace[1895109453] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"100.42984ms","start":"2026-02-04T05:15:42.439379Z","end":"2026-02-04T05:15:42.539808Z","steps":["trace[1895109453] 'process raft request' (duration: 94.231253ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:44.277325Z","caller":"traceutil/trace.go:171","msg":"trace[469643367] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"245.992678ms","start":"2026-02-04T05:15:44.031308Z","end":"2026-02-04T05:15:44.277301Z","steps":["trace[469643367] 'process raft request' (duration: 245.913137ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:44.287252Z","caller":"traceutil/trace.go:171","msg":"trace[1262361630] linearizableReadLoop","detail":"{readStateIndex:784; appliedIndex:783; }","duration":"176.813872ms","start":"2026-02-04T05:15:44.11043Z","end":"2026-02-04T05:15:44.287243Z","steps":["trace[1262361630] 'read index received' (duration: 167.144731ms)","trace[1262361630] 'applied index is now lower than readState.Index' (duration: 9.668651ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:15:44.2873Z","caller":"traceutil/trace.go:171","msg":"trace[1822184103] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"176.889943ms","start":"2026-02-04T05:15:44.110406Z","end":"2026-02-04T05:15:44.287296Z","steps":["trace[1822184103] 'process raft request' (duration: 176.802962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:44.287469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.029036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-4cpbx\" ","response":"range_response_count:1 size:4824"} {"level":"info","ts":"2026-02-04T05:15:44.287491Z","caller":"traceutil/trace.go:171","msg":"trace[520231190] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-4cpbx; range_end:; response_count:1; response_revision:758; }","duration":"177.060927ms","start":"2026-02-04T05:15:44.110425Z","end":"2026-02-04T05:15:44.287485Z","steps":["trace[520231190] 'agreement among raft nodes before linearized reading' (duration: 176.964905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:15:44.2876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.12485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-04T05:15:44.287613Z","caller":"traceutil/trace.go:171","msg":"trace[975693611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:758; }","duration":"161.15883ms","start":"2026-02-04T05:15:44.12645Z","end":"2026-02-04T05:15:44.287609Z","steps":["trace[975693611] 'agreement among raft nodes before linearized reading' (duration: 161.120919ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:50.269146Z","caller":"traceutil/trace.go:171","msg":"trace[1724333135] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"142.212094ms","start":"2026-02-04T05:15:50.126918Z","end":"2026-02-04T05:15:50.26913Z","steps":["trace[1724333135] 'process raft request' (duration: 142.128733ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:52.931598Z","caller":"traceutil/trace.go:171","msg":"trace[66074125] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"120.933573ms","start":"2026-02-04T05:15:52.810647Z","end":"2026-02-04T05:15:52.931581Z","steps":["trace[66074125] 'process raft request' (duration: 120.816991ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:56.666847Z","caller":"traceutil/trace.go:171","msg":"trace[1714979933] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"135.256644ms","start":"2026-02-04T05:15:56.531295Z","end":"2026-02-04T05:15:56.666552Z","steps":["trace[1714979933] 'process raft request' (duration: 135.066611ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:15:56.668683Z","caller":"traceutil/trace.go:171","msg":"trace[661279443] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"136.126567ms","start":"2026-02-04T05:15:56.532539Z","end":"2026-02-04T05:15:56.668666Z","steps":["trace[661279443] 'process raft request' (duration: 136.033176ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:16:01.142445Z","caller":"traceutil/trace.go:171","msg":"trace[607161945] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"127.84426ms","start":"2026-02-04T05:16:01.014582Z","end":"2026-02-04T05:16:01.142426Z","steps":["trace[607161945] 'process raft request' (duration: 127.755549ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:16:10.757988Z","caller":"traceutil/trace.go:171","msg":"trace[1106774757] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"197.161861ms","start":"2026-02-04T05:16:10.560807Z","end":"2026-02-04T05:16:10.757969Z","steps":["trace[1106774757] 'process raft request' (duration: 170.044424ms)","trace[1106774757] 'compare' (duration: 27.001726ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:16:10.899359Z","caller":"traceutil/trace.go:171","msg":"trace[1970406831] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"136.200274ms","start":"2026-02-04T05:16:10.763143Z","end":"2026-02-04T05:16:10.899344Z","steps":["trace[1970406831] 'process raft request' (duration: 136.150213ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:16:10.899482Z","caller":"traceutil/trace.go:171","msg":"trace[33545076] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"136.436417ms","start":"2026-02-04T05:16:10.763041Z","end":"2026-02-04T05:16:10.899478Z","steps":["trace[33545076] 'process raft request' (duration: 133.269098ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:16:23.078348Z","caller":"traceutil/trace.go:171","msg":"trace[396025770] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"151.517307ms","start":"2026-02-04T05:16:22.926808Z","end":"2026-02-04T05:16:23.078325Z","steps":["trace[396025770] 'process raft request' (duration: 151.051081ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:16:25.446294Z","caller":"traceutil/trace.go:171","msg":"trace[196153297] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"285.655128ms","start":"2026-02-04T05:16:25.160625Z","end":"2026-02-04T05:16:25.44628Z","steps":["trace[196153297] 'process raft request' (duration: 285.548736ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:16:25.483918Z","caller":"traceutil/trace.go:171","msg":"trace[791526150] linearizableReadLoop","detail":"{readStateIndex:922; appliedIndex:921; }","duration":"125.650693ms","start":"2026-02-04T05:16:25.358249Z","end":"2026-02-04T05:16:25.483899Z","steps":["trace[791526150] 'read index received' (duration: 88.312629ms)","trace[791526150] 'applied index is now lower than readState.Index' (duration: 37.337214ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:16:25.484095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.832566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-04T05:16:25.484119Z","caller":"traceutil/trace.go:171","msg":"trace[94823431] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:887; }","duration":"125.894336ms","start":"2026-02-04T05:16:25.358217Z","end":"2026-02-04T05:16:25.484112Z","steps":["trace[94823431] 'agreement among raft nodes before linearized reading' (duration: 125.752774ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:16:25.484115Z","caller":"traceutil/trace.go:171","msg":"trace[400241220] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"322.861ms","start":"2026-02-04T05:16:25.161235Z","end":"2026-02-04T05:16:25.484096Z","steps":["trace[400241220] 'process raft request' (duration: 322.548895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:16:25.484218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:16:25.161202Z","time spent":"322.954712ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:16:33.779513Z","caller":"traceutil/trace.go:171","msg":"trace[1759939784] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"111.764321ms","start":"2026-02-04T05:16:33.667733Z","end":"2026-02-04T05:16:33.779498Z","steps":["trace[1759939784] 'process raft request' (duration: 111.379406ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:16:43.122255Z","caller":"traceutil/trace.go:171","msg":"trace[1755779031] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"103.067548ms","start":"2026-02-04T05:16:43.019166Z","end":"2026-02-04T05:16:43.122233Z","steps":["trace[1755779031] 'process raft request' (duration: 102.874516ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:16:45.309276Z","caller":"traceutil/trace.go:171","msg":"trace[554024963] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"110.851934ms","start":"2026-02-04T05:16:45.198396Z","end":"2026-02-04T05:16:45.309248Z","steps":["trace[554024963] 'process raft request' (duration: 110.455208ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:16:47.262722Z","caller":"traceutil/trace.go:171","msg":"trace[1181006615] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"113.996541ms","start":"2026-02-04T05:16:47.148704Z","end":"2026-02-04T05:16:47.262701Z","steps":["trace[1181006615] 'process raft request' (duration: 113.857699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:16:47.426459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.42437ms","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-02-04T05:16:47.426526Z","caller":"traceutil/trace.go:171","msg":"trace[1035690998] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:977; }","duration":"155.498571ms","start":"2026-02-04T05:16:47.271018Z","end":"2026-02-04T05:16:47.426516Z","steps":["trace[1035690998] 'range keys from in-memory index tree' (duration: 155.41672ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:16:48.02569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.015296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-04T05:16:48.025936Z","caller":"traceutil/trace.go:171","msg":"trace[916795998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:979; }","duration":"156.39451ms","start":"2026-02-04T05:16:47.86952Z","end":"2026-02-04T05:16:48.025914Z","steps":["trace[916795998] 'range keys from in-memory index tree' (duration: 155.860874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:16:48.026186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.451635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-04T05:16:48.026366Z","caller":"traceutil/trace.go:171","msg":"trace[109662428] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:979; }","duration":"142.618288ms","start":"2026-02-04T05:16:47.883704Z","end":"2026-02-04T05:16:48.026322Z","steps":["trace[109662428] 'range keys from in-memory index tree' (duration: 142.063391ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:16:48.244934Z","caller":"traceutil/trace.go:171","msg":"trace[1769277395] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"213.338356ms","start":"2026-02-04T05:16:48.031578Z","end":"2026-02-04T05:16:48.244916Z","steps":["trace[1769277395] 'process raft request' (duration: 171.739186ms)","trace[1769277395] 'compare' (duration: 41.523629ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:16:49.322404Z","caller":"traceutil/trace.go:171","msg":"trace[1784590395] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"198.071895ms","start":"2026-02-04T05:16:49.124317Z","end":"2026-02-04T05:16:49.322389Z","steps":["trace[1784590395] 'process raft request' (duration: 197.788301ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:16:50.233109Z","caller":"traceutil/trace.go:171","msg":"trace[263323004] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"114.099873ms","start":"2026-02-04T05:16:50.118985Z","end":"2026-02-04T05:16:50.233085Z","steps":["trace[263323004] 'process raft request' (duration: 113.980341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:16:50.49369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.956317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-04T05:16:50.494109Z","caller":"traceutil/trace.go:171","msg":"trace[810949406] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:988; }","duration":"159.401943ms","start":"2026-02-04T05:16:50.33469Z","end":"2026-02-04T05:16:50.494091Z","steps":["trace[810949406] 'range keys from in-memory index tree' (duration: 158.830117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:16:51.573558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.502543ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:16:51.574005Z","caller":"traceutil/trace.go:171","msg":"trace[1014107358] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1030; }","duration":"584.52348ms","start":"2026-02-04T05:16:50.989468Z","end":"2026-02-04T05:16:51.573992Z","steps":["trace[1014107358] 'read index received' (duration: 39.051µs)","trace[1014107358] 'applied index is now lower than readState.Index' (duration: 584.482989ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:16:51.574154Z","caller":"traceutil/trace.go:171","msg":"trace[722814099] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"898.372408ms","start":"2026-02-04T05:16:50.675773Z","end":"2026-02-04T05:16:51.574145Z","steps":["trace[722814099] 'process raft request' (duration: 127.221177ms)","trace[722814099] 'compare' (duration: 770.428522ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:16:51.574364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:16:50.675759Z","time spent":"898.51736ms","remote":"127.0.0.1:52190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:16:51.574511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.798801ms","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-02-04T05:16:51.574562Z","caller":"traceutil/trace.go:171","msg":"trace[1975279653] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:989; }","duration":"185.890382ms","start":"2026-02-04T05:16:51.388664Z","end":"2026-02-04T05:16:51.574554Z","steps":["trace[1975279653] 'agreement among raft nodes before linearized reading' (duration: 185.71355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:16:51.574764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.294309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:16:51.574797Z","caller":"traceutil/trace.go:171","msg":"trace[562828910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:989; }","duration":"585.36334ms","start":"2026-02-04T05:16:50.989428Z","end":"2026-02-04T05:16:51.574791Z","steps":["trace[562828910] 'agreement among raft nodes before linearized reading' (duration: 585.316299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:16:51.574925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:16:50.989408Z","time spent":"585.508582ms","remote":"127.0.0.1:52154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-04T05:16:51.724034Z","caller":"traceutil/trace.go:171","msg":"trace[1926482764] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"140.649306ms","start":"2026-02-04T05:16:51.583366Z","end":"2026-02-04T05:16:51.724016Z","steps":["trace[1926482764] 'process raft request' (duration: 133.060402ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:16:52.412405Z","caller":"traceutil/trace.go:171","msg":"trace[454637297] linearizableReadLoop","detail":"{readStateIndex:1035; appliedIndex:1034; }","duration":"144.93129ms","start":"2026-02-04T05:16:52.267453Z","end":"2026-02-04T05:16:52.412385Z","steps":["trace[454637297] 'read index received' (duration: 144.784438ms)","trace[454637297] 'applied index is now lower than readState.Index' (duration: 146.082µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:16:52.41276Z","caller":"traceutil/trace.go:171","msg":"trace[1504572146] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"171.725568ms","start":"2026-02-04T05:16:52.24102Z","end":"2026-02-04T05:16:52.412745Z","steps":["trace[1504572146] 'process raft request' (duration: 171.268173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:16:52.412907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.366305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-04T05:16:52.412971Z","caller":"traceutil/trace.go:171","msg":"trace[827743931] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:993; }","duration":"145.542598ms","start":"2026-02-04T05:16:52.267418Z","end":"2026-02-04T05:16:52.412961Z","steps":["trace[827743931] 'agreement among raft nodes before linearized reading' (duration: 145.298905ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:16:59.809394Z","caller":"traceutil/trace.go:171","msg":"trace[1812130312] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"267.172589ms","start":"2026-02-04T05:16:59.542202Z","end":"2026-02-04T05:16:59.809375Z","steps":["trace[1812130312] 'process raft request' (duration: 266.793005ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:16:59.930803Z","caller":"traceutil/trace.go:171","msg":"trace[150164361] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"107.3058ms","start":"2026-02-04T05:16:59.82348Z","end":"2026-02-04T05:16:59.930786Z","steps":["trace[150164361] 'process raft request' (duration: 53.096854ms)","trace[150164361] 'compare' (duration: 54.010284ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:17:00.972816Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305493840746121226,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T05:17:01.472933Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305493840746121226,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T05:17:01.492852Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.156349217s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-04T05:17:01.493395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:17:00.336332Z","time spent":"1.157055566s","remote":"127.0.0.1:52190","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-04T05:17:01.493973Z","caller":"traceutil/trace.go:171","msg":"trace[1947017957] linearizableReadLoop","detail":"{readStateIndex:1064; appliedIndex:1064; }","duration":"1.02205407s","start":"2026-02-04T05:17:00.471891Z","end":"2026-02-04T05:17:01.493945Z","steps":["trace[1947017957] 'read index received' (duration: 1.02204489s)","trace[1947017957] 'applied index is now lower than readState.Index' (duration: 7.01µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:17:01.494171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022261882s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-04T05:17:01.495651Z","caller":"traceutil/trace.go:171","msg":"trace[1269573307] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1020; }","duration":"1.023786051s","start":"2026-02-04T05:17:00.471847Z","end":"2026-02-04T05:17:01.495633Z","steps":["trace[1269573307] 'agreement among raft nodes before linearized reading' (duration: 1.022164042s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:17:01.495783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:17:00.47183Z","time spent":"1.023934423s","remote":"127.0.0.1:52404","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-02-04T05:17:01.495179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.2505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-04T05:17:01.499188Z","caller":"traceutil/trace.go:171","msg":"trace[977072979] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1020; }","duration":"956.292407ms","start":"2026-02-04T05:17:00.54288Z","end":"2026-02-04T05:17:01.499172Z","steps":["trace[977072979] 'agreement among raft nodes before linearized reading' (duration: 952.195728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:17:01.49525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.80384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-04T05:17:01.4953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.568897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-04T05:17:01.499388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:17:00.542866Z","time spent":"956.503829ms","remote":"127.0.0.1:52404","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-02-04T05:17:01.499463Z","caller":"traceutil/trace.go:171","msg":"trace[1865001486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1020; }","duration":"514.026069ms","start":"2026-02-04T05:17:00.985423Z","end":"2026-02-04T05:17:01.499449Z","steps":["trace[1865001486] 'agreement among raft nodes before linearized reading' (duration: 509.79954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:17:01.500181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:17:00.985416Z","time spent":"514.753667ms","remote":"127.0.0.1:52154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-04T05:17:01.499618Z","caller":"traceutil/trace.go:171","msg":"trace[1604605730] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1020; }","duration":"519.886158ms","start":"2026-02-04T05:17:00.979686Z","end":"2026-02-04T05:17:01.499572Z","steps":["trace[1604605730] 'agreement among raft nodes before linearized reading' (duration: 515.584108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:17:01.500387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:17:00.979668Z","time spent":"520.704988ms","remote":"127.0.0.1:36394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-02-04T05:17:01.660378Z","caller":"traceutil/trace.go:171","msg":"trace[1300644495] linearizableReadLoop","detail":"{readStateIndex:1067; appliedIndex:1065; }","duration":"123.456693ms","start":"2026-02-04T05:17:01.536904Z","end":"2026-02-04T05:17:01.660361Z","steps":["trace[1300644495] 'read index received' (duration: 123.285741ms)","trace[1300644495] 'applied index is now lower than readState.Index' (duration: 170.272µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:17:01.660735Z","caller":"traceutil/trace.go:171","msg":"trace[396856759] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"157.500237ms","start":"2026-02-04T05:17:01.503225Z","end":"2026-02-04T05:17:01.660726Z","steps":["trace[396856759] 'process raft request' (duration: 157.0131ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:17:01.661547Z","caller":"traceutil/trace.go:171","msg":"trace[1571419805] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"156.980029ms","start":"2026-02-04T05:17:01.504558Z","end":"2026-02-04T05:17:01.661538Z","steps":["trace[1571419805] 'process raft request' (duration: 155.777394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:17:01.661732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.813619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-04T05:17:01.66177Z","caller":"traceutil/trace.go:171","msg":"trace[2036848903] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1023; }","duration":"124.8624ms","start":"2026-02-04T05:17:01.536901Z","end":"2026-02-04T05:17:01.661763Z","steps":["trace[2036848903] 'agreement among raft nodes before linearized reading' (duration: 124.790639ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:17:02.439373Z","caller":"traceutil/trace.go:171","msg":"trace[760206560] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"105.848659ms","start":"2026-02-04T05:17:02.333482Z","end":"2026-02-04T05:17:02.43933Z","steps":["trace[760206560] 'process raft request' (duration: 105.743268ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:17:10.568963Z","caller":"traceutil/trace.go:171","msg":"trace[1108188128] linearizableReadLoop","detail":"{readStateIndex:1099; appliedIndex:1098; }","duration":"191.330424ms","start":"2026-02-04T05:17:10.377605Z","end":"2026-02-04T05:17:10.568935Z","steps":["trace[1108188128] 'read index received' (duration: 180.138646ms)","trace[1108188128] 'applied index is now lower than readState.Index' (duration: 11.190868ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:17:10.569057Z","caller":"traceutil/trace.go:171","msg":"trace[707058595] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"226.282734ms","start":"2026-02-04T05:17:10.342767Z","end":"2026-02-04T05:17:10.56905Z","steps":["trace[707058595] 'process raft request' (duration: 215.033145ms)","trace[707058595] 'compare' (duration: 11.045817ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:17:10.56955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.861919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:daemon-set-controller\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-02-04T05:17:10.569575Z","caller":"traceutil/trace.go:171","msg":"trace[1980806860] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:daemon-set-controller; range_end:; response_count:1; response_revision:1053; }","duration":"191.996152ms","start":"2026-02-04T05:17:10.377572Z","end":"2026-02-04T05:17:10.569568Z","steps":["trace[1980806860] 'agreement among raft nodes before linearized reading' (duration: 191.8481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:17:10.570031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.078657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:endpoint-controller\" ","response":"range_response_count:1 size:756"} {"level":"info","ts":"2026-02-04T05:17:10.570434Z","caller":"traceutil/trace.go:171","msg":"trace[1618847555] range","detail":"{range_begin:/registry/clusterroles/system:controller:endpoint-controller; range_end:; response_count:1; response_revision:1053; }","duration":"161.512762ms","start":"2026-02-04T05:17:10.408887Z","end":"2026-02-04T05:17:10.570399Z","steps":["trace[1618847555] 'agreement among raft nodes before linearized reading' (duration: 161.052247ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:17:13.994583Z","caller":"traceutil/trace.go:171","msg":"trace[910408409] linearizableReadLoop","detail":"{readStateIndex:1105; appliedIndex:1104; }","duration":"119.708969ms","start":"2026-02-04T05:17:13.874852Z","end":"2026-02-04T05:17:13.994561Z","steps":["trace[910408409] 'read index received' (duration: 119.556377ms)","trace[910408409] 'applied index is now lower than readState.Index' (duration: 151.432µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:17:13.994706Z","caller":"traceutil/trace.go:171","msg":"trace[1441067279] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"120.465087ms","start":"2026-02-04T05:17:13.87423Z","end":"2026-02-04T05:17:13.994695Z","steps":["trace[1441067279] 'process raft request' (duration: 120.200583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:17:13.994733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.107913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:discovery\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-02-04T05:17:13.994764Z","caller":"traceutil/trace.go:171","msg":"trace[799605156] range","detail":"{range_begin:/registry/clusterroles/system:discovery; range_end:; response_count:1; response_revision:1059; }","duration":"105.157724ms","start":"2026-02-04T05:17:13.889598Z","end":"2026-02-04T05:17:13.994756Z","steps":["trace[799605156] 'agreement among raft nodes before linearized reading' (duration: 105.095453ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:17:13.994896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.040183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-04T05:17:13.994914Z","caller":"traceutil/trace.go:171","msg":"trace[872661332] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1059; }","duration":"120.066973ms","start":"2026-02-04T05:17:13.874841Z","end":"2026-02-04T05:17:13.994908Z","steps":["trace[872661332] 'agreement among raft nodes before linearized reading' (duration: 120.003913ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:17:13.995099Z","caller":"traceutil/trace.go:171","msg":"trace[1820275912] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1059; }","duration":"100.009395ms","start":"2026-02-04T05:17:13.895084Z","end":"2026-02-04T05:17:13.995094Z","steps":["trace[1820275912] 'agreement among raft nodes before linearized reading' (duration: 99.982185ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:17:16.175119Z","caller":"traceutil/trace.go:171","msg":"trace[1396331198] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"101.404759ms","start":"2026-02-04T05:17:16.073693Z","end":"2026-02-04T05:17:16.175098Z","steps":["trace[1396331198] 'process raft request' (duration: 101.372069ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:17:16.175652Z","caller":"traceutil/trace.go:171","msg":"trace[1583431165] linearizableReadLoop","detail":"{readStateIndex:1111; appliedIndex:1110; }","duration":"102.003116ms","start":"2026-02-04T05:17:16.07363Z","end":"2026-02-04T05:17:16.175633Z","steps":["trace[1583431165] 'read index received' (duration: 63.140749ms)","trace[1583431165] 'applied index is now lower than readState.Index' (duration: 38.860377ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:17:16.17603Z","caller":"traceutil/trace.go:171","msg":"trace[1610215803] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"104.208511ms","start":"2026-02-04T05:17:16.071799Z","end":"2026-02-04T05:17:16.176008Z","steps":["trace[1610215803] 'process raft request' (duration: 64.96487ms)","trace[1610215803] 'compare' (duration: 38.148849ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:17:16.176399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.747154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:public-info-viewer\" ","response":"range_response_count:1 size:613"} {"level":"info","ts":"2026-02-04T05:17:16.176642Z","caller":"traceutil/trace.go:171","msg":"trace[842119129] range","detail":"{range_begin:/registry/clusterroles/system:public-info-viewer; range_end:; response_count:1; response_revision:1067; }","duration":"102.925776ms","start":"2026-02-04T05:17:16.073613Z","end":"2026-02-04T05:17:16.176539Z","steps":["trace[842119129] 'agreement among raft nodes before linearized reading' (duration: 102.705113ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:17:16.175717Z","caller":"traceutil/trace.go:171","msg":"trace[2071202011] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"102.038067ms","start":"2026-02-04T05:17:16.073661Z","end":"2026-02-04T05:17:16.175699Z","steps":["trace[2071202011] 'process raft request' (duration: 101.330489ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:17:17.010348Z","caller":"traceutil/trace.go:171","msg":"trace[931847581] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"133.104747ms","start":"2026-02-04T05:17:16.877222Z","end":"2026-02-04T05:17:17.010327Z","steps":["trace[931847581] 'process raft request' (duration: 132.769354ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:17:18.296807Z","caller":"traceutil/trace.go:171","msg":"trace[609032954] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"108.827056ms","start":"2026-02-04T05:17:18.187957Z","end":"2026-02-04T05:17:18.296784Z","steps":["trace[609032954] 'process raft request' (duration: 108.751915ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:17:18.297419Z","caller":"traceutil/trace.go:171","msg":"trace[520296373] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"110.584395ms","start":"2026-02-04T05:17:18.18682Z","end":"2026-02-04T05:17:18.297404Z","steps":["trace[520296373] 'process raft request' (duration: 42.696927ms)","trace[520296373] 'compare' (duration: 67.081939ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:17:18.297414Z","caller":"traceutil/trace.go:171","msg":"trace[1955817316] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"108.707223ms","start":"2026-02-04T05:17:18.188653Z","end":"2026-02-04T05:17:18.29736Z","steps":["trace[1955817316] 'process raft request' (duration: 108.098047ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:17:20.492094Z","caller":"traceutil/trace.go:171","msg":"trace[1361797807] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"185.122895ms","start":"2026-02-04T05:17:20.30695Z","end":"2026-02-04T05:17:20.492073Z","steps":["trace[1361797807] 'process raft request' (duration: 171.919778ms)","trace[1361797807] 'compare' (duration: 13.029955ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:17:20.492591Z","caller":"traceutil/trace.go:171","msg":"trace[462165736] linearizableReadLoop","detail":"{readStateIndex:1125; appliedIndex:1123; }","duration":"182.103252ms","start":"2026-02-04T05:17:20.310467Z","end":"2026-02-04T05:17:20.49257Z","steps":["trace[462165736] 'read index received' (duration: 168.41426ms)","trace[462165736] 'applied index is now lower than readState.Index' (duration: 13.688012ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:17:20.49275Z","caller":"traceutil/trace.go:171","msg":"trace[1513065191] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"185.53686ms","start":"2026-02-04T05:17:20.307201Z","end":"2026-02-04T05:17:20.492737Z","steps":["trace[1513065191] 'process raft request' (duration: 185.201426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:17:20.494113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.625579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/default/\" range_end:\"/registry/jobs/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:17:20.494206Z","caller":"traceutil/trace.go:171","msg":"trace[1208538281] range","detail":"{range_begin:/registry/jobs/default/; range_end:/registry/jobs/default0; response_count:0; response_revision:1080; }","duration":"183.73552ms","start":"2026-02-04T05:17:20.310455Z","end":"2026-02-04T05:17:20.494191Z","steps":["trace[1208538281] 'agreement among raft nodes before linearized reading' (duration: 183.611109ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:17:20.494417Z","caller":"traceutil/trace.go:171","msg":"trace[1709583866] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"183.078163ms","start":"2026-02-04T05:17:20.311328Z","end":"2026-02-04T05:17:20.494406Z","steps":["trace[1709583866] 'process raft request' (duration: 181.155671ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:17:20.494668Z","caller":"traceutil/trace.go:171","msg":"trace[1945547844] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"183.278525ms","start":"2026-02-04T05:17:20.311362Z","end":"2026-02-04T05:17:20.494641Z","steps":["trace[1945547844] 'process raft request' (duration: 181.173182ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:17:20.494683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.850563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-04T05:17:20.496407Z","caller":"traceutil/trace.go:171","msg":"trace[1234497876] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:1080; }","duration":"159.565481ms","start":"2026-02-04T05:17:20.336786Z","end":"2026-02-04T05:17:20.496351Z","steps":["trace[1234497876] 'agreement among raft nodes before linearized reading' (duration: 157.649281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:17:20.495228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.249495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-04T05:17:20.496971Z","caller":"traceutil/trace.go:171","msg":"trace[1050144630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1080; }","duration":"159.012735ms","start":"2026-02-04T05:17:20.337943Z","end":"2026-02-04T05:17:20.496955Z","steps":["trace[1050144630] 'agreement among raft nodes before linearized reading' (duration: 157.175664ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:17:20.643245Z","caller":"traceutil/trace.go:171","msg":"trace[1879137191] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"137.690389ms","start":"2026-02-04T05:17:20.505538Z","end":"2026-02-04T05:17:20.643229Z","steps":["trace[1879137191] 'process raft request' (duration: 123.825815ms)","trace[1879137191] 'compare' (duration: 13.615311ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:17:20.685486Z","caller":"traceutil/trace.go:171","msg":"trace[887055651] linearizableReadLoop","detail":"{readStateIndex:1130; appliedIndex:1128; }","duration":"109.605627ms","start":"2026-02-04T05:17:20.575839Z","end":"2026-02-04T05:17:20.685445Z","steps":["trace[887055651] 'read index received' (duration: 53.530164ms)","trace[887055651] 'applied index is now lower than readState.Index' (duration: 56.074493ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:17:20.685723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.868409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/default/\" range_end:\"/registry/networkpolicies/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:17:20.685772Z","caller":"traceutil/trace.go:171","msg":"trace[790525584] range","detail":"{range_begin:/registry/networkpolicies/default/; range_end:/registry/networkpolicies/default0; response_count:0; response_revision:1082; }","duration":"109.957021ms","start":"2026-02-04T05:17:20.575802Z","end":"2026-02-04T05:17:20.685759Z","steps":["trace[790525584] 'agreement among raft nodes before linearized reading' (duration: 109.83889ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:17:20.685596Z","caller":"traceutil/trace.go:171","msg":"trace[197012843] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"138.134153ms","start":"2026-02-04T05:17:20.547442Z","end":"2026-02-04T05:17:20.685576Z","steps":["trace[197012843] 'process raft request' (duration: 137.87414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:17:20.68643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.693736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-node-lease/\" range_end:\"/registry/rolebindings/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:17:20.686646Z","caller":"traceutil/trace.go:171","msg":"trace[1504444019] range","detail":"{range_begin:/registry/rolebindings/kube-node-lease/; range_end:/registry/rolebindings/kube-node-lease0; response_count:0; response_revision:1082; }","duration":"107.938488ms","start":"2026-02-04T05:17:20.578692Z","end":"2026-02-04T05:17:20.68663Z","steps":["trace[1504444019] 'agreement among raft nodes before linearized reading' (duration: 107.681876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:17:20.859527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.928104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/\" range_end:\"/registry/pods/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:17:20.859592Z","caller":"traceutil/trace.go:171","msg":"trace[1303725401] range","detail":"{range_begin:/registry/pods/default/; range_end:/registry/pods/default0; response_count:0; response_revision:1082; }","duration":"104.038335ms","start":"2026-02-04T05:17:20.75554Z","end":"2026-02-04T05:17:20.859579Z","steps":["trace[1303725401] 'range keys from in-memory index tree' (duration: 103.879484ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:17:28.927197Z","caller":"traceutil/trace.go:171","msg":"trace[743967193] linearizableReadLoop","detail":"{readStateIndex:1149; appliedIndex:1148; }","duration":"173.683394ms","start":"2026-02-04T05:17:28.753492Z","end":"2026-02-04T05:17:28.927175Z","steps":["trace[743967193] 'read index received' (duration: 84.405125ms)","trace[743967193] 'applied index is now lower than readState.Index' (duration: 89.277159ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:17:28.927301Z","caller":"traceutil/trace.go:171","msg":"trace[942130031] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"173.952517ms","start":"2026-02-04T05:17:28.753338Z","end":"2026-02-04T05:17:28.92729Z","steps":["trace[942130031] 'process raft request' (duration: 84.567267ms)","trace[942130031] 'compare' (duration: 89.182038ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:17:28.927435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.708806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-l921qx\" ","response":"range_response_count:1 size:540"} {"level":"info","ts":"2026-02-04T05:17:28.927501Z","caller":"traceutil/trace.go:171","msg":"trace[1726931570] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-l921qx; range_end:; response_count:1; response_revision:1100; }","duration":"139.799708ms","start":"2026-02-04T05:17:28.787685Z","end":"2026-02-04T05:17:28.927484Z","steps":["trace[1726931570] 'agreement among raft nodes before linearized reading' (duration: 139.697856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:17:28.927792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.285461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-04T05:17:28.927828Z","caller":"traceutil/trace.go:171","msg":"trace[399402095] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1100; }","duration":"174.349192ms","start":"2026-02-04T05:17:28.753469Z","end":"2026-02-04T05:17:28.927818Z","steps":["trace[399402095] 'agreement among raft nodes before linearized reading' (duration: 174.236791ms)"],"step_count":1}