{"level":"warn","ts":"2026-07-08T21:38:02.43337Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-08T21:38:02.433456Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.194:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.194:2380","--initial-cluster=instance=https://199.19.213.194:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.194:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.194:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-08T21:38:02.433518Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-08T21:38:02.433529Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.194:2380"]} {"level":"info","ts":"2026-07-08T21:38:02.433545Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-08T21:38:02.434169Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.194:2379"]} {"level":"info","ts":"2026-07-08T21:38:02.434258Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.194:2380"],"listen-peer-urls":["https://199.19.213.194:2380"],"advertise-client-urls":["https://199.19.213.194:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.194:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.194:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-08T21:38:02.436823Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.361483ms"} {"level":"info","ts":"2026-07-08T21:38:02.446437Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1444e0f5dfca45ef","cluster-id":"46f0df2abd5471c9"} {"level":"info","ts":"2026-07-08T21:38:02.4465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef switched to configuration voters=()"} {"level":"info","ts":"2026-07-08T21:38:02.446516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef became follower at term 0"} {"level":"info","ts":"2026-07-08T21:38:02.446529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1444e0f5dfca45ef [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-08T21:38:02.446538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef became follower at term 1"} {"level":"info","ts":"2026-07-08T21:38:02.446571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef switched to configuration voters=(1460539525801068015)"} {"level":"warn","ts":"2026-07-08T21:38:02.454989Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-08T21:38:02.457538Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-08T21:38:02.459213Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-08T21:38:02.461466Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1444e0f5dfca45ef","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-08T21:38:02.463661Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-08T21:38:02.463739Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-08T21:38:02.463753Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-08T21:38:02.46369Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1444e0f5dfca45ef","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-08T21:38:02.467841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef switched to configuration voters=(1460539525801068015)"} {"level":"info","ts":"2026-07-08T21:38:02.468002Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"46f0df2abd5471c9","local-member-id":"1444e0f5dfca45ef","added-peer-id":"1444e0f5dfca45ef","added-peer-peer-urls":["https://199.19.213.194:2380"]} {"level":"info","ts":"2026-07-08T21:38:02.468865Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-08T21:38:02.469191Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.194:2380"} {"level":"info","ts":"2026-07-08T21:38:02.469243Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.194:2380"} {"level":"info","ts":"2026-07-08T21:38:02.470062Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1444e0f5dfca45ef","initial-advertise-peer-urls":["https://199.19.213.194:2380"],"listen-peer-urls":["https://199.19.213.194:2380"],"advertise-client-urls":["https://199.19.213.194:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.194:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-08T21:38:02.470145Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-08T21:38:03.047673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef is starting a new election at term 1"} {"level":"info","ts":"2026-07-08T21:38:03.047734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef became pre-candidate at term 1"} {"level":"info","ts":"2026-07-08T21:38:03.047762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef received MsgPreVoteResp from 1444e0f5dfca45ef at term 1"} {"level":"info","ts":"2026-07-08T21:38:03.047783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef became candidate at term 2"} {"level":"info","ts":"2026-07-08T21:38:03.047796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef received MsgVoteResp from 1444e0f5dfca45ef at term 2"} {"level":"info","ts":"2026-07-08T21:38:03.047812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef became leader at term 2"} {"level":"info","ts":"2026-07-08T21:38:03.04785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1444e0f5dfca45ef elected leader 1444e0f5dfca45ef at term 2"} {"level":"info","ts":"2026-07-08T21:38:03.048877Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T21:38:03.049741Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1444e0f5dfca45ef","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.194:2379]}","request-path":"/0/members/1444e0f5dfca45ef/attributes","cluster-id":"46f0df2abd5471c9","publish-timeout":"7s"} {"level":"info","ts":"2026-07-08T21:38:03.049785Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T21:38:03.050381Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T21:38:03.050812Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"46f0df2abd5471c9","local-member-id":"1444e0f5dfca45ef","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T21:38:03.050894Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T21:38:03.050919Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T21:38:03.053836Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.194:2379"} {"level":"info","ts":"2026-07-08T21:38:03.053929Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-08T21:38:03.053967Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-08T21:38:03.057069Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-08T21:38:23.91096Z","caller":"traceutil/trace.go:171","msg":"trace[71457297] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"225.095546ms","start":"2026-07-08T21:38:23.685829Z","end":"2026-07-08T21:38:23.910924Z","steps":["trace[71457297] 'process raft request' (duration: 224.850749ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:38:27.219508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.869749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-08T21:38:27.219474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.862017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-08T21:38:27.219622Z","caller":"traceutil/trace.go:171","msg":"trace[737181877] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:466; }","duration":"402.056274ms","start":"2026-07-08T21:38:26.817544Z","end":"2026-07-08T21:38:27.2196Z","steps":["trace[737181877] 'range keys from in-memory index tree' (duration: 401.744986ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:38:27.219653Z","caller":"traceutil/trace.go:171","msg":"trace[1456599773] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:466; }","duration":"177.130565ms","start":"2026-07-08T21:38:27.042492Z","end":"2026-07-08T21:38:27.219622Z","steps":["trace[1456599773] 'range keys from in-memory index tree' (duration: 176.657301ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:38:27.219673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:38:26.817524Z","time spent":"402.138886ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-08T21:38:27.566735Z","caller":"traceutil/trace.go:171","msg":"trace[1237160807] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"339.770308ms","start":"2026-07-08T21:38:27.226942Z","end":"2026-07-08T21:38:27.566712Z","steps":["trace[1237160807] 'process raft request' (duration: 339.626815ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:38:27.567494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:38:27.22693Z","time spent":"339.882442ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T21:38:27.56906Z","caller":"traceutil/trace.go:171","msg":"trace[679183559] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"341.270409ms","start":"2026-07-08T21:38:27.227766Z","end":"2026-07-08T21:38:27.569037Z","steps":["trace[679183559] 'process raft request' (duration: 341.173616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:38:27.569199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:38:27.22775Z","time spent":"341.381352ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T21:38:39.153884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.867651ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T21:38:39.154162Z","caller":"traceutil/trace.go:171","msg":"trace[1019644375] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:577; }","duration":"554.74421ms","start":"2026-07-08T21:38:38.599403Z","end":"2026-07-08T21:38:39.154147Z","steps":["trace[1019644375] 'read index received' (duration: 121.455177ms)","trace[1019644375] 'applied index is now lower than readState.Index' (duration: 433.288413ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:38:39.154306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.910534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-08T21:38:39.154327Z","caller":"traceutil/trace.go:171","msg":"trace[2105579171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:562; }","duration":"554.932885ms","start":"2026-07-08T21:38:38.599386Z","end":"2026-07-08T21:38:39.154319Z","steps":["trace[2105579171] 'agreement among raft nodes before linearized reading' (duration: 554.807852ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:38:39.15434Z","caller":"traceutil/trace.go:171","msg":"trace[847759463] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"822.889742ms","start":"2026-07-08T21:38:38.331424Z","end":"2026-07-08T21:38:39.154314Z","steps":["trace[847759463] 'process raft request' (duration: 389.345552ms)","trace[847759463] 'compare' (duration: 432.729398ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:38:39.154353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:38:38.599316Z","time spent":"555.032168ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-08T21:38:39.15448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:38:38.331412Z","time spent":"823.006716ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T21:38:39.154593Z","caller":"traceutil/trace.go:171","msg":"trace[1043986051] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"813.250523ms","start":"2026-07-08T21:38:38.341335Z","end":"2026-07-08T21:38:39.154586Z","steps":["trace[1043986051] 'process raft request' (duration: 812.651437ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:38:39.154637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:38:38.341313Z","time spent":"813.299194ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T21:38:39.154674Z","caller":"traceutil/trace.go:171","msg":"trace[1679854199] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"785.876777ms","start":"2026-07-08T21:38:38.368775Z","end":"2026-07-08T21:38:39.154652Z","steps":["trace[1679854199] 'process raft request' (duration: 785.326402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:38:39.154723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:38:38.368749Z","time spent":"785.950979ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T21:38:45.330251Z","caller":"traceutil/trace.go:171","msg":"trace[452981604] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"139.615775ms","start":"2026-07-08T21:38:45.190607Z","end":"2026-07-08T21:38:45.330223Z","steps":["trace[452981604] 'read index received' (duration: 138.992918ms)","trace[452981604] 'applied index is now lower than readState.Index' (duration: 621.877µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:38:45.330332Z","caller":"traceutil/trace.go:171","msg":"trace[1673390144] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"419.412802ms","start":"2026-07-08T21:38:44.910873Z","end":"2026-07-08T21:38:45.330286Z","steps":["trace[1673390144] 'process raft request' (duration: 418.785825ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:38:45.330504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:38:44.910841Z","time spent":"419.573045ms","remote":"127.0.0.1:58290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T21:38:45.330506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.881473ms","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-07-08T21:38:45.33058Z","caller":"traceutil/trace.go:171","msg":"trace[1721866085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"140.010866ms","start":"2026-07-08T21:38:45.19055Z","end":"2026-07-08T21:38:45.330561Z","steps":["trace[1721866085] 'agreement among raft nodes before linearized reading' (duration: 139.77662ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:38:45.446264Z","caller":"traceutil/trace.go:171","msg":"trace[635471802] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"115.903397ms","start":"2026-07-08T21:38:45.33033Z","end":"2026-07-08T21:38:45.446233Z","steps":["trace[635471802] 'read index received' (duration: 79.074767ms)","trace[635471802] 'applied index is now lower than readState.Index' (duration: 36.8278ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:38:45.446277Z","caller":"traceutil/trace.go:171","msg":"trace[1061549290] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"158.121863ms","start":"2026-07-08T21:38:45.28813Z","end":"2026-07-08T21:38:45.446252Z","steps":["trace[1061549290] 'process raft request' (duration: 121.320443ms)","trace[1061549290] 'compare' (duration: 36.659066ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:38:45.446511Z","caller":"traceutil/trace.go:171","msg":"trace[1838414385] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"112.509227ms","start":"2026-07-08T21:38:45.333992Z","end":"2026-07-08T21:38:45.446501Z","steps":["trace[1838414385] 'process raft request' (duration: 112.209939ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:38:45.446522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.590804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T21:38:45.446624Z","caller":"traceutil/trace.go:171","msg":"trace[816170400] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"220.705067ms","start":"2026-07-08T21:38:45.225909Z","end":"2026-07-08T21:38:45.446614Z","steps":["trace[816170400] 'agreement among raft nodes before linearized reading' (duration: 220.516322ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:38:46.251664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.722296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-08T21:38:46.25176Z","caller":"traceutil/trace.go:171","msg":"trace[1925720158] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:635; }","duration":"565.842899ms","start":"2026-07-08T21:38:45.685896Z","end":"2026-07-08T21:38:46.251739Z","steps":["trace[1925720158] 'range keys from in-memory index tree' (duration: 565.595342ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:38:46.251805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:38:45.685888Z","time spent":"565.90857ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-08T21:38:46.252089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.554922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.194\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-08T21:38:46.25218Z","caller":"traceutil/trace.go:171","msg":"trace[1678320898] range","detail":"{range_begin:/registry/masterleases/199.19.213.194; range_end:; response_count:1; response_revision:635; }","duration":"384.684506ms","start":"2026-07-08T21:38:45.867481Z","end":"2026-07-08T21:38:46.252166Z","steps":["trace[1678320898] 'range keys from in-memory index tree' (duration: 384.430059ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:38:46.25221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:38:45.867463Z","time spent":"384.741398ms","remote":"127.0.0.1:58226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.194\" "} {"level":"warn","ts":"2026-07-08T21:38:46.252425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.576529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-08T21:38:46.252405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.380367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nbqvq\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-08T21:38:46.252519Z","caller":"traceutil/trace.go:171","msg":"trace[1107199312] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nbqvq; range_end:; response_count:1; response_revision:635; }","duration":"597.539432ms","start":"2026-07-08T21:38:45.65495Z","end":"2026-07-08T21:38:46.25249Z","steps":["trace[1107199312] 'range keys from in-memory index tree' (duration: 597.163571ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:38:46.252525Z","caller":"traceutil/trace.go:171","msg":"trace[1704632249] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"377.322408ms","start":"2026-07-08T21:38:45.875182Z","end":"2026-07-08T21:38:46.252504Z","steps":["trace[1704632249] 'process raft request' (duration: 340.092037ms)","trace[1704632249] 'compare' (duration: 36.582574ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:38:46.252588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:38:45.654936Z","time spent":"597.632634ms","remote":"127.0.0.1:58372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-nbqvq\" "} {"level":"info","ts":"2026-07-08T21:38:46.252454Z","caller":"traceutil/trace.go:171","msg":"trace[1982134561] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:635; }","duration":"569.60582ms","start":"2026-07-08T21:38:45.682842Z","end":"2026-07-08T21:38:46.252448Z","steps":["trace[1982134561] 'range keys from in-memory index tree' (duration: 569.478666ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:38:46.25271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:38:45.875163Z","time spent":"377.442391ms","remote":"127.0.0.1:58290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T21:38:46.252724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:38:45.68276Z","time spent":"569.914698ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-08T21:38:48.184583Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.87153528s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-08T21:38:48.315334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.433418ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T21:38:48.31564Z","caller":"traceutil/trace.go:171","msg":"trace[627193146] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"343.986412ms","start":"2026-07-08T21:38:47.971636Z","end":"2026-07-08T21:38:48.315623Z","steps":["trace[627193146] 'process raft request' (duration: 343.939401ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:38:48.315709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:38:47.971578Z","time spent":"344.096846ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T21:38:48.315894Z","caller":"traceutil/trace.go:171","msg":"trace[1909481080] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"2.046845905s","start":"2026-07-08T21:38:46.269036Z","end":"2026-07-08T21:38:48.315882Z","steps":["trace[1909481080] 'process raft request' (duration: 1.915754159s)","trace[1909481080] 'compare' (duration: 127.500039ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:38:48.315946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:38:46.269021Z","time spent":"2.046897126s","remote":"127.0.0.1:58226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T21:38:48.316196Z","caller":"traceutil/trace.go:171","msg":"trace[541737321] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:658; }","duration":"2.035178391s","start":"2026-07-08T21:38:46.280967Z","end":"2026-07-08T21:38:48.316145Z","steps":["trace[541737321] 'read index received' (duration: 32.623927ms)","trace[541737321] 'applied index is now lower than readState.Index' (duration: 2.002545714s)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:38:48.316342Z","caller":"traceutil/trace.go:171","msg":"trace[1181797946] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"2.005703988s","start":"2026-07-08T21:38:46.310626Z","end":"2026-07-08T21:38:48.31633Z","steps":["trace[1181797946] 'process raft request' (duration: 2.004907247s)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:38:48.316389Z","caller":"traceutil/trace.go:171","msg":"trace[642060501] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"2.040867264s","start":"2026-07-08T21:38:46.275422Z","end":"2026-07-08T21:38:48.316289Z","steps":["trace[642060501] 'process raft request' (duration: 2.040026231s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:38:48.316432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:38:46.310609Z","time spent":"2.00575913s","remote":"127.0.0.1:58440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T21:38:48.316483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:38:46.275396Z","time spent":"2.041042329s","remote":"127.0.0.1:58372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T21:38:48.316642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.035675014s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-08T21:38:48.316667Z","caller":"traceutil/trace.go:171","msg":"trace[1120949317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:642; }","duration":"2.035733886s","start":"2026-07-08T21:38:46.280927Z","end":"2026-07-08T21:38:48.316661Z","steps":["trace[1120949317] 'agreement among raft nodes before linearized reading' (duration: 2.035614483s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:38:48.31669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:38:46.280862Z","time spent":"2.035823479s","remote":"127.0.0.1:58474","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":"warn","ts":"2026-07-08T21:38:48.316703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.093297877s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T21:38:48.316743Z","caller":"traceutil/trace.go:171","msg":"trace[1065083094] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"1.093373489s","start":"2026-07-08T21:38:47.223357Z","end":"2026-07-08T21:38:48.316731Z","steps":["trace[1065083094] 'agreement among raft nodes before linearized reading' (duration: 1.093311647s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:38:48.316772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:38:47.223339Z","time spent":"1.093427111s","remote":"127.0.0.1:58202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-08T21:38:48.316866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.454841ms","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-07-08T21:38:48.316921Z","caller":"traceutil/trace.go:171","msg":"trace[1978309814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:642; }","duration":"864.550504ms","start":"2026-07-08T21:38:47.452355Z","end":"2026-07-08T21:38:48.316906Z","steps":["trace[1978309814] 'agreement among raft nodes before linearized reading' (duration: 864.379349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:38:48.316969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:38:47.452326Z","time spent":"864.630656ms","remote":"127.0.0.1:58474","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-07-08T21:38:49.888958Z","caller":"traceutil/trace.go:171","msg":"trace[1656566465] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:680; }","duration":"114.235863ms","start":"2026-07-08T21:38:49.774696Z","end":"2026-07-08T21:38:49.888931Z","steps":["trace[1656566465] 'read index received' (duration: 113.534714ms)","trace[1656566465] 'applied index is now lower than readState.Index' (duration: 699.939µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:38:49.889082Z","caller":"traceutil/trace.go:171","msg":"trace[1862825126] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"151.363961ms","start":"2026-07-08T21:38:49.737707Z","end":"2026-07-08T21:38:49.889071Z","steps":["trace[1862825126] 'process raft request' (duration: 150.535049ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:38:49.889543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.839719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T21:38:49.8896Z","caller":"traceutil/trace.go:171","msg":"trace[1894534821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:661; }","duration":"114.90706ms","start":"2026-07-08T21:38:49.774679Z","end":"2026-07-08T21:38:49.889586Z","steps":["trace[1894534821] 'agreement among raft nodes before linearized reading' (duration: 114.811108ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:38:49.889211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.691282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T21:38:49.889927Z","caller":"traceutil/trace.go:171","msg":"trace[1186130952] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:661; }","duration":"103.407881ms","start":"2026-07-08T21:38:49.786496Z","end":"2026-07-08T21:38:49.889904Z","steps":["trace[1186130952] 'agreement among raft nodes before linearized reading' (duration: 102.665981ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:39:00.137297Z","caller":"traceutil/trace.go:171","msg":"trace[1278135683] linearizableReadLoop","detail":"{readStateIndex:801; appliedIndex:800; }","duration":"149.942263ms","start":"2026-07-08T21:38:59.987318Z","end":"2026-07-08T21:39:00.13726Z","steps":["trace[1278135683] 'read index received' (duration: 149.533942ms)","trace[1278135683] 'applied index is now lower than readState.Index' (duration: 407.081µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:39:00.13735Z","caller":"traceutil/trace.go:171","msg":"trace[1917812517] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"362.903881ms","start":"2026-07-08T21:38:59.774401Z","end":"2026-07-08T21:39:00.137305Z","steps":["trace[1917812517] 'process raft request' (duration: 362.571352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:39:00.137621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:38:59.774381Z","time spent":"363.072235ms","remote":"127.0.0.1:58372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5035,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T21:39:00.137635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.284832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-08T21:39:00.137734Z","caller":"traceutil/trace.go:171","msg":"trace[2047767832] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:778; }","duration":"150.445676ms","start":"2026-07-08T21:38:59.987264Z","end":"2026-07-08T21:39:00.13771Z","steps":["trace[2047767832] 'agreement among raft nodes before linearized reading' (duration: 150.136988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:39:03.725906Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039421621024871601,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T21:39:04.204616Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.41328148s","expected-duration":"1s"} {"level":"info","ts":"2026-07-08T21:39:04.205222Z","caller":"traceutil/trace.go:171","msg":"trace[817574151] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"979.930664ms","start":"2026-07-08T21:39:03.225266Z","end":"2026-07-08T21:39:04.205196Z","steps":["trace[817574151] 'read index received' (duration: 979.618065ms)","trace[817574151] 'applied index is now lower than readState.Index' (duration: 310.989µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:39:04.205352Z","caller":"traceutil/trace.go:171","msg":"trace[1999727670] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"1.414150653s","start":"2026-07-08T21:39:02.791136Z","end":"2026-07-08T21:39:04.205287Z","steps":["trace[1999727670] 'process raft request' (duration: 1.413875906s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:39:04.205415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.131209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T21:39:04.205463Z","caller":"traceutil/trace.go:171","msg":"trace[1479415868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:804; }","duration":"980.241172ms","start":"2026-07-08T21:39:03.225206Z","end":"2026-07-08T21:39:04.205447Z","steps":["trace[1479415868] 'agreement among raft nodes before linearized reading' (duration: 980.14884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:39:04.205502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:39:03.225183Z","time spent":"980.312344ms","remote":"127.0.0.1:58200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-08T21:39:04.205572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:39:02.79111Z","time spent":"1.414341039s","remote":"127.0.0.1:58372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T21:39:04.32132Z","caller":"traceutil/trace.go:171","msg":"trace[1583829864] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"110.43669ms","start":"2026-07-08T21:39:04.210848Z","end":"2026-07-08T21:39:04.321284Z","steps":["trace[1583829864] 'process raft request' (duration: 101.302035ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:39:33.115101Z","caller":"traceutil/trace.go:171","msg":"trace[1133315778] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"109.967826ms","start":"2026-07-08T21:39:33.005116Z","end":"2026-07-08T21:39:33.115084Z","steps":["trace[1133315778] 'process raft request' (duration: 109.865274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:39:33.621757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.288468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T21:39:33.621945Z","caller":"traceutil/trace.go:171","msg":"trace[577548778] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:997; }","duration":"397.527564ms","start":"2026-07-08T21:39:33.224394Z","end":"2026-07-08T21:39:33.621922Z","steps":["trace[577548778] 'range keys from in-memory index tree' (duration: 397.205926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:39:33.621996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:39:33.224376Z","time spent":"397.609197ms","remote":"127.0.0.1:58200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-08T21:39:39.72461Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039421621024872068,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T21:39:40.037433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.386347ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T21:39:40.037617Z","caller":"traceutil/trace.go:171","msg":"trace[2036120497] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1053; }","duration":"813.905832ms","start":"2026-07-08T21:39:39.223698Z","end":"2026-07-08T21:39:40.037604Z","steps":["trace[2036120497] 'read index received' (duration: 624.288289ms)","trace[2036120497] 'applied index is now lower than readState.Index' (duration: 189.616873ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:39:40.037706Z","caller":"traceutil/trace.go:171","msg":"trace[365726934] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"1.011864968s","start":"2026-07-08T21:39:39.025811Z","end":"2026-07-08T21:39:40.037676Z","steps":["trace[365726934] 'process raft request' (duration: 822.161692ms)","trace[365726934] 'compare' (duration: 189.272544ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:39:40.037748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:39:39.025778Z","time spent":"1.01194412s","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T21:39:40.037946Z","caller":"traceutil/trace.go:171","msg":"trace[889928373] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"902.001644ms","start":"2026-07-08T21:39:39.135938Z","end":"2026-07-08T21:39:40.03794Z","steps":["trace[889928373] 'process raft request' (duration: 901.589353ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:39:40.037981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:39:39.135906Z","time spent":"902.055275ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T21:39:40.038331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.435364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T21:39:40.038375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.353473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T21:39:40.038431Z","caller":"traceutil/trace.go:171","msg":"trace[40717760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1022; }","duration":"814.770184ms","start":"2026-07-08T21:39:39.223645Z","end":"2026-07-08T21:39:40.038415Z","steps":["trace[40717760] 'agreement among raft nodes before linearized reading' (duration: 814.359623ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:39:40.038433Z","caller":"traceutil/trace.go:171","msg":"trace[871225144] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1022; }","duration":"651.586917ms","start":"2026-07-08T21:39:39.386808Z","end":"2026-07-08T21:39:40.038395Z","steps":["trace[871225144] 'agreement among raft nodes before linearized reading' (duration: 651.428513ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:39:40.038455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:39:39.223628Z","time spent":"814.821836ms","remote":"127.0.0.1:58200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-08T21:39:40.038494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:39:39.386786Z","time spent":"651.69034ms","remote":"127.0.0.1:58714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-08T21:39:40.366344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.586491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T21:39:40.366484Z","caller":"traceutil/trace.go:171","msg":"trace[1090150529] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1022; }","duration":"141.795187ms","start":"2026-07-08T21:39:40.224657Z","end":"2026-07-08T21:39:40.366452Z","steps":["trace[1090150529] 'range keys from in-memory index tree' (duration: 141.492678ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:39:40.680589Z","caller":"traceutil/trace.go:171","msg":"trace[599451419] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"123.266981ms","start":"2026-07-08T21:39:40.557298Z","end":"2026-07-08T21:39:40.680565Z","steps":["trace[599451419] 'process raft request' (duration: 123.121187ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:40:04.795729Z","caller":"traceutil/trace.go:171","msg":"trace[1589709258] linearizableReadLoop","detail":"{readStateIndex:1138; appliedIndex:1137; }","duration":"421.688409ms","start":"2026-07-08T21:40:04.374016Z","end":"2026-07-08T21:40:04.795704Z","steps":["trace[1589709258] 'read index received' (duration: 421.528235ms)","trace[1589709258] 'applied index is now lower than readState.Index' (duration: 158.614µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:40:04.795887Z","caller":"traceutil/trace.go:171","msg":"trace[1405613480] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"662.430948ms","start":"2026-07-08T21:40:04.133425Z","end":"2026-07-08T21:40:04.795856Z","steps":["trace[1405613480] 'process raft request' (duration: 662.104559ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:40:04.796033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.991597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-08T21:40:04.796049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:40:04.133364Z","time spent":"662.596832ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T21:40:04.796162Z","caller":"traceutil/trace.go:171","msg":"trace[127960977] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1100; }","duration":"422.152001ms","start":"2026-07-08T21:40:04.373971Z","end":"2026-07-08T21:40:04.796123Z","steps":["trace[127960977] 'agreement among raft nodes before linearized reading' (duration: 421.940936ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:40:04.796228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:40:04.373949Z","time spent":"422.264495ms","remote":"127.0.0.1:58232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-07-08T21:40:05.049587Z","caller":"traceutil/trace.go:171","msg":"trace[1240179183] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"248.35222ms","start":"2026-07-08T21:40:04.801199Z","end":"2026-07-08T21:40:05.049551Z","steps":["trace[1240179183] 'process raft request' (duration: 248.144835ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:40:05.223536Z","caller":"traceutil/trace.go:171","msg":"trace[869249544] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"105.090696ms","start":"2026-07-08T21:40:05.118418Z","end":"2026-07-08T21:40:05.223509Z","steps":["trace[869249544] 'process raft request' (duration: 104.005197ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:40:05.223907Z","caller":"traceutil/trace.go:171","msg":"trace[1071917925] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"105.202729ms","start":"2026-07-08T21:40:05.118675Z","end":"2026-07-08T21:40:05.223877Z","steps":["trace[1071917925] 'process raft request' (duration: 105.139848ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:40:07.000249Z","caller":"traceutil/trace.go:171","msg":"trace[2122728717] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"195.961682ms","start":"2026-07-08T21:40:06.804249Z","end":"2026-07-08T21:40:07.00021Z","steps":["trace[2122728717] 'process raft request' (duration: 195.672365ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:40:07.01196Z","caller":"traceutil/trace.go:171","msg":"trace[1484765209] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"128.44704ms","start":"2026-07-08T21:40:06.883479Z","end":"2026-07-08T21:40:07.011926Z","steps":["trace[1484765209] 'process raft request' (duration: 128.280486ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:40:07.209827Z","caller":"traceutil/trace.go:171","msg":"trace[1423666220] linearizableReadLoop","detail":"{readStateIndex:1148; appliedIndex:1147; }","duration":"118.855244ms","start":"2026-07-08T21:40:07.09094Z","end":"2026-07-08T21:40:07.209796Z","steps":["trace[1423666220] 'read index received' (duration: 89.533391ms)","trace[1423666220] 'applied index is now lower than readState.Index' (duration: 29.320473ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:40:07.210453Z","caller":"traceutil/trace.go:171","msg":"trace[885649048] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"149.632165ms","start":"2026-07-08T21:40:07.060753Z","end":"2026-07-08T21:40:07.210385Z","steps":["trace[885649048] 'process raft request' (duration: 119.793539ms)","trace[885649048] 'compare' (duration: 28.83429ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:40:07.210687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.628435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-08T21:40:07.210816Z","caller":"traceutil/trace.go:171","msg":"trace[1959243427] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1109; }","duration":"119.928342ms","start":"2026-07-08T21:40:07.090863Z","end":"2026-07-08T21:40:07.210791Z","steps":["trace[1959243427] 'agreement among raft nodes before linearized reading' (duration: 119.625664ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:40:09.720268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.573144ms","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-07-08T21:40:09.720336Z","caller":"traceutil/trace.go:171","msg":"trace[645603200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1114; }","duration":"437.688317ms","start":"2026-07-08T21:40:09.282634Z","end":"2026-07-08T21:40:09.720322Z","steps":["trace[645603200] 'range keys from in-memory index tree' (duration: 437.383019ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:40:09.720368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:40:09.282622Z","time spent":"437.739929ms","remote":"127.0.0.1:58474","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":"warn","ts":"2026-07-08T21:40:09.720493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.506289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-08T21:40:09.720779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.859193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-08T21:40:09.720799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.303338ms","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-07-08T21:40:09.721771Z","caller":"traceutil/trace.go:171","msg":"trace[1060300264] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1114; }","duration":"359.309795ms","start":"2026-07-08T21:40:09.362437Z","end":"2026-07-08T21:40:09.721747Z","steps":["trace[1060300264] 'range keys from in-memory index tree' (duration: 358.099752ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:40:09.721088Z","caller":"traceutil/trace.go:171","msg":"trace[2067270142] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1114; }","duration":"486.012448ms","start":"2026-07-08T21:40:09.234829Z","end":"2026-07-08T21:40:09.720841Z","steps":["trace[2067270142] 'range keys from in-memory index tree' (duration: 485.368071ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:40:09.721859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:40:09.362426Z","time spent":"359.398647ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-08T21:40:09.721925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:40:09.234807Z","time spent":"487.063206ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-08T21:40:09.721084Z","caller":"traceutil/trace.go:171","msg":"trace[1968008294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1114; }","duration":"456.88746ms","start":"2026-07-08T21:40:09.263924Z","end":"2026-07-08T21:40:09.720811Z","steps":["trace[1968008294] 'range keys from in-memory index tree' (duration: 456.379357ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:40:09.722041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:40:09.263907Z","time spent":"458.116743ms","remote":"127.0.0.1:58474","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":"warn","ts":"2026-07-08T21:40:09.721157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.841517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T21:40:09.7222Z","caller":"traceutil/trace.go:171","msg":"trace[1399438279] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1114; }","duration":"497.907716ms","start":"2026-07-08T21:40:09.224279Z","end":"2026-07-08T21:40:09.722186Z","steps":["trace[1399438279] 'range keys from in-memory index tree' (duration: 496.747934ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:40:09.72127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.984464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-08T21:40:09.722344Z","caller":"traceutil/trace.go:171","msg":"trace[2069700604] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1114; }","duration":"506.951396ms","start":"2026-07-08T21:40:09.21538Z","end":"2026-07-08T21:40:09.722331Z","steps":["trace[2069700604] 'range keys from in-memory index tree' (duration: 504.768168ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:40:09.722387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:40:09.215352Z","time spent":"507.025059ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-08T21:40:09.722401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:40:09.224267Z","time spent":"498.007528ms","remote":"127.0.0.1:58200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-08T21:40:09.906229Z","caller":"traceutil/trace.go:171","msg":"trace[1569110554] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"180.566141ms","start":"2026-07-08T21:40:09.725638Z","end":"2026-07-08T21:40:09.906204Z","steps":["trace[1569110554] 'process raft request' (duration: 180.418927ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:40:09.985181Z","caller":"traceutil/trace.go:171","msg":"trace[1891560967] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"259.51433ms","start":"2026-07-08T21:40:09.725648Z","end":"2026-07-08T21:40:09.985162Z","steps":["trace[1891560967] 'process raft request' (duration: 259.353685ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:40:09.985345Z","caller":"traceutil/trace.go:171","msg":"trace[298893876] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"259.481968ms","start":"2026-07-08T21:40:09.725856Z","end":"2026-07-08T21:40:09.985338Z","steps":["trace[298893876] 'process raft request' (duration: 259.248332ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:40:09.985499Z","caller":"traceutil/trace.go:171","msg":"trace[123476238] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"259.52852ms","start":"2026-07-08T21:40:09.725963Z","end":"2026-07-08T21:40:09.985492Z","steps":["trace[123476238] 'process raft request' (duration: 259.352696ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:40:09.985591Z","caller":"traceutil/trace.go:171","msg":"trace[902078070] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"258.962475ms","start":"2026-07-08T21:40:09.726622Z","end":"2026-07-08T21:40:09.985584Z","steps":["trace[902078070] 'process raft request' (duration: 258.844822ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:40:25.256562Z","caller":"traceutil/trace.go:171","msg":"trace[945737232] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"123.904848ms","start":"2026-07-08T21:40:25.132306Z","end":"2026-07-08T21:40:25.256211Z","steps":["trace[945737232] 'process raft request' (duration: 123.669721ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:40:26.725239Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039421621024872560,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T21:40:27.225572Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039421621024872560,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T21:40:27.663851Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.533851036s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-08T21:40:27.744688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.614647753s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T21:40:27.744859Z","caller":"traceutil/trace.go:171","msg":"trace[1521353431] linearizableReadLoop","detail":"{readStateIndex:1268; appliedIndex:1264; }","duration":"1.520173891s","start":"2026-07-08T21:40:26.224675Z","end":"2026-07-08T21:40:27.744849Z","steps":["trace[1521353431] 'read index received' (duration: 1.439327272s)","trace[1521353431] 'applied index is now lower than readState.Index' (duration: 80.846129ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:40:27.744901Z","caller":"traceutil/trace.go:171","msg":"trace[299690320] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"1.667497284s","start":"2026-07-08T21:40:26.077397Z","end":"2026-07-08T21:40:27.744895Z","steps":["trace[299690320] 'process raft request' (duration: 1.667419612s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:40:27.744933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:40:26.077385Z","time spent":"1.667528094s","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T21:40:27.744919Z","caller":"traceutil/trace.go:171","msg":"trace[2140324594] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"1.672925059s","start":"2026-07-08T21:40:26.071958Z","end":"2026-07-08T21:40:27.744883Z","steps":["trace[2140324594] 'process raft request' (duration: 1.672811986s)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:40:27.745044Z","caller":"traceutil/trace.go:171","msg":"trace[1436462538] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"1.666993841s","start":"2026-07-08T21:40:26.078044Z","end":"2026-07-08T21:40:27.745038Z","steps":["trace[1436462538] 'process raft request' (duration: 1.666788035s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:40:27.745071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:40:26.078036Z","time spent":"1.667021931s","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T21:40:27.745094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:40:26.071932Z","time spent":"1.673064973s","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T21:40:27.745164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.520498339s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T21:40:27.74518Z","caller":"traceutil/trace.go:171","msg":"trace[1215233261] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1223; }","duration":"1.520513809s","start":"2026-07-08T21:40:26.224661Z","end":"2026-07-08T21:40:27.745175Z","steps":["trace[1215233261] 'agreement among raft nodes before linearized reading' (duration: 1.520466148s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:40:27.745194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:40:26.224607Z","time spent":"1.520583952s","remote":"127.0.0.1:58200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-08T21:40:27.745331Z","caller":"traceutil/trace.go:171","msg":"trace[416138296] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"1.673457143s","start":"2026-07-08T21:40:26.07186Z","end":"2026-07-08T21:40:27.745317Z","steps":["trace[416138296] 'process raft request' (duration: 58.126372ms)","trace[416138296] 'compare' (duration: 1.614466648s)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:40:27.745392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:40:26.071837Z","time spent":"1.673522914s","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T21:40:27.922824Z","caller":"traceutil/trace.go:171","msg":"trace[329489406] linearizableReadLoop","detail":"{readStateIndex:1270; appliedIndex:1268; }","duration":"177.924981ms","start":"2026-07-08T21:40:27.744874Z","end":"2026-07-08T21:40:27.922799Z","steps":["trace[329489406] 'read index received' (duration: 177.600073ms)","trace[329489406] 'applied index is now lower than readState.Index' (duration: 323.798µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:40:27.922874Z","caller":"traceutil/trace.go:171","msg":"trace[425029390] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"491.986958ms","start":"2026-07-08T21:40:27.430859Z","end":"2026-07-08T21:40:27.922846Z","steps":["trace[425029390] 'process raft request' (duration: 491.877245ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:40:27.922927Z","caller":"traceutil/trace.go:171","msg":"trace[221129496] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"661.166715ms","start":"2026-07-08T21:40:27.261689Z","end":"2026-07-08T21:40:27.922856Z","steps":["trace[221129496] 'process raft request' (duration: 660.885417ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:40:27.923026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:40:27.430822Z","time spent":"492.119851ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T21:40:27.92303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.879877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T21:40:27.923073Z","caller":"traceutil/trace.go:171","msg":"trace[1198689766] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1225; }","duration":"305.99013ms","start":"2026-07-08T21:40:27.617069Z","end":"2026-07-08T21:40:27.92306Z","steps":["trace[1198689766] 'agreement among raft nodes before linearized reading' (duration: 305.872597ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:40:27.923125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:40:27.61705Z","time spent":"306.064083ms","remote":"127.0.0.1:40062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-07-08T21:40:27.923208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:40:27.261655Z","time spent":"661.412251ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T21:40:27.923416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.444899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T21:40:27.923452Z","caller":"traceutil/trace.go:171","msg":"trace[964217180] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1225; }","duration":"177.510149ms","start":"2026-07-08T21:40:27.745932Z","end":"2026-07-08T21:40:27.923443Z","steps":["trace[964217180] 'agreement among raft nodes before linearized reading' (duration: 177.448198ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:40:30.0345Z","caller":"traceutil/trace.go:171","msg":"trace[934930875] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"105.696101ms","start":"2026-07-08T21:40:29.928787Z","end":"2026-07-08T21:40:30.034483Z","steps":["trace[934930875] 'process raft request' (duration: 105.550528ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:40:46.396923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.83141ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T21:40:46.397055Z","caller":"traceutil/trace.go:171","msg":"trace[753224315] linearizableReadLoop","detail":"{readStateIndex:1337; appliedIndex:1336; }","duration":"504.737871ms","start":"2026-07-08T21:40:45.892296Z","end":"2026-07-08T21:40:46.397034Z","steps":["trace[753224315] 'read index received' (duration: 226.776798ms)","trace[753224315] 'applied index is now lower than readState.Index' (duration: 277.959643ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:40:46.397091Z","caller":"traceutil/trace.go:171","msg":"trace[1224196115] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"523.135045ms","start":"2026-07-08T21:40:45.87393Z","end":"2026-07-08T21:40:46.397065Z","steps":["trace[1224196115] 'process raft request' (duration: 245.055688ms)","trace[1224196115] 'compare' (duration: 277.376388ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:40:46.397215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:40:45.873895Z","time spent":"523.255279ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T21:40:46.397469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.294173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-08T21:40:46.397511Z","caller":"traceutil/trace.go:171","msg":"trace[1858516178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1289; }","duration":"522.346815ms","start":"2026-07-08T21:40:45.87515Z","end":"2026-07-08T21:40:46.397497Z","steps":["trace[1858516178] 'agreement among raft nodes before linearized reading' (duration: 521.965945ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:40:46.397592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:40:45.875136Z","time spent":"522.408436ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-08T21:40:46.404744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:40:45.893169Z","time spent":"511.569294ms","remote":"127.0.0.1:58226","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-08T21:40:47.315183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.440072ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T21:40:47.315413Z","caller":"traceutil/trace.go:171","msg":"trace[1336990296] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"909.71873ms","start":"2026-07-08T21:40:46.405676Z","end":"2026-07-08T21:40:47.315394Z","steps":["trace[1336990296] 'process raft request' (duration: 909.676088ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:40:47.315454Z","caller":"traceutil/trace.go:171","msg":"trace[604297425] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"1.15930839s","start":"2026-07-08T21:40:46.156113Z","end":"2026-07-08T21:40:47.315421Z","steps":["trace[604297425] 'process raft request' (duration: 705.530709ms)","trace[604297425] 'compare' (duration: 453.111773ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:40:47.315616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:40:46.156087Z","time spent":"1.159438534s","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T21:40:47.31571Z","caller":"traceutil/trace.go:171","msg":"trace[1352535963] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"912.503844ms","start":"2026-07-08T21:40:46.403186Z","end":"2026-07-08T21:40:47.31569Z","steps":["trace[1352535963] 'process raft request' (duration: 912.102024ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:40:47.315777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.090734736s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T21:40:47.315786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:40:46.403154Z","time spent":"912.590457ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T21:40:47.31577Z","caller":"traceutil/trace.go:171","msg":"trace[1261764637] linearizableReadLoop","detail":"{readStateIndex:1339; appliedIndex:1337; }","duration":"918.64436ms","start":"2026-07-08T21:40:46.397084Z","end":"2026-07-08T21:40:47.315729Z","steps":["trace[1261764637] 'read index received' (duration: 7.500641ms)","trace[1261764637] 'applied index is now lower than readState.Index' (duration: 911.137478ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:40:47.315825Z","caller":"traceutil/trace.go:171","msg":"trace[831274140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1292; }","duration":"1.090805989s","start":"2026-07-08T21:40:46.225005Z","end":"2026-07-08T21:40:47.315811Z","steps":["trace[831274140] 'agreement among raft nodes before linearized reading' (duration: 1.090720006s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:40:47.315859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:40:46.224995Z","time spent":"1.09085809s","remote":"127.0.0.1:58200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-08T21:40:47.315863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.134879664s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-08T21:40:47.315897Z","caller":"traceutil/trace.go:171","msg":"trace[7930311] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1292; }","duration":"1.134913135s","start":"2026-07-08T21:40:46.180974Z","end":"2026-07-08T21:40:47.315887Z","steps":["trace[7930311] 'agreement among raft nodes before linearized reading' (duration: 1.134775661s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:40:47.315929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:40:46.180924Z","time spent":"1.134999297s","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-08T21:40:47.315971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.277596841s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T21:40:47.316051Z","caller":"traceutil/trace.go:171","msg":"trace[850592815] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1292; }","duration":"1.277765586s","start":"2026-07-08T21:40:46.038266Z","end":"2026-07-08T21:40:47.316032Z","steps":["trace[850592815] 'agreement among raft nodes before linearized reading' (duration: 1.277585051s)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:40:47.316132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:40:46.038236Z","time spent":"1.277885019s","remote":"127.0.0.1:58622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-07-08T21:40:47.316361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:40:46.405654Z","time spent":"909.798812ms","remote":"127.0.0.1:58226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T21:40:48.014665Z","caller":"traceutil/trace.go:171","msg":"trace[147500574] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"115.160637ms","start":"2026-07-08T21:40:47.899482Z","end":"2026-07-08T21:40:48.014642Z","steps":["trace[147500574] 'process raft request' (duration: 71.290827ms)","trace[147500574] 'compare' (duration: 43.787897ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:40:48.79262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.884199ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T21:40:48.792759Z","caller":"traceutil/trace.go:171","msg":"trace[1836130336] linearizableReadLoop","detail":"{readStateIndex:1345; appliedIndex:1344; }","duration":"391.25119ms","start":"2026-07-08T21:40:48.401486Z","end":"2026-07-08T21:40:48.792737Z","steps":["trace[1836130336] 'read index received' (duration: 16.091883ms)","trace[1836130336] 'applied index is now lower than readState.Index' (duration: 375.157567ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:40:48.793033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.542407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-08T21:40:48.793062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.24911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T21:40:48.793016Z","caller":"traceutil/trace.go:171","msg":"trace[1854656169] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"590.290772ms","start":"2026-07-08T21:40:48.202682Z","end":"2026-07-08T21:40:48.792973Z","steps":["trace[1854656169] 'process raft request' (duration: 214.9458ms)","trace[1854656169] 'compare' (duration: 374.288734ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:40:48.793087Z","caller":"traceutil/trace.go:171","msg":"trace[1513338181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1296; }","duration":"391.62687ms","start":"2026-07-08T21:40:48.401445Z","end":"2026-07-08T21:40:48.793072Z","steps":["trace[1513338181] 'agreement among raft nodes before linearized reading' (duration: 391.452715ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:40:48.793107Z","caller":"traceutil/trace.go:171","msg":"trace[1984077660] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1296; }","duration":"211.334982ms","start":"2026-07-08T21:40:48.581759Z","end":"2026-07-08T21:40:48.793094Z","steps":["trace[1984077660] 'agreement among raft nodes before linearized reading' (duration: 211.25428ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:40:48.793148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:40:48.401427Z","time spent":"391.708772ms","remote":"127.0.0.1:58474","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-07-08T21:40:48.793266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:40:48.202657Z","time spent":"590.479236ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T21:40:49.463834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.829094ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T21:40:49.464045Z","caller":"traceutil/trace.go:171","msg":"trace[596798032] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"141.219107ms","start":"2026-07-08T21:40:49.322807Z","end":"2026-07-08T21:40:49.464026Z","steps":["trace[596798032] 'process raft request' (duration: 141.143155ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:40:49.464137Z","caller":"traceutil/trace.go:171","msg":"trace[896750077] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"666.696916ms","start":"2026-07-08T21:40:48.797418Z","end":"2026-07-08T21:40:49.464115Z","steps":["trace[896750077] 'process raft request' (duration: 521.512653ms)","trace[896750077] 'compare' (duration: 144.665869ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:40:49.464266Z","caller":"traceutil/trace.go:171","msg":"trace[703404661] linearizableReadLoop","detail":"{readStateIndex:1346; appliedIndex:1345; }","duration":"570.592032ms","start":"2026-07-08T21:40:48.893663Z","end":"2026-07-08T21:40:49.464255Z","steps":["trace[703404661] 'read index received' (duration: 425.119791ms)","trace[703404661] 'applied index is now lower than readState.Index' (duration: 145.471221ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:40:49.464331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:40:48.797396Z","time spent":"666.87521ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T21:40:49.464371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.699925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T21:40:49.464401Z","caller":"traceutil/trace.go:171","msg":"trace[173787324] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1298; }","duration":"570.734596ms","start":"2026-07-08T21:40:48.893655Z","end":"2026-07-08T21:40:49.46439Z","steps":["trace[173787324] 'agreement among raft nodes before linearized reading' (duration: 570.675494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:40:49.464432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:40:48.893591Z","time spent":"570.831019ms","remote":"127.0.0.1:39978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-08T21:40:49.464581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.556604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T21:40:49.464651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.499365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-08T21:40:49.464649Z","caller":"traceutil/trace.go:171","msg":"trace[65468910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1298; }","duration":"238.663787ms","start":"2026-07-08T21:40:49.225968Z","end":"2026-07-08T21:40:49.464631Z","steps":["trace[65468910] 'agreement among raft nodes before linearized reading' (duration: 238.561104ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:40:49.46468Z","caller":"traceutil/trace.go:171","msg":"trace[922072998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1298; }","duration":"144.630039ms","start":"2026-07-08T21:40:49.320041Z","end":"2026-07-08T21:40:49.464671Z","steps":["trace[922072998] 'agreement among raft nodes before linearized reading' (duration: 144.524246ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:40:49.464714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.695978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-08T21:40:49.464786Z","caller":"traceutil/trace.go:171","msg":"trace[517663216] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1298; }","duration":"119.820872ms","start":"2026-07-08T21:40:49.344948Z","end":"2026-07-08T21:40:49.464769Z","steps":["trace[517663216] 'agreement among raft nodes before linearized reading' (duration: 119.604846ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:40:50.016975Z","caller":"traceutil/trace.go:171","msg":"trace[2116515317] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"119.974026ms","start":"2026-07-08T21:40:49.896982Z","end":"2026-07-08T21:40:50.016956Z","steps":["trace[2116515317] 'process raft request' (duration: 119.856522ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:40:51.669318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.720746ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-08T21:40:51.669656Z","caller":"traceutil/trace.go:171","msg":"trace[573896434] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"199.417002ms","start":"2026-07-08T21:40:51.470175Z","end":"2026-07-08T21:40:51.669592Z","steps":["trace[573896434] 'process raft request' (duration: 199.257847ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:40:51.66965Z","caller":"traceutil/trace.go:171","msg":"trace[1549181461] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1351; }","duration":"444.632854ms","start":"2026-07-08T21:40:51.224933Z","end":"2026-07-08T21:40:51.669565Z","steps":["trace[1549181461] 'read index received' (duration: 249.674802ms)","trace[1549181461] 'applied index is now lower than readState.Index' (duration: 194.955482ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:40:51.669839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.893481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T21:40:51.669929Z","caller":"traceutil/trace.go:171","msg":"trace[1919714822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1303; }","duration":"445.024834ms","start":"2026-07-08T21:40:51.224887Z","end":"2026-07-08T21:40:51.669912Z","steps":["trace[1919714822] 'agreement among raft nodes before linearized reading' (duration: 444.815259ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:40:51.669981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:40:51.224867Z","time spent":"445.099437ms","remote":"127.0.0.1:58202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-08T21:40:51.695247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.447216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T21:40:51.695335Z","caller":"traceutil/trace.go:171","msg":"trace[1394274096] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1304; }","duration":"355.60407ms","start":"2026-07-08T21:40:51.339714Z","end":"2026-07-08T21:40:51.695318Z","steps":["trace[1394274096] 'agreement among raft nodes before linearized reading' (duration: 355.442256ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:40:51.695369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:40:51.339691Z","time spent":"355.672002ms","remote":"127.0.0.1:40004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"info","ts":"2026-07-08T21:40:51.695442Z","caller":"traceutil/trace.go:171","msg":"trace[1004979399] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"139.251994ms","start":"2026-07-08T21:40:51.556162Z","end":"2026-07-08T21:40:51.695414Z","steps":["trace[1004979399] 'process raft request' (duration: 138.851353ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:40:51.695598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.52291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T21:40:51.695651Z","caller":"traceutil/trace.go:171","msg":"trace[785605110] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1304; }","duration":"133.628513ms","start":"2026-07-08T21:40:51.562011Z","end":"2026-07-08T21:40:51.695639Z","steps":["trace[785605110] 'agreement among raft nodes before linearized reading' (duration: 133.53077ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:40:51.695703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.615942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-08T21:40:51.695729Z","caller":"traceutil/trace.go:171","msg":"trace[386670875] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1304; }","duration":"156.665162ms","start":"2026-07-08T21:40:51.539057Z","end":"2026-07-08T21:40:51.695722Z","steps":["trace[386670875] 'agreement among raft nodes before linearized reading' (duration: 156.559429ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:40:51.695778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.576446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-08T21:40:51.695846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.641252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T21:40:51.695851Z","caller":"traceutil/trace.go:171","msg":"trace[1838579577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1304; }","duration":"138.680949ms","start":"2026-07-08T21:40:51.557142Z","end":"2026-07-08T21:40:51.695823Z","steps":["trace[1838579577] 'agreement among raft nodes before linearized reading' (duration: 138.216536ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:40:51.69589Z","caller":"traceutil/trace.go:171","msg":"trace[2059520015] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1304; }","duration":"291.725564ms","start":"2026-07-08T21:40:51.404149Z","end":"2026-07-08T21:40:51.695875Z","steps":["trace[2059520015] 'agreement among raft nodes before linearized reading' (duration: 291.655142ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:40:51.696028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.566452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-08T21:40:51.696095Z","caller":"traceutil/trace.go:171","msg":"trace[2091915053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1304; }","duration":"226.638084ms","start":"2026-07-08T21:40:51.469414Z","end":"2026-07-08T21:40:51.696052Z","steps":["trace[2091915053] 'agreement among raft nodes before linearized reading' (duration: 226.512651ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:40:51.939906Z","caller":"traceutil/trace.go:171","msg":"trace[91457638] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"239.301255ms","start":"2026-07-08T21:40:51.700575Z","end":"2026-07-08T21:40:51.939876Z","steps":["trace[91457638] 'process raft request' (duration: 239.251604ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:40:51.939969Z","caller":"traceutil/trace.go:171","msg":"trace[1655878453] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"239.420478ms","start":"2026-07-08T21:40:51.70052Z","end":"2026-07-08T21:40:51.939941Z","steps":["trace[1655878453] 'process raft request' (duration: 239.181801ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:40:58.247019Z","caller":"traceutil/trace.go:171","msg":"trace[1725707874] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"115.475324ms","start":"2026-07-08T21:40:58.13152Z","end":"2026-07-08T21:40:58.246995Z","steps":["trace[1725707874] 'process raft request' (duration: 61.792141ms)","trace[1725707874] 'compare' (duration: 53.365245ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:41:02.357644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.660864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T21:41:02.357717Z","caller":"traceutil/trace.go:171","msg":"trace[1789332252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1358; }","duration":"133.764117ms","start":"2026-07-08T21:41:02.223937Z","end":"2026-07-08T21:41:02.357701Z","steps":["trace[1789332252] 'range keys from in-memory index tree' (duration: 133.565442ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:41:02.357815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.288052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-08T21:41:02.358063Z","caller":"traceutil/trace.go:171","msg":"trace[1885066259] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1358; }","duration":"329.58589ms","start":"2026-07-08T21:41:02.028456Z","end":"2026-07-08T21:41:02.358042Z","steps":["trace[1885066259] 'range keys from in-memory index tree' (duration: 329.081917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:41:02.358109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:41:02.028438Z","time spent":"329.657233ms","remote":"127.0.0.1:58474","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":"warn","ts":"2026-07-08T21:41:02.358261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.815227ms","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-07-08T21:41:02.358294Z","caller":"traceutil/trace.go:171","msg":"trace[1343672739] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1358; }","duration":"143.882269ms","start":"2026-07-08T21:41:02.2144Z","end":"2026-07-08T21:41:02.358282Z","steps":["trace[1343672739] 'range keys from in-memory index tree' (duration: 143.681243ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:41:02.774487Z","caller":"traceutil/trace.go:171","msg":"trace[189172846] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"411.773771ms","start":"2026-07-08T21:41:02.362689Z","end":"2026-07-08T21:41:02.774463Z","steps":["trace[189172846] 'process raft request' (duration: 411.634668ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:41:02.774756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:41:02.362681Z","time spent":"411.987637ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T21:41:02.776222Z","caller":"traceutil/trace.go:171","msg":"trace[776691007] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"412.718216ms","start":"2026-07-08T21:41:02.363481Z","end":"2026-07-08T21:41:02.776199Z","steps":["trace[776691007] 'process raft request' (duration: 410.942328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:41:02.776455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:41:02.363453Z","time spent":"412.961273ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T21:41:02.778133Z","caller":"traceutil/trace.go:171","msg":"trace[935381712] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"413.268982ms","start":"2026-07-08T21:41:02.362605Z","end":"2026-07-08T21:41:02.775874Z","steps":["trace[935381712] 'process raft request' (duration: 410.2523ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:41:02.781367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:41:02.362585Z","time spent":"417.676069ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T21:41:04.177931Z","caller":"traceutil/trace.go:171","msg":"trace[1007745401] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"167.399181ms","start":"2026-07-08T21:41:04.010508Z","end":"2026-07-08T21:41:04.177907Z","steps":["trace[1007745401] 'process raft request' (duration: 167.264758ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:41:09.83074Z","caller":"traceutil/trace.go:171","msg":"trace[809787848] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"104.033957ms","start":"2026-07-08T21:41:09.726678Z","end":"2026-07-08T21:41:09.830712Z","steps":["trace[809787848] 'process raft request' (duration: 103.822972ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:42:29.368124Z","caller":"traceutil/trace.go:171","msg":"trace[1817063412] transaction","detail":"{read_only:false; response_revision:1783; number_of_response:1; }","duration":"134.811756ms","start":"2026-07-08T21:42:29.233284Z","end":"2026-07-08T21:42:29.368096Z","steps":["trace[1817063412] 'process raft request' (duration: 134.624821ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:42:29.401093Z","caller":"traceutil/trace.go:171","msg":"trace[1092718938] transaction","detail":"{read_only:false; response_revision:1785; number_of_response:1; }","duration":"164.258228ms","start":"2026-07-08T21:42:29.236809Z","end":"2026-07-08T21:42:29.401067Z","steps":["trace[1092718938] 'process raft request' (duration: 164.185586ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:42:29.401201Z","caller":"traceutil/trace.go:171","msg":"trace[2137517705] transaction","detail":"{read_only:false; response_revision:1784; number_of_response:1; }","duration":"167.120965ms","start":"2026-07-08T21:42:29.23403Z","end":"2026-07-08T21:42:29.401151Z","steps":["trace[2137517705] 'process raft request' (duration: 166.800626ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:42:30.439747Z","caller":"traceutil/trace.go:171","msg":"trace[1448634683] transaction","detail":"{read_only:false; response_revision:1788; number_of_response:1; }","duration":"132.33957ms","start":"2026-07-08T21:42:30.307371Z","end":"2026-07-08T21:42:30.439711Z","steps":["trace[1448634683] 'process raft request' (duration: 132.160345ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:45:19.353569Z","caller":"traceutil/trace.go:171","msg":"trace[1164218661] transaction","detail":"{read_only:false; response_revision:2598; number_of_response:1; }","duration":"112.417457ms","start":"2026-07-08T21:45:19.241135Z","end":"2026-07-08T21:45:19.353552Z","steps":["trace[1164218661] 'process raft request' (duration: 112.311744ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:45:20.339385Z","caller":"traceutil/trace.go:171","msg":"trace[1081567032] transaction","detail":"{read_only:false; response_revision:2603; number_of_response:1; }","duration":"114.218166ms","start":"2026-07-08T21:45:20.225141Z","end":"2026-07-08T21:45:20.339359Z","steps":["trace[1081567032] 'process raft request' (duration: 52.550895ms)","trace[1081567032] 'compare' (duration: 61.501236ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:45:20.339639Z","caller":"traceutil/trace.go:171","msg":"trace[759994604] transaction","detail":"{read_only:false; response_revision:2604; number_of_response:1; }","duration":"113.034934ms","start":"2026-07-08T21:45:20.226581Z","end":"2026-07-08T21:45:20.339615Z","steps":["trace[759994604] 'process raft request' (duration: 112.738346ms)"],"step_count":1}