{"level":"warn","ts":"2026-03-03T17:46:08.522394Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-03T17:46:08.522486Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.207:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.207:2380","--initial-cluster=instance=https://162.253.55.207:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.207:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.207:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-03T17:46:08.522561Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-03T17:46:08.522578Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.207:2380"]} {"level":"info","ts":"2026-03-03T17:46:08.522599Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-03T17:46:08.523612Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"]} {"level":"info","ts":"2026-03-03T17:46:08.52371Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.207:2380"],"listen-peer-urls":["https://162.253.55.207:2380"],"advertise-client-urls":["https://162.253.55.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.207:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-03T17:46:08.528176Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.215014ms"} {"level":"info","ts":"2026-03-03T17:46:08.534515Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6f1fcbe50562a63a","cluster-id":"a9b9f04f4e8a9fba"} {"level":"info","ts":"2026-03-03T17:46:08.534577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=()"} {"level":"info","ts":"2026-03-03T17:46:08.534597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became follower at term 0"} {"level":"info","ts":"2026-03-03T17:46:08.534616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6f1fcbe50562a63a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-03T17:46:08.534629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became follower at term 1"} {"level":"info","ts":"2026-03-03T17:46:08.534662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=(8007342846986331706)"} {"level":"warn","ts":"2026-03-03T17:46:08.540943Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-03T17:46:08.541981Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-03T17:46:08.542984Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-03T17:46:08.545113Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6f1fcbe50562a63a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-03T17:46:08.545487Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6f1fcbe50562a63a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-03T17:46:08.545616Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-03T17:46:08.545697Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-03T17:46:08.545742Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-03T17:46:08.546404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=(8007342846986331706)"} {"level":"info","ts":"2026-03-03T17:46:08.55167Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a9b9f04f4e8a9fba","local-member-id":"6f1fcbe50562a63a","added-peer-id":"6f1fcbe50562a63a","added-peer-peer-urls":["https://162.253.55.207:2380"]} {"level":"info","ts":"2026-03-03T17:46:08.556411Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-03T17:46:08.556527Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.207:2380"} {"level":"info","ts":"2026-03-03T17:46:08.556611Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.207:2380"} {"level":"info","ts":"2026-03-03T17:46:08.55676Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6f1fcbe50562a63a","initial-advertise-peer-urls":["https://162.253.55.207:2380"],"listen-peer-urls":["https://162.253.55.207:2380"],"advertise-client-urls":["https://162.253.55.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-03T17:46:08.556807Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-03T17:46:08.935385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a is starting a new election at term 1"} {"level":"info","ts":"2026-03-03T17:46:08.935456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became pre-candidate at term 1"} {"level":"info","ts":"2026-03-03T17:46:08.935478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a received MsgPreVoteResp from 6f1fcbe50562a63a at term 1"} {"level":"info","ts":"2026-03-03T17:46:08.935499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became candidate at term 2"} {"level":"info","ts":"2026-03-03T17:46:08.935521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a received MsgVoteResp from 6f1fcbe50562a63a at term 2"} {"level":"info","ts":"2026-03-03T17:46:08.935547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became leader at term 2"} {"level":"info","ts":"2026-03-03T17:46:08.935566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6f1fcbe50562a63a elected leader 6f1fcbe50562a63a at term 2"} {"level":"info","ts":"2026-03-03T17:46:08.936782Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T17:46:08.937593Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6f1fcbe50562a63a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.207:2379]}","request-path":"/0/members/6f1fcbe50562a63a/attributes","cluster-id":"a9b9f04f4e8a9fba","publish-timeout":"7s"} {"level":"info","ts":"2026-03-03T17:46:08.937604Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-03T17:46:08.93765Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-03T17:46:08.938176Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-03T17:46:08.938223Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-03T17:46:08.939982Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-03T17:46:08.941024Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.207:2379"} {"level":"info","ts":"2026-03-03T17:46:08.94543Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a9b9f04f4e8a9fba","local-member-id":"6f1fcbe50562a63a","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T17:46:08.955792Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T17:46:08.955853Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T17:46:16.721323Z","caller":"traceutil/trace.go:171","msg":"trace[1385145732] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"363.874655ms","start":"2026-03-03T17:46:16.357426Z","end":"2026-03-03T17:46:16.721301Z","steps":["trace[1385145732] 'process raft request' (duration: 363.724231ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:46:16.721854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:46:16.357404Z","time spent":"364.006799ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":199,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-03T17:46:17.069287Z","caller":"traceutil/trace.go:171","msg":"trace[548455278] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"337.17057ms","start":"2026-03-03T17:46:16.732091Z","end":"2026-03-03T17:46:17.069261Z","steps":["trace[548455278] 'process raft request' (duration: 334.611797ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:46:17.069427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:46:16.732078Z","time spent":"337.290803ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":203,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T17:46:18.089226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.223259ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-03T17:46:18.089357Z","caller":"traceutil/trace.go:171","msg":"trace[1387026631] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"1.010424603s","start":"2026-03-03T17:46:17.078914Z","end":"2026-03-03T17:46:18.089339Z","steps":["trace[1387026631] 'process raft request' (duration: 478.790153ms)","trace[1387026631] 'compare' (duration: 531.010964ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T17:46:18.089406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:46:17.078889Z","time spent":"1.010501554s","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":201,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T17:46:18.648256Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.090455616s","expected-duration":"1s"} {"level":"info","ts":"2026-03-03T17:46:18.64871Z","caller":"traceutil/trace.go:171","msg":"trace[1320277315] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"1.567386033s","start":"2026-03-03T17:46:17.081308Z","end":"2026-03-03T17:46:18.648694Z","steps":["trace[1320277315] 'process raft request' (duration: 1.567186729s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:46:18.649223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:46:17.081289Z","time spent":"1.567469235s","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T17:46:18.649163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.498026307s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-03T17:46:18.649476Z","caller":"traceutil/trace.go:171","msg":"trace[1224350523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:295; }","duration":"1.498380206s","start":"2026-03-03T17:46:17.151069Z","end":"2026-03-03T17:46:18.64945Z","steps":["trace[1224350523] 'agreement among raft nodes before linearized reading' (duration: 1.497918445s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:46:18.649541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:46:17.151001Z","time spent":"1.49852256s","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-03T17:46:18.649577Z","caller":"traceutil/trace.go:171","msg":"trace[250510914] linearizableReadLoop","detail":"{readStateIndex:308; appliedIndex:306; }","duration":"1.497554316s","start":"2026-03-03T17:46:17.151089Z","end":"2026-03-03T17:46:18.648643Z","steps":["trace[250510914] 'read index received' (duration: 406.704551ms)","trace[250510914] 'applied index is now lower than readState.Index' (duration: 1.090848865s)"],"step_count":2} {"level":"warn","ts":"2026-03-03T17:46:18.650292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.828045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-03-03T17:46:18.650337Z","caller":"traceutil/trace.go:171","msg":"trace[742670369] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:295; }","duration":"556.922198ms","start":"2026-03-03T17:46:18.093401Z","end":"2026-03-03T17:46:18.650323Z","steps":["trace[742670369] 'agreement among raft nodes before linearized reading' (duration: 556.835196ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:46:18.650332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.042266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-03-03T17:46:18.650375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:46:18.093373Z","time spent":"556.99406ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":239,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"info","ts":"2026-03-03T17:46:18.650391Z","caller":"traceutil/trace.go:171","msg":"trace[1901124706] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:295; }","duration":"472.146379ms","start":"2026-03-03T17:46:18.178227Z","end":"2026-03-03T17:46:18.650374Z","steps":["trace[1901124706] 'agreement among raft nodes before linearized reading' (duration: 472.041246ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:46:18.650434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:46:18.178198Z","time spent":"472.224391ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-03T17:46:18.650552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.144442ms","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-03-03T17:46:18.650584Z","caller":"traceutil/trace.go:171","msg":"trace[1086020931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:295; }","duration":"542.187963ms","start":"2026-03-03T17:46:18.108388Z","end":"2026-03-03T17:46:18.650575Z","steps":["trace[1086020931] 'agreement among raft nodes before linearized reading' (duration: 542.133701ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:46:18.650611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:46:18.108378Z","time spent":"542.227413ms","remote":"127.0.0.1:58246","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":"info","ts":"2026-03-03T17:46:40.617226Z","caller":"traceutil/trace.go:171","msg":"trace[749224896] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"575.108171ms","start":"2026-03-03T17:46:40.042067Z","end":"2026-03-03T17:46:40.617175Z","steps":["trace[749224896] 'process raft request' (duration: 574.951537ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:46:40.617523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:46:40.042055Z","time spent":"575.372277ms","remote":"127.0.0.1:58154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4873,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T17:46:40.622876Z","caller":"traceutil/trace.go:171","msg":"trace[696810034] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"271.383944ms","start":"2026-03-03T17:46:40.351407Z","end":"2026-03-03T17:46:40.622791Z","steps":["trace[696810034] 'process raft request' (duration: 270.850971ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:46:40.624666Z","caller":"traceutil/trace.go:171","msg":"trace[1752235077] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"473.272477ms","start":"2026-03-03T17:46:40.149324Z","end":"2026-03-03T17:46:40.622596Z","steps":["trace[1752235077] 'process raft request' (duration: 472.882877ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:46:40.624841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:46:40.1493Z","time spent":"475.471052ms","remote":"127.0.0.1:58034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-03T17:46:47.106565Z","caller":"traceutil/trace.go:171","msg":"trace[1410574420] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"319.998733ms","start":"2026-03-03T17:46:46.786541Z","end":"2026-03-03T17:46:47.10654Z","steps":["trace[1410574420] 'process raft request' (duration: 319.797118ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:46:47.106757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:46:46.786525Z","time spent":"320.134926ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T17:46:47.118013Z","caller":"traceutil/trace.go:171","msg":"trace[828934870] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"329.702694ms","start":"2026-03-03T17:46:46.788286Z","end":"2026-03-03T17:46:47.117989Z","steps":["trace[828934870] 'process raft request' (duration: 329.565301ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:46:47.118144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:46:46.788268Z","time spent":"329.806767ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T17:46:47.118247Z","caller":"traceutil/trace.go:171","msg":"trace[1470164322] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"329.09187ms","start":"2026-03-03T17:46:46.789137Z","end":"2026-03-03T17:46:47.118229Z","steps":["trace[1470164322] 'process raft request' (duration: 328.988007ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:46:47.118263Z","caller":"traceutil/trace.go:171","msg":"trace[462128110] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"329.90652ms","start":"2026-03-03T17:46:46.788328Z","end":"2026-03-03T17:46:47.118235Z","steps":["trace[462128110] 'process raft request' (duration: 329.630572ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:46:47.118392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:46:46.789083Z","time spent":"329.228113ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T17:46:47.118451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:46:46.788309Z","time spent":"330.024122ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T17:46:47.17463Z","caller":"traceutil/trace.go:171","msg":"trace[2001629372] linearizableReadLoop","detail":"{readStateIndex:562; appliedIndex:558; }","duration":"116.890849ms","start":"2026-03-03T17:46:47.05771Z","end":"2026-03-03T17:46:47.174601Z","steps":["trace[2001629372] 'read index received' (duration: 48.61123ms)","trace[2001629372] 'applied index is now lower than readState.Index' (duration: 68.277919ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T17:46:47.17487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.140925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8r74x\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-03T17:46:47.174913Z","caller":"traceutil/trace.go:171","msg":"trace[1568583972] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8r74x; range_end:; response_count:1; response_revision:542; }","duration":"117.250218ms","start":"2026-03-03T17:46:47.057652Z","end":"2026-03-03T17:46:47.174902Z","steps":["trace[1568583972] 'agreement among raft nodes before linearized reading' (duration: 117.055363ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:46:47.390285Z","caller":"traceutil/trace.go:171","msg":"trace[1003488765] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"199.568597ms","start":"2026-03-03T17:46:47.190241Z","end":"2026-03-03T17:46:47.38981Z","steps":["trace[1003488765] 'process raft request' (duration: 199.411033ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:46:49.621986Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058459320490691,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T17:46:50.122968Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058459320490691,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T17:46:50.160907Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.04608877s","expected-duration":"1s"} {"level":"info","ts":"2026-03-03T17:46:50.162455Z","caller":"traceutil/trace.go:171","msg":"trace[70881440] linearizableReadLoop","detail":"{readStateIndex:566; appliedIndex:565; }","duration":"1.041428074s","start":"2026-03-03T17:46:49.121Z","end":"2026-03-03T17:46:50.162428Z","steps":["trace[70881440] 'read index received' (duration: 1.04004765s)","trace[70881440] 'applied index is now lower than readState.Index' (duration: 1.379134ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T17:46:50.162755Z","caller":"traceutil/trace.go:171","msg":"trace[455053881] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"1.048049688s","start":"2026-03-03T17:46:49.114684Z","end":"2026-03-03T17:46:50.162733Z","steps":["trace[455053881] 'process raft request' (duration: 1.046378487s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:46:50.162858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.63893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T17:46:50.162878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040850669s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-03T17:46:50.162867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041855184s","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-03-03T17:46:50.162903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:46:49.114667Z","time spent":"1.04812931s","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T17:46:50.162925Z","caller":"traceutil/trace.go:171","msg":"trace[1196219368] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:545; }","duration":"810.774554ms","start":"2026-03-03T17:46:49.352133Z","end":"2026-03-03T17:46:50.162907Z","steps":["trace[1196219368] 'agreement among raft nodes before linearized reading' (duration: 810.63306ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:46:50.162946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.750572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T17:46:50.162951Z","caller":"traceutil/trace.go:171","msg":"trace[172421526] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:545; }","duration":"1.041987537s","start":"2026-03-03T17:46:49.120947Z","end":"2026-03-03T17:46:50.162934Z","steps":["trace[172421526] 'agreement among raft nodes before linearized reading' (duration: 1.04167146s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:46:50.162973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.253266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T17:46:50.162975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:46:49.352108Z","time spent":"810.852036ms","remote":"127.0.0.1:55102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-03-03T17:46:50.16299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:46:49.120927Z","time spent":"1.042054059s","remote":"127.0.0.1:58246","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":"info","ts":"2026-03-03T17:46:50.163003Z","caller":"traceutil/trace.go:171","msg":"trace[158179578] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:545; }","duration":"811.279707ms","start":"2026-03-03T17:46:49.351712Z","end":"2026-03-03T17:46:50.162992Z","steps":["trace[158179578] 'agreement among raft nodes before linearized reading' (duration: 811.237386ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:46:50.163015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.546459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T17:46:50.163009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.923471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T17:46:50.163029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:46:49.351688Z","time spent":"811.333877ms","remote":"127.0.0.1:55102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-03-03T17:46:50.163061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.312661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T17:46:50.163052Z","caller":"traceutil/trace.go:171","msg":"trace[532423540] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:545; }","duration":"827.991733ms","start":"2026-03-03T17:46:49.335048Z","end":"2026-03-03T17:46:50.16304Z","steps":["trace[532423540] 'agreement among raft nodes before linearized reading' (duration: 827.923581ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:46:50.163078Z","caller":"traceutil/trace.go:171","msg":"trace[1554411820] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:545; }","duration":"779.330842ms","start":"2026-03-03T17:46:49.383742Z","end":"2026-03-03T17:46:50.163073Z","steps":["trace[1554411820] 'agreement among raft nodes before linearized reading' (duration: 779.303651ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:46:50.163033Z","caller":"traceutil/trace.go:171","msg":"trace[1492736129] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:545; }","duration":"507.56396ms","start":"2026-03-03T17:46:49.655464Z","end":"2026-03-03T17:46:50.163028Z","steps":["trace[1492736129] 'agreement among raft nodes before linearized reading' (duration: 507.531289ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:46:50.1631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.778557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T17:46:50.163099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:46:49.335Z","time spent":"828.086265ms","remote":"127.0.0.1:55094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 "} {"level":"warn","ts":"2026-03-03T17:46:50.16311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:46:49.655409Z","time spent":"507.694803ms","remote":"127.0.0.1:57954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-03T17:46:50.163126Z","caller":"traceutil/trace.go:171","msg":"trace[576676476] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:545; }","duration":"827.810928ms","start":"2026-03-03T17:46:49.335305Z","end":"2026-03-03T17:46:50.163116Z","steps":["trace[576676476] 'agreement among raft nodes before linearized reading' (duration: 827.775497ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:46:50.16313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.748123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T17:46:50.16315Z","caller":"traceutil/trace.go:171","msg":"trace[462050946] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:545; }","duration":"779.792564ms","start":"2026-03-03T17:46:49.383352Z","end":"2026-03-03T17:46:50.163145Z","steps":["trace[462050946] 'agreement among raft nodes before linearized reading' (duration: 779.762333ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:46:50.163151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:46:49.3353Z","time spent":"827.844549ms","remote":"127.0.0.1:55094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 "} {"level":"warn","ts":"2026-03-03T17:46:50.163166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:46:49.383336Z","time spent":"779.826095ms","remote":"127.0.0.1:55132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-03T17:46:50.163101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:46:49.383729Z","time spent":"779.366283ms","remote":"127.0.0.1:55132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"info","ts":"2026-03-03T17:46:50.16298Z","caller":"traceutil/trace.go:171","msg":"trace[533575385] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:545; }","duration":"779.822424ms","start":"2026-03-03T17:46:49.383149Z","end":"2026-03-03T17:46:50.162971Z","steps":["trace[533575385] 'agreement among raft nodes before linearized reading' (duration: 779.752943ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:46:50.163262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.26565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T17:46:50.163225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.170177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T17:46:50.163289Z","caller":"traceutil/trace.go:171","msg":"trace[1218518874] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:545; }","duration":"791.26328ms","start":"2026-03-03T17:46:49.372018Z","end":"2026-03-03T17:46:50.163281Z","steps":["trace[1218518874] 'agreement among raft nodes before linearized reading' (duration: 791.177738ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:46:50.163299Z","caller":"traceutil/trace.go:171","msg":"trace[1996466538] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:545; }","duration":"791.319971ms","start":"2026-03-03T17:46:49.37196Z","end":"2026-03-03T17:46:50.16328Z","steps":["trace[1996466538] 'agreement among raft nodes before linearized reading' (duration: 791.28311ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:46:50.162933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.895347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T17:46:50.162942Z","caller":"traceutil/trace.go:171","msg":"trace[1969418008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:545; }","duration":"1.040964822s","start":"2026-03-03T17:46:49.12196Z","end":"2026-03-03T17:46:50.162924Z","steps":["trace[1969418008] 'agreement among raft nodes before linearized reading' (duration: 1.040707056s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:46:50.163353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:46:49.121951Z","time spent":"1.041396403s","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-03T17:46:50.163361Z","caller":"traceutil/trace.go:171","msg":"trace[413820597] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:545; }","duration":"811.348429ms","start":"2026-03-03T17:46:49.351994Z","end":"2026-03-03T17:46:50.163343Z","steps":["trace[413820597] 'agreement among raft nodes before linearized reading' (duration: 810.904927ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:46:50.163313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:46:49.371995Z","time spent":"791.309431ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-03-03T17:46:50.163395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:46:49.351984Z","time spent":"811.40109ms","remote":"127.0.0.1:55102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-03-03T17:46:50.163349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.04127647s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-03T17:46:50.163433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.788061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-03T17:46:50.16346Z","caller":"traceutil/trace.go:171","msg":"trace[2088753442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:545; }","duration":"1.041430984s","start":"2026-03-03T17:46:49.122021Z","end":"2026-03-03T17:46:50.163452Z","steps":["trace[2088753442] 'agreement among raft nodes before linearized reading' (duration: 1.04126039s)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:46:50.163466Z","caller":"traceutil/trace.go:171","msg":"trace[330267646] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:545; }","duration":"932.853643ms","start":"2026-03-03T17:46:49.230601Z","end":"2026-03-03T17:46:50.163454Z","steps":["trace[330267646] 'agreement among raft nodes before linearized reading' (duration: 932.779291ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:46:50.163487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:46:49.122Z","time spent":"1.041479176s","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-03T17:46:50.163495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:46:49.230583Z","time spent":"932.905854ms","remote":"127.0.0.1:58246","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-03-03T17:46:50.163523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.564578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T17:46:50.163547Z","caller":"traceutil/trace.go:171","msg":"trace[1958188668] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:545; }","duration":"791.613158ms","start":"2026-03-03T17:46:49.371927Z","end":"2026-03-03T17:46:50.163541Z","steps":["trace[1958188668] 'agreement among raft nodes before linearized reading' (duration: 791.574427ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:46:50.163325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:46:49.371947Z","time spent":"791.371323ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-03-03T17:46:50.162858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.696816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T17:46:50.163566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:46:49.371911Z","time spent":"791.64958ms","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-03-03T17:46:50.163262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:46:49.383134Z","time spent":"780.113222ms","remote":"127.0.0.1:55132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"info","ts":"2026-03-03T17:46:50.163582Z","caller":"traceutil/trace.go:171","msg":"trace[223520195] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:545; }","duration":"828.443235ms","start":"2026-03-03T17:46:49.335128Z","end":"2026-03-03T17:46:50.163572Z","steps":["trace[223520195] 'agreement among raft nodes before linearized reading' (duration: 827.662395ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:46:50.163617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:46:49.335069Z","time spent":"828.537137ms","remote":"127.0.0.1:55094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-03-03T17:46:58.961115Z","caller":"traceutil/trace.go:171","msg":"trace[1168577140] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"103.734851ms","start":"2026-03-03T17:46:58.857356Z","end":"2026-03-03T17:46:58.961091Z","steps":["trace[1168577140] 'process raft request' (duration: 103.599048ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:08.155457Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058459320490869,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T17:47:08.175098Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.443772899s","expected-duration":"1s"} {"level":"info","ts":"2026-03-03T17:47:08.17552Z","caller":"traceutil/trace.go:171","msg":"trace[655236200] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"1.444345023s","start":"2026-03-03T17:47:06.731146Z","end":"2026-03-03T17:47:08.175491Z","steps":["trace[655236200] 'process raft request' (duration: 1.444162229s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:08.175694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:47:06.731118Z","time spent":"1.444490347s","remote":"127.0.0.1:58154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T17:47:09.071262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.084454ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-03T17:47:09.071484Z","caller":"traceutil/trace.go:171","msg":"trace[1086018826] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:654; }","duration":"1.416573821s","start":"2026-03-03T17:47:07.654883Z","end":"2026-03-03T17:47:09.071457Z","steps":["trace[1086018826] 'read index received' (duration: 520.402378ms)","trace[1086018826] 'applied index is now lower than readState.Index' (duration: 896.168443ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T17:47:09.071588Z","caller":"traceutil/trace.go:171","msg":"trace[914145005] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"882.084377ms","start":"2026-03-03T17:47:08.189488Z","end":"2026-03-03T17:47:09.071572Z","steps":["trace[914145005] 'process raft request' (duration: 882.013885ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:09.071646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.416749525s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T17:47:09.071691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:47:08.189471Z","time spent":"882.161318ms","remote":"127.0.0.1:58480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14465,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T17:47:09.071731Z","caller":"traceutil/trace.go:171","msg":"trace[1554361200] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"1.416868258s","start":"2026-03-03T17:47:07.654841Z","end":"2026-03-03T17:47:09.07171Z","steps":["trace[1554361200] 'agreement among raft nodes before linearized reading' (duration: 1.416698834s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:09.071779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:47:07.654822Z","time spent":"1.41694858s","remote":"127.0.0.1:57954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-03T17:47:09.071829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.535387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-03T17:47:09.071857Z","caller":"traceutil/trace.go:171","msg":"trace[1634368483] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"679.578708ms","start":"2026-03-03T17:47:08.392269Z","end":"2026-03-03T17:47:09.071848Z","steps":["trace[1634368483] 'agreement among raft nodes before linearized reading' (duration: 679.530267ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:09.07188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:47:08.392259Z","time spent":"679.61609ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-03T17:47:09.071787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.977273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-03T17:47:09.071928Z","caller":"traceutil/trace.go:171","msg":"trace[340161389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"662.137767ms","start":"2026-03-03T17:47:08.409779Z","end":"2026-03-03T17:47:09.071916Z","steps":["trace[340161389] 'agreement among raft nodes before linearized reading' (duration: 661.964792ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:09.071968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:47:08.409765Z","time spent":"662.193039ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-03T17:47:09.072003Z","caller":"traceutil/trace.go:171","msg":"trace[940649854] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"884.532467ms","start":"2026-03-03T17:47:08.18746Z","end":"2026-03-03T17:47:09.071992Z","steps":["trace[940649854] 'process raft request' (duration: 883.904192ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:09.072061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.577735ms","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-03-03T17:47:09.07207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:47:08.18744Z","time spent":"884.5993ms","remote":"127.0.0.1:58152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4640,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T17:47:09.072123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.824517ms","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-03-03T17:47:09.072145Z","caller":"traceutil/trace.go:171","msg":"trace[782862060] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:632; }","duration":"685.888598ms","start":"2026-03-03T17:47:08.386252Z","end":"2026-03-03T17:47:09.07214Z","steps":["trace[782862060] 'agreement among raft nodes before linearized reading' (duration: 685.839837ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:09.072163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:47:08.386229Z","time spent":"685.929449ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-03T17:47:09.072081Z","caller":"traceutil/trace.go:171","msg":"trace[1223275910] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"684.630246ms","start":"2026-03-03T17:47:08.387444Z","end":"2026-03-03T17:47:09.072075Z","steps":["trace[1223275910] 'agreement among raft nodes before linearized reading' (duration: 684.575625ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:09.072291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:47:08.387426Z","time spent":"684.854783ms","remote":"127.0.0.1:58246","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-03-03T17:47:09.071706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.327781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-03T17:47:09.072342Z","caller":"traceutil/trace.go:171","msg":"trace[764845344] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:632; }","duration":"787.019328ms","start":"2026-03-03T17:47:08.28531Z","end":"2026-03-03T17:47:09.072329Z","steps":["trace[764845344] 'agreement among raft nodes before linearized reading' (duration: 786.276689ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:09.072387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:47:08.285289Z","time spent":"787.0878ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-03T17:47:10.415526Z","caller":"traceutil/trace.go:171","msg":"trace[1354211496] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"195.203793ms","start":"2026-03-03T17:47:10.220294Z","end":"2026-03-03T17:47:10.415498Z","steps":["trace[1354211496] 'process raft request' (duration: 173.853412ms)","trace[1354211496] 'compare' (duration: 21.145506ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T17:47:10.415816Z","caller":"traceutil/trace.go:171","msg":"trace[481409998] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"195.06548ms","start":"2026-03-03T17:47:10.22074Z","end":"2026-03-03T17:47:10.415806Z","steps":["trace[481409998] 'process raft request' (duration: 194.689721ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:47:10.415914Z","caller":"traceutil/trace.go:171","msg":"trace[67175765] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"190.829052ms","start":"2026-03-03T17:47:10.225071Z","end":"2026-03-03T17:47:10.4159Z","steps":["trace[67175765] 'process raft request' (duration: 190.682549ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:47:10.628284Z","caller":"traceutil/trace.go:171","msg":"trace[678589368] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"206.052858ms","start":"2026-03-03T17:47:10.422205Z","end":"2026-03-03T17:47:10.628258Z","steps":["trace[678589368] 'process raft request' (duration: 141.809161ms)","trace[678589368] 'compare' (duration: 63.987911ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T17:47:10.628464Z","caller":"traceutil/trace.go:171","msg":"trace[1295714678] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"203.967955ms","start":"2026-03-03T17:47:10.424463Z","end":"2026-03-03T17:47:10.628431Z","steps":["trace[1295714678] 'process raft request' (duration: 203.679418ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:47:10.628601Z","caller":"traceutil/trace.go:171","msg":"trace[1703766820] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"201.909092ms","start":"2026-03-03T17:47:10.426682Z","end":"2026-03-03T17:47:10.628591Z","steps":["trace[1703766820] 'process raft request' (duration: 201.852511ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:47:10.628613Z","caller":"traceutil/trace.go:171","msg":"trace[1919324060] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"203.911954ms","start":"2026-03-03T17:47:10.424691Z","end":"2026-03-03T17:47:10.628603Z","steps":["trace[1919324060] 'process raft request' (duration: 203.708179ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:47:10.905733Z","caller":"traceutil/trace.go:171","msg":"trace[1409113739] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"272.662464ms","start":"2026-03-03T17:47:10.633046Z","end":"2026-03-03T17:47:10.905708Z","steps":["trace[1409113739] 'process raft request' (duration: 272.472779ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:47:10.905983Z","caller":"traceutil/trace.go:171","msg":"trace[120730171] linearizableReadLoop","detail":"{readStateIndex:704; appliedIndex:700; }","duration":"252.601016ms","start":"2026-03-03T17:47:10.653365Z","end":"2026-03-03T17:47:10.905966Z","steps":["trace[120730171] 'read index received' (duration: 1.699022ms)","trace[120730171] 'applied index is now lower than readState.Index' (duration: 250.901024ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T17:47:10.9061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.709059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T17:47:10.906122Z","caller":"traceutil/trace.go:171","msg":"trace[1156028783] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:678; }","duration":"252.790241ms","start":"2026-03-03T17:47:10.653324Z","end":"2026-03-03T17:47:10.906114Z","steps":["trace[1156028783] 'agreement among raft nodes before linearized reading' (duration: 252.717489ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:47:10.906264Z","caller":"traceutil/trace.go:171","msg":"trace[150074886] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"272.976361ms","start":"2026-03-03T17:47:10.633281Z","end":"2026-03-03T17:47:10.906258Z","steps":["trace[150074886] 'process raft request' (duration: 272.580442ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:47:10.906303Z","caller":"traceutil/trace.go:171","msg":"trace[47889686] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"273.140546ms","start":"2026-03-03T17:47:10.633151Z","end":"2026-03-03T17:47:10.906292Z","steps":["trace[47889686] 'process raft request' (duration: 272.518081ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:47:10.906445Z","caller":"traceutil/trace.go:171","msg":"trace[1971195083] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"271.934095ms","start":"2026-03-03T17:47:10.634483Z","end":"2026-03-03T17:47:10.906417Z","steps":["trace[1971195083] 'process raft request' (duration: 271.448803ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:19.500991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.957574ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T17:47:19.501088Z","caller":"traceutil/trace.go:171","msg":"trace[39093667] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"250.042161ms","start":"2026-03-03T17:47:19.251029Z","end":"2026-03-03T17:47:19.501071Z","steps":["trace[39093667] 'process raft request' (duration: 43.933793ms)","trace[39093667] 'compare' (duration: 205.869332ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T17:47:19.634156Z","caller":"traceutil/trace.go:171","msg":"trace[127031370] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"382.897936ms","start":"2026-03-03T17:47:19.251218Z","end":"2026-03-03T17:47:19.634116Z","steps":["trace[127031370] 'process raft request' (duration: 356.135518ms)","trace[127031370] 'compare' (duration: 26.43318ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T17:47:19.634338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:47:19.251209Z","time spent":"383.038759ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T17:47:19.802341Z","caller":"traceutil/trace.go:171","msg":"trace[2013488906] linearizableReadLoop","detail":"{readStateIndex:745; appliedIndex:744; }","duration":"145.931355ms","start":"2026-03-03T17:47:19.65638Z","end":"2026-03-03T17:47:19.802312Z","steps":["trace[2013488906] 'read index received' (duration: 109.212015ms)","trace[2013488906] 'applied index is now lower than readState.Index' (duration: 36.71742ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T17:47:19.802372Z","caller":"traceutil/trace.go:171","msg":"trace[1315226663] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"167.165844ms","start":"2026-03-03T17:47:19.635167Z","end":"2026-03-03T17:47:19.802333Z","steps":["trace[1315226663] 'process raft request' (duration: 130.448344ms)","trace[1315226663] 'compare' (duration: 36.490444ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T17:47:19.802812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.417348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T17:47:19.802989Z","caller":"traceutil/trace.go:171","msg":"trace[1521728894] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:716; }","duration":"146.670943ms","start":"2026-03-03T17:47:19.656294Z","end":"2026-03-03T17:47:19.802965Z","steps":["trace[1521728894] 'agreement among raft nodes before linearized reading' (duration: 146.335835ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:21.750289Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058459320491033,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-03T17:47:21.776343Z","caller":"traceutil/trace.go:171","msg":"trace[1092409838] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"545.149004ms","start":"2026-03-03T17:47:21.231176Z","end":"2026-03-03T17:47:21.776325Z","steps":["trace[1092409838] 'process raft request' (duration: 545.051371ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:21.776457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:47:21.231159Z","time spent":"545.238715ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T17:47:21.777963Z","caller":"traceutil/trace.go:171","msg":"trace[1314682376] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:746; }","duration":"528.050192ms","start":"2026-03-03T17:47:21.249878Z","end":"2026-03-03T17:47:21.777928Z","steps":["trace[1314682376] 'read index received' (duration: 526.43625ms)","trace[1314682376] 'applied index is now lower than readState.Index' (duration: 1.612882ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T17:47:21.778238Z","caller":"traceutil/trace.go:171","msg":"trace[1623714832] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"271.379022ms","start":"2026-03-03T17:47:21.506848Z","end":"2026-03-03T17:47:21.778227Z","steps":["trace[1623714832] 'process raft request' (duration: 270.945411ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:21.778529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.631356ms","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-03-03T17:47:21.778577Z","caller":"traceutil/trace.go:171","msg":"trace[1207845039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:718; }","duration":"528.724258ms","start":"2026-03-03T17:47:21.24984Z","end":"2026-03-03T17:47:21.778564Z","steps":["trace[1207845039] 'agreement among raft nodes before linearized reading' (duration: 528.580865ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:21.778608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:47:21.24983Z","time spent":"528.770849ms","remote":"127.0.0.1:58246","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-03-03T17:47:21.778802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.80521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-03T17:47:21.778828Z","caller":"traceutil/trace.go:171","msg":"trace[1630476692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:718; }","duration":"528.867432ms","start":"2026-03-03T17:47:21.249952Z","end":"2026-03-03T17:47:21.77882Z","steps":["trace[1630476692] 'agreement among raft nodes before linearized reading' (duration: 528.79477ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:21.778867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:47:21.249931Z","time spent":"528.929964ms","remote":"127.0.0.1:58246","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-03-03T17:47:21.779069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.2333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T17:47:21.779103Z","caller":"traceutil/trace.go:171","msg":"trace[2133145078] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:718; }","duration":"125.305612ms","start":"2026-03-03T17:47:21.65379Z","end":"2026-03-03T17:47:21.779095Z","steps":["trace[2133145078] 'agreement among raft nodes before linearized reading' (duration: 125.25665ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:21.779283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.838506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-03T17:47:21.779311Z","caller":"traceutil/trace.go:171","msg":"trace[1017920744] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:718; }","duration":"140.893618ms","start":"2026-03-03T17:47:21.63841Z","end":"2026-03-03T17:47:21.779303Z","steps":["trace[1017920744] 'agreement among raft nodes before linearized reading' (duration: 140.816416ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:21.779479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.965106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-03T17:47:21.77951Z","caller":"traceutil/trace.go:171","msg":"trace[32727630] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:718; }","duration":"274.035479ms","start":"2026-03-03T17:47:21.505467Z","end":"2026-03-03T17:47:21.779502Z","steps":["trace[32727630] 'agreement among raft nodes before linearized reading' (duration: 273.958486ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:47:30.069414Z","caller":"traceutil/trace.go:171","msg":"trace[249957611] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"192.670589ms","start":"2026-03-03T17:47:29.876726Z","end":"2026-03-03T17:47:30.069396Z","steps":["trace[249957611] 'process raft request' (duration: 192.558566ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:47:30.150746Z","caller":"traceutil/trace.go:171","msg":"trace[2044067424] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"273.912356ms","start":"2026-03-03T17:47:29.876814Z","end":"2026-03-03T17:47:30.150727Z","steps":["trace[2044067424] 'process raft request' (duration: 269.753681ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:47:30.150832Z","caller":"traceutil/trace.go:171","msg":"trace[1240343979] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"273.980048ms","start":"2026-03-03T17:47:29.876833Z","end":"2026-03-03T17:47:30.150813Z","steps":["trace[1240343979] 'process raft request' (duration: 273.862655ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:47:32.391657Z","caller":"traceutil/trace.go:171","msg":"trace[650197072] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"240.209432ms","start":"2026-03-03T17:47:32.151431Z","end":"2026-03-03T17:47:32.39164Z","steps":["trace[650197072] 'process raft request' (duration: 213.67949ms)","trace[650197072] 'compare' (duration: 25.922026ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T17:47:32.391413Z","caller":"traceutil/trace.go:171","msg":"trace[164649504] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:786; }","duration":"237.017012ms","start":"2026-03-03T17:47:32.154371Z","end":"2026-03-03T17:47:32.391388Z","steps":["trace[164649504] 'read index received' (duration: 210.671234ms)","trace[164649504] 'applied index is now lower than readState.Index' (duration: 26.344638ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T17:47:32.3922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.808192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-03T17:47:32.392254Z","caller":"traceutil/trace.go:171","msg":"trace[210722172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:754; }","duration":"237.902944ms","start":"2026-03-03T17:47:32.154334Z","end":"2026-03-03T17:47:32.392237Z","steps":["trace[210722172] 'agreement among raft nodes before linearized reading' (duration: 237.73221ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:32.392405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.76004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-03T17:47:32.392689Z","caller":"traceutil/trace.go:171","msg":"trace[868674582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:754; }","duration":"238.072788ms","start":"2026-03-03T17:47:32.154595Z","end":"2026-03-03T17:47:32.392667Z","steps":["trace[868674582] 'agreement among raft nodes before linearized reading' (duration: 237.686688ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:47:34.432531Z","caller":"traceutil/trace.go:171","msg":"trace[732922468] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:789; }","duration":"452.080018ms","start":"2026-03-03T17:47:33.98043Z","end":"2026-03-03T17:47:34.43251Z","steps":["trace[732922468] 'read index received' (duration: 451.898283ms)","trace[732922468] 'applied index is now lower than readState.Index' (duration: 180.885µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T17:47:34.432656Z","caller":"traceutil/trace.go:171","msg":"trace[352545467] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"504.416414ms","start":"2026-03-03T17:47:33.928206Z","end":"2026-03-03T17:47:34.432623Z","steps":["trace[352545467] 'process raft request' (duration: 504.177098ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:34.432762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.209498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-03-03T17:47:34.432762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.326164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-03T17:47:34.432795Z","caller":"traceutil/trace.go:171","msg":"trace[527833677] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:757; }","duration":"303.29703ms","start":"2026-03-03T17:47:34.129489Z","end":"2026-03-03T17:47:34.432786Z","steps":["trace[527833677] 'agreement among raft nodes before linearized reading' (duration: 303.214928ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:47:34.432811Z","caller":"traceutil/trace.go:171","msg":"trace[1218042596] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:757; }","duration":"452.417796ms","start":"2026-03-03T17:47:33.980377Z","end":"2026-03-03T17:47:34.432794Z","steps":["trace[1218042596] 'agreement among raft nodes before linearized reading' (duration: 452.275302ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:34.432836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:47:33.928186Z","time spent":"504.565637ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T17:47:34.432885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:47:33.980358Z","time spent":"452.479028ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-03T17:47:34.432818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:47:34.129464Z","time spent":"303.348361ms","remote":"127.0.0.1:58246","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":"info","ts":"2026-03-03T17:47:34.728828Z","caller":"traceutil/trace.go:171","msg":"trace[383600615] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"291.85368ms","start":"2026-03-03T17:47:34.43695Z","end":"2026-03-03T17:47:34.728804Z","steps":["trace[383600615] 'process raft request' (duration: 291.703096ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:34.911568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.100831ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T17:47:34.912001Z","caller":"traceutil/trace.go:171","msg":"trace[77721397] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"474.646439ms","start":"2026-03-03T17:47:34.437323Z","end":"2026-03-03T17:47:34.91197Z","steps":["trace[77721397] 'process raft request' (duration: 371.037565ms)","trace[77721397] 'compare' (duration: 102.994008ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T17:47:34.912027Z","caller":"traceutil/trace.go:171","msg":"trace[1040721243] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"473.611963ms","start":"2026-03-03T17:47:34.438388Z","end":"2026-03-03T17:47:34.912Z","steps":["trace[1040721243] 'process raft request' (duration: 473.554542ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:47:34.91232Z","caller":"traceutil/trace.go:171","msg":"trace[1272235225] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"474.68025ms","start":"2026-03-03T17:47:34.437587Z","end":"2026-03-03T17:47:34.912267Z","steps":["trace[1272235225] 'process raft request' (duration: 474.27687ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:34.912267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:47:34.437304Z","time spent":"474.765462ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T17:47:34.912615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:47:34.437581Z","time spent":"474.859525ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T17:47:34.912799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:47:34.438382Z","time spent":"473.984253ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T17:47:37.081609Z","caller":"traceutil/trace.go:171","msg":"trace[1415029140] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"155.208091ms","start":"2026-03-03T17:47:36.926378Z","end":"2026-03-03T17:47:37.081586Z","steps":["trace[1415029140] 'process raft request' (duration: 155.15688ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:47:37.081702Z","caller":"traceutil/trace.go:171","msg":"trace[1665427928] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"155.281273ms","start":"2026-03-03T17:47:36.92638Z","end":"2026-03-03T17:47:37.081661Z","steps":["trace[1665427928] 'process raft request' (duration: 148.923232ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:47.528015Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058459320491336,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T17:47:48.028553Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058459320491336,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T17:47:48.343805Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.510730025s","expected-duration":"1s"} {"level":"info","ts":"2026-03-03T17:47:48.344252Z","caller":"traceutil/trace.go:171","msg":"trace[1598702558] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"1.511302989s","start":"2026-03-03T17:47:46.832925Z","end":"2026-03-03T17:47:48.344228Z","steps":["trace[1598702558] 'process raft request' (duration: 1.511075393s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:48.344383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:47:46.832909Z","time spent":"1.511406142s","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T17:47:48.354633Z","caller":"traceutil/trace.go:171","msg":"trace[1976265424] linearizableReadLoop","detail":"{readStateIndex:880; appliedIndex:879; }","duration":"1.327342082s","start":"2026-03-03T17:47:47.027256Z","end":"2026-03-03T17:47:48.354599Z","steps":["trace[1976265424] 'read index received' (duration: 1.317608685s)","trace[1976265424] 'applied index is now lower than readState.Index' (duration: 9.732407ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T17:47:48.354821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.327560197s","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-03-03T17:47:48.354844Z","caller":"traceutil/trace.go:171","msg":"trace[93134535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:843; }","duration":"1.327639829s","start":"2026-03-03T17:47:47.027199Z","end":"2026-03-03T17:47:48.354838Z","steps":["trace[93134535] 'agreement among raft nodes before linearized reading' (duration: 1.327524076s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:48.354863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:47:47.027175Z","time spent":"1.32768386s","remote":"127.0.0.1:58246","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-03-03T17:47:48.354945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.206486081s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-03T17:47:48.355002Z","caller":"traceutil/trace.go:171","msg":"trace[379747574] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:843; }","duration":"1.206584913s","start":"2026-03-03T17:47:47.148403Z","end":"2026-03-03T17:47:48.354988Z","steps":["trace[379747574] 'agreement among raft nodes before linearized reading' (duration: 1.206402199s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:48.355036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:47:47.148385Z","time spent":"1.206643505s","remote":"127.0.0.1:58246","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-03-03T17:47:48.355148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.654237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T17:47:48.355175Z","caller":"traceutil/trace.go:171","msg":"trace[299480790] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:843; }","duration":"699.704699ms","start":"2026-03-03T17:47:47.655462Z","end":"2026-03-03T17:47:48.355167Z","steps":["trace[299480790] 'agreement among raft nodes before linearized reading' (duration: 699.660358ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:48.355201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:47:47.655449Z","time spent":"699.74645ms","remote":"127.0.0.1:57954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-03T17:47:48.355245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.420788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-03T17:47:48.355318Z","caller":"traceutil/trace.go:171","msg":"trace[732196384] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:843; }","duration":"813.533021ms","start":"2026-03-03T17:47:47.541777Z","end":"2026-03-03T17:47:48.355311Z","steps":["trace[732196384] 'agreement among raft nodes before linearized reading' (duration: 813.433078ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:48.355335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:47:47.54176Z","time spent":"813.570881ms","remote":"127.0.0.1:55862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":2,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-03-03T17:47:48.355418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.141379222s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-03T17:47:48.355446Z","caller":"traceutil/trace.go:171","msg":"trace[1559600580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:843; }","duration":"1.141447334s","start":"2026-03-03T17:47:47.213991Z","end":"2026-03-03T17:47:48.355438Z","steps":["trace[1559600580] 'agreement among raft nodes before linearized reading' (duration: 1.141344151s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:48.355472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:47:47.213969Z","time spent":"1.141497694s","remote":"127.0.0.1:58246","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-03-03T17:47:50.87935Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058459320491349,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-03T17:47:50.960086Z","caller":"traceutil/trace.go:171","msg":"trace[1675681848] linearizableReadLoop","detail":"{readStateIndex:885; appliedIndex:884; }","duration":"581.386632ms","start":"2026-03-03T17:47:50.378675Z","end":"2026-03-03T17:47:50.960062Z","steps":["trace[1675681848] 'read index received' (duration: 581.171647ms)","trace[1675681848] 'applied index is now lower than readState.Index' (duration: 213.985µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T17:47:50.960405Z","caller":"traceutil/trace.go:171","msg":"trace[1424864563] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"609.080993ms","start":"2026-03-03T17:47:50.351313Z","end":"2026-03-03T17:47:50.960394Z","steps":["trace[1424864563] 'process raft request' (duration: 608.629612ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:50.960527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:47:50.3513Z","time spent":"609.145894ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T17:47:50.960899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.762646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-03T17:47:50.960965Z","caller":"traceutil/trace.go:171","msg":"trace[1344780021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:848; }","duration":"580.873129ms","start":"2026-03-03T17:47:50.380075Z","end":"2026-03-03T17:47:50.960948Z","steps":["trace[1344780021] 'agreement among raft nodes before linearized reading' (duration: 580.673714ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:50.961006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:47:50.38006Z","time spent":"580.9385ms","remote":"127.0.0.1:58246","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-03-03T17:47:50.961049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.305818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-03T17:47:50.961111Z","caller":"traceutil/trace.go:171","msg":"trace[765454673] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:848; }","duration":"421.395831ms","start":"2026-03-03T17:47:50.539698Z","end":"2026-03-03T17:47:50.961094Z","steps":["trace[765454673] 'agreement among raft nodes before linearized reading' (duration: 421.240627ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:50.961154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:47:50.539682Z","time spent":"421.462912ms","remote":"127.0.0.1:58246","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-03-03T17:47:50.961209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.766116ms","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-03-03T17:47:50.961246Z","caller":"traceutil/trace.go:171","msg":"trace[965417305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:848; }","duration":"580.830757ms","start":"2026-03-03T17:47:50.380403Z","end":"2026-03-03T17:47:50.961233Z","steps":["trace[965417305] 'agreement among raft nodes before linearized reading' (duration: 580.745545ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:50.961272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:47:50.380386Z","time spent":"580.880139ms","remote":"127.0.0.1:58246","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-03-03T17:47:50.961408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.737536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-03T17:47:50.961444Z","caller":"traceutil/trace.go:171","msg":"trace[488952710] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:848; }","duration":"582.814588ms","start":"2026-03-03T17:47:50.378619Z","end":"2026-03-03T17:47:50.961434Z","steps":["trace[488952710] 'agreement among raft nodes before linearized reading' (duration: 582.711666ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:47:50.961473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:47:50.378599Z","time spent":"582.868259ms","remote":"127.0.0.1:58246","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":"info","ts":"2026-03-03T17:48:01.217958Z","caller":"traceutil/trace.go:171","msg":"trace[563382168] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"101.061689ms","start":"2026-03-03T17:48:01.116881Z","end":"2026-03-03T17:48:01.217942Z","steps":["trace[563382168] 'process raft request' (duration: 100.960526ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:48:01.231852Z","caller":"traceutil/trace.go:171","msg":"trace[918784136] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"114.216052ms","start":"2026-03-03T17:48:01.117621Z","end":"2026-03-03T17:48:01.231837Z","steps":["trace[918784136] 'process raft request' (duration: 114.016918ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:48:01.231765Z","caller":"traceutil/trace.go:171","msg":"trace[1509299424] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"114.11193ms","start":"2026-03-03T17:48:01.117624Z","end":"2026-03-03T17:48:01.231736Z","steps":["trace[1509299424] 'process raft request' (duration: 113.854574ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:48:05.233044Z","caller":"traceutil/trace.go:171","msg":"trace[852749315] linearizableReadLoop","detail":"{readStateIndex:928; appliedIndex:927; }","duration":"105.482679ms","start":"2026-03-03T17:48:05.127541Z","end":"2026-03-03T17:48:05.233023Z","steps":["trace[852749315] 'read index received' (duration: 105.303204ms)","trace[852749315] 'applied index is now lower than readState.Index' (duration: 178.575µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T17:48:05.233089Z","caller":"traceutil/trace.go:171","msg":"trace[1047080949] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"169.93268ms","start":"2026-03-03T17:48:05.063136Z","end":"2026-03-03T17:48:05.233068Z","steps":["trace[1047080949] 'process raft request' (duration: 169.755526ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:05.23323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.691585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-03T17:48:05.233276Z","caller":"traceutil/trace.go:171","msg":"trace[1316708013] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:888; }","duration":"105.788877ms","start":"2026-03-03T17:48:05.127478Z","end":"2026-03-03T17:48:05.233267Z","steps":["trace[1316708013] 'agreement among raft nodes before linearized reading' (duration: 105.660074ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:07.241544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.241343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5184"} {"level":"info","ts":"2026-03-03T17:48:07.241601Z","caller":"traceutil/trace.go:171","msg":"trace[439221413] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:907; }","duration":"141.337586ms","start":"2026-03-03T17:48:07.100249Z","end":"2026-03-03T17:48:07.241586Z","steps":["trace[439221413] 'range keys from in-memory index tree' (duration: 141.055068ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:48:11.55206Z","caller":"traceutil/trace.go:171","msg":"trace[1419530759] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"175.802938ms","start":"2026-03-03T17:48:11.376236Z","end":"2026-03-03T17:48:11.552039Z","steps":["trace[1419530759] 'process raft request' (duration: 175.691175ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:12.807744Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.212691061s","expected-duration":"1s"} {"level":"info","ts":"2026-03-03T17:48:12.808507Z","caller":"traceutil/trace.go:171","msg":"trace[1161288742] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"1.431836615s","start":"2026-03-03T17:48:11.376647Z","end":"2026-03-03T17:48:12.808484Z","steps":["trace[1161288742] 'process raft request' (duration: 1.43162409s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:12.808848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:11.376635Z","time spent":"1.43204752s","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T17:48:12.809153Z","caller":"traceutil/trace.go:171","msg":"trace[1537395679] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:972; }","duration":"1.294851428s","start":"2026-03-03T17:48:11.514281Z","end":"2026-03-03T17:48:12.809132Z","steps":["trace[1537395679] 'read index received' (duration: 37.801426ms)","trace[1537395679] 'applied index is now lower than readState.Index' (duration: 1.257045472s)"],"step_count":2} {"level":"info","ts":"2026-03-03T17:48:12.809252Z","caller":"traceutil/trace.go:171","msg":"trace[805779265] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"1.431514347s","start":"2026-03-03T17:48:11.377728Z","end":"2026-03-03T17:48:12.809242Z","steps":["trace[805779265] 'process raft request' (duration: 1.431359933s)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:48:12.809204Z","caller":"traceutil/trace.go:171","msg":"trace[1287304653] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"1.431803514s","start":"2026-03-03T17:48:11.377375Z","end":"2026-03-03T17:48:12.809178Z","steps":["trace[1287304653] 'process raft request' (duration: 1.43160395s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:12.80938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:11.377711Z","time spent":"1.431574888s","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T17:48:12.809448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:11.377362Z","time spent":"1.431977108s","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T17:48:12.809534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.29525694s","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-03-03T17:48:12.809567Z","caller":"traceutil/trace.go:171","msg":"trace[955107501] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:935; }","duration":"1.295319652s","start":"2026-03-03T17:48:11.514238Z","end":"2026-03-03T17:48:12.809557Z","steps":["trace[955107501] 'agreement among raft nodes before linearized reading' (duration: 1.29525774s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:12.809591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:11.514222Z","time spent":"1.295362933s","remote":"127.0.0.1:58436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-03-03T17:48:12.809588Z","caller":"traceutil/trace.go:171","msg":"trace[719140242] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"1.432885622s","start":"2026-03-03T17:48:11.376679Z","end":"2026-03-03T17:48:12.809564Z","steps":["trace[719140242] 'process raft request' (duration: 1.431763703s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:12.809722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:11.37666Z","time spent":"1.432978804s","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T17:48:12.809745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.15336015s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T17:48:12.809814Z","caller":"traceutil/trace.go:171","msg":"trace[4428627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:935; }","duration":"1.153436162s","start":"2026-03-03T17:48:11.656337Z","end":"2026-03-03T17:48:12.809773Z","steps":["trace[4428627] 'agreement among raft nodes before linearized reading' (duration: 1.153316439s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:12.809852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:11.656316Z","time spent":"1.153525524s","remote":"127.0.0.1:57954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-03T17:48:12.810011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.62116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.207\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-03T17:48:12.810116Z","caller":"traceutil/trace.go:171","msg":"trace[1512292360] range","detail":"{range_begin:/registry/masterleases/162.253.55.207; range_end:; response_count:1; response_revision:935; }","duration":"782.770714ms","start":"2026-03-03T17:48:12.027315Z","end":"2026-03-03T17:48:12.810086Z","steps":["trace[1512292360] 'agreement among raft nodes before linearized reading' (duration: 782.476927ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:12.810171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:12.027297Z","time spent":"782.863937ms","remote":"127.0.0.1:58018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.207\" "} {"level":"warn","ts":"2026-03-03T17:48:13.044397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.619104ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T17:48:13.044651Z","caller":"traceutil/trace.go:171","msg":"trace[1061169069] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"187.670918ms","start":"2026-03-03T17:48:12.856948Z","end":"2026-03-03T17:48:13.044619Z","steps":["trace[1061169069] 'process raft request' (duration: 39.744736ms)","trace[1061169069] 'compare' (duration: 147.468581ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T17:48:13.533241Z","caller":"traceutil/trace.go:171","msg":"trace[316874258] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"171.702954ms","start":"2026-03-03T17:48:13.361514Z","end":"2026-03-03T17:48:13.533217Z","steps":["trace[316874258] 'process raft request' (duration: 171.52268ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:48:13.709676Z","caller":"traceutil/trace.go:171","msg":"trace[390687094] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"150.181739ms","start":"2026-03-03T17:48:13.559461Z","end":"2026-03-03T17:48:13.709643Z","steps":["trace[390687094] 'process raft request' (duration: 67.329133ms)","trace[390687094] 'compare' (duration: 82.700463ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T17:48:14.53875Z","caller":"traceutil/trace.go:171","msg":"trace[868428372] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"657.753171ms","start":"2026-03-03T17:48:13.88097Z","end":"2026-03-03T17:48:14.538723Z","steps":["trace[868428372] 'process raft request' (duration: 657.575846ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:14.538911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:13.880942Z","time spent":"657.892385ms","remote":"127.0.0.1:58048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":882,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T17:48:15.769304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.201736833s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-03T17:48:15.769382Z","caller":"traceutil/trace.go:171","msg":"trace[1067185231] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:940; }","duration":"1.201870247s","start":"2026-03-03T17:48:14.567496Z","end":"2026-03-03T17:48:15.769366Z","steps":["trace[1067185231] 'count revisions from in-memory index tree' (duration: 1.201642661s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:15.769421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:14.567474Z","time spent":"1.201937818s","remote":"127.0.0.1:58152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-03-03T17:48:15.769792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.71437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-03T17:48:15.769937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.09689112s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-03T17:48:15.769994Z","caller":"traceutil/trace.go:171","msg":"trace[1547247002] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:940; }","duration":"1.096989132s","start":"2026-03-03T17:48:14.672986Z","end":"2026-03-03T17:48:15.769975Z","steps":["trace[1547247002] 'count revisions from in-memory index tree' (duration: 1.096786848s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:15.770036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:14.672962Z","time spent":"1.097062134s","remote":"127.0.0.1:58068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":6,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-03-03T17:48:15.770057Z","caller":"traceutil/trace.go:171","msg":"trace[1730464268] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:940; }","duration":"242.947265ms","start":"2026-03-03T17:48:15.526995Z","end":"2026-03-03T17:48:15.769942Z","steps":["trace[1730464268] 'count revisions from in-memory index tree' (duration: 242.576166ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:15.770406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.704064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-03T17:48:15.77045Z","caller":"traceutil/trace.go:171","msg":"trace[220835023] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:940; }","duration":"956.813897ms","start":"2026-03-03T17:48:14.813626Z","end":"2026-03-03T17:48:15.77044Z","steps":["trace[220835023] 'range keys from in-memory index tree' (duration: 956.506309ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:15.770482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:14.813606Z","time spent":"956.867168ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-03T17:48:15.769854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.114575268s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T17:48:15.770629Z","caller":"traceutil/trace.go:171","msg":"trace[1603232009] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:940; }","duration":"1.11540079s","start":"2026-03-03T17:48:14.655216Z","end":"2026-03-03T17:48:15.770617Z","steps":["trace[1603232009] 'range keys from in-memory index tree' (duration: 1.114513127s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:15.770664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:14.6552Z","time spent":"1.115455611s","remote":"127.0.0.1:57954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-03T17:48:15.770746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.640053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-03T17:48:15.770788Z","caller":"traceutil/trace.go:171","msg":"trace[2099621553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:940; }","duration":"956.719285ms","start":"2026-03-03T17:48:14.814056Z","end":"2026-03-03T17:48:15.770776Z","steps":["trace[2099621553] 'range keys from in-memory index tree' (duration: 956.50451ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:15.770828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:14.814036Z","time spent":"956.781707ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-03T17:48:15.770914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.606877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-03T17:48:15.770954Z","caller":"traceutil/trace.go:171","msg":"trace[1343252543] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:940; }","duration":"623.680809ms","start":"2026-03-03T17:48:15.147264Z","end":"2026-03-03T17:48:15.770945Z","steps":["trace[1343252543] 'count revisions from in-memory index tree' (duration: 623.531365ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:15.770981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:15.147219Z","time spent":"623.754341ms","remote":"127.0.0.1:58452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-03-03T17:48:15.771171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.460619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-03T17:48:15.771205Z","caller":"traceutil/trace.go:171","msg":"trace[1134883687] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:940; }","duration":"956.531971ms","start":"2026-03-03T17:48:14.814665Z","end":"2026-03-03T17:48:15.771197Z","steps":["trace[1134883687] 'range keys from in-memory index tree' (duration: 956.334015ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:15.771204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.910014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-03-03T17:48:15.771229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:14.814646Z","time spent":"956.576691ms","remote":"127.0.0.1:58246","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":"info","ts":"2026-03-03T17:48:15.771235Z","caller":"traceutil/trace.go:171","msg":"trace[918176524] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:940; }","duration":"955.960166ms","start":"2026-03-03T17:48:14.815266Z","end":"2026-03-03T17:48:15.771226Z","steps":["trace[918176524] 'range keys from in-memory index tree' (duration: 955.811772ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:15.771261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:14.815256Z","time spent":"955.998496ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-03T17:48:15.771397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.335045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T17:48:15.771485Z","caller":"traceutil/trace.go:171","msg":"trace[1717214610] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:940; }","duration":"675.465819ms","start":"2026-03-03T17:48:15.096005Z","end":"2026-03-03T17:48:15.771471Z","steps":["trace[1717214610] 'count revisions from in-memory index tree' (duration: 675.246853ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:15.771559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:15.095984Z","time spent":"675.562832ms","remote":"127.0.0.1:58400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-03-03T17:48:16.215051Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058459320491577,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-03T17:48:16.530809Z","caller":"traceutil/trace.go:171","msg":"trace[497857488] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"829.439504ms","start":"2026-03-03T17:48:15.701353Z","end":"2026-03-03T17:48:16.530792Z","steps":["trace[497857488] 'process raft request' (duration: 829.283ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:16.530934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:15.701333Z","time spent":"829.544867ms","remote":"127.0.0.1:58152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6173,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T17:48:16.55511Z","caller":"traceutil/trace.go:171","msg":"trace[1831488273] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"778.367192ms","start":"2026-03-03T17:48:15.776725Z","end":"2026-03-03T17:48:16.555093Z","steps":["trace[1831488273] 'process raft request' (duration: 778.318311ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:48:16.555163Z","caller":"traceutil/trace.go:171","msg":"trace[650216375] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"778.628549ms","start":"2026-03-03T17:48:15.776502Z","end":"2026-03-03T17:48:16.555131Z","steps":["trace[650216375] 'process raft request' (duration: 778.421724ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:16.55523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:15.776704Z","time spent":"778.464705ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T17:48:16.555339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:15.776479Z","time spent":"778.759822ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T17:48:16.555662Z","caller":"traceutil/trace.go:171","msg":"trace[610757445] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"778.868075ms","start":"2026-03-03T17:48:15.776785Z","end":"2026-03-03T17:48:16.555653Z","steps":["trace[610757445] 'process raft request' (duration: 778.285631ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:16.555715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:15.776759Z","time spent":"778.931116ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T17:48:16.555874Z","caller":"traceutil/trace.go:171","msg":"trace[1031713311] linearizableReadLoop","detail":"{readStateIndex:987; appliedIndex:982; }","duration":"841.593082ms","start":"2026-03-03T17:48:15.714265Z","end":"2026-03-03T17:48:16.555859Z","steps":["trace[1031713311] 'read index received' (duration: 816.476447ms)","trace[1031713311] 'applied index is now lower than readState.Index' (duration: 25.115115ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T17:48:16.556005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.797108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-03T17:48:16.556039Z","caller":"traceutil/trace.go:171","msg":"trace[2087077371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:945; }","duration":"841.86672ms","start":"2026-03-03T17:48:15.714164Z","end":"2026-03-03T17:48:16.556031Z","steps":["trace[2087077371] 'agreement among raft nodes before linearized reading' (duration: 841.773107ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:16.556059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:15.714142Z","time spent":"841.91305ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-03T17:48:16.556008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.321737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T17:48:16.556116Z","caller":"traceutil/trace.go:171","msg":"trace[2143850877] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:945; }","duration":"779.45631ms","start":"2026-03-03T17:48:15.776653Z","end":"2026-03-03T17:48:16.55611Z","steps":["trace[2143850877] 'agreement among raft nodes before linearized reading' (duration: 779.312596ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:16.55612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.002783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T17:48:16.556135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:15.776647Z","time spent":"779.48305ms","remote":"127.0.0.1:57952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-03T17:48:16.556154Z","caller":"traceutil/trace.go:171","msg":"trace[514074273] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:945; }","duration":"325.030104ms","start":"2026-03-03T17:48:16.23111Z","end":"2026-03-03T17:48:16.55614Z","steps":["trace[514074273] 'agreement among raft nodes before linearized reading' (duration: 324.991043ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:16.556195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:16.23109Z","time spent":"325.096265ms","remote":"127.0.0.1:58132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-03-03T17:48:16.556083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.640803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-03T17:48:16.556253Z","caller":"traceutil/trace.go:171","msg":"trace[98755430] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:945; }","duration":"171.851968ms","start":"2026-03-03T17:48:16.384389Z","end":"2026-03-03T17:48:16.556241Z","steps":["trace[98755430] 'agreement among raft nodes before linearized reading' (duration: 171.650813ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:48:16.556008Z","caller":"traceutil/trace.go:171","msg":"trace[1037874345] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"776.007143ms","start":"2026-03-03T17:48:15.779885Z","end":"2026-03-03T17:48:16.555892Z","steps":["trace[1037874345] 'process raft request' (duration: 775.733976ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:16.556983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:15.779869Z","time spent":"776.969747ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T17:48:16.747665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.10398ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T17:48:16.747791Z","caller":"traceutil/trace.go:171","msg":"trace[2087300022] linearizableReadLoop","detail":"{readStateIndex:988; appliedIndex:987; }","duration":"185.160444ms","start":"2026-03-03T17:48:16.562615Z","end":"2026-03-03T17:48:16.747776Z","steps":["trace[2087300022] 'read index received' (duration: 73.782427ms)","trace[2087300022] 'applied index is now lower than readState.Index' (duration: 111.376857ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T17:48:16.747869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.250296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T17:48:16.747902Z","caller":"traceutil/trace.go:171","msg":"trace[916231642] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:946; }","duration":"185.302198ms","start":"2026-03-03T17:48:16.562591Z","end":"2026-03-03T17:48:16.747893Z","steps":["trace[916231642] 'agreement among raft nodes before linearized reading' (duration: 185.229456ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:48:16.74809Z","caller":"traceutil/trace.go:171","msg":"trace[656666800] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"187.866493ms","start":"2026-03-03T17:48:16.560181Z","end":"2026-03-03T17:48:16.748048Z","steps":["trace[656666800] 'process raft request' (duration: 76.294161ms)","trace[656666800] 'compare' (duration: 110.980657ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T17:48:17.196079Z","caller":"traceutil/trace.go:171","msg":"trace[145249331] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"108.089874ms","start":"2026-03-03T17:48:17.087958Z","end":"2026-03-03T17:48:17.196047Z","steps":["trace[145249331] 'process raft request' (duration: 107.859589ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:48:17.336823Z","caller":"traceutil/trace.go:171","msg":"trace[436058492] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"134.118984ms","start":"2026-03-03T17:48:17.202675Z","end":"2026-03-03T17:48:17.336794Z","steps":["trace[436058492] 'read index received' (duration: 43.799238ms)","trace[436058492] 'applied index is now lower than readState.Index' (duration: 90.314806ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T17:48:17.336984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.281938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T17:48:17.337015Z","caller":"traceutil/trace.go:171","msg":"trace[806843366] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:949; }","duration":"134.358439ms","start":"2026-03-03T17:48:17.202645Z","end":"2026-03-03T17:48:17.337004Z","steps":["trace[806843366] 'agreement among raft nodes before linearized reading' (duration: 134.240506ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:48:17.54956Z","caller":"traceutil/trace.go:171","msg":"trace[607801492] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"163.755493ms","start":"2026-03-03T17:48:17.385787Z","end":"2026-03-03T17:48:17.549542Z","steps":["trace[607801492] 'process raft request' (duration: 163.705572ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:48:17.549604Z","caller":"traceutil/trace.go:171","msg":"trace[1019067398] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"175.837529ms","start":"2026-03-03T17:48:17.37372Z","end":"2026-03-03T17:48:17.549558Z","steps":["trace[1019067398] 'process raft request' (duration: 87.164375ms)","trace[1019067398] 'compare' (duration: 88.463218ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T17:48:18.191434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.712664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T17:48:18.191544Z","caller":"traceutil/trace.go:171","msg":"trace[974098474] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:953; }","duration":"537.877808ms","start":"2026-03-03T17:48:17.65364Z","end":"2026-03-03T17:48:18.191518Z","steps":["trace[974098474] 'range keys from in-memory index tree' (duration: 537.554671ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:18.191666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:17.65362Z","time spent":"538.033553ms","remote":"127.0.0.1:57954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-03T17:48:18.191713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.403067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-03T17:48:18.191776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.180145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-03T17:48:18.191795Z","caller":"traceutil/trace.go:171","msg":"trace[40367564] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:953; }","duration":"188.550171ms","start":"2026-03-03T17:48:18.003225Z","end":"2026-03-03T17:48:18.191775Z","steps":["trace[40367564] 'count revisions from in-memory index tree' (duration: 188.317304ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:48:18.191829Z","caller":"traceutil/trace.go:171","msg":"trace[1504940084] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:953; }","duration":"117.272738ms","start":"2026-03-03T17:48:18.074541Z","end":"2026-03-03T17:48:18.191814Z","steps":["trace[1504940084] 'count revisions from in-memory index tree' (duration: 117.092633ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:18.793606Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058459320491603,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-03T17:48:18.958245Z","caller":"traceutil/trace.go:171","msg":"trace[2102768103] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:996; }","duration":"665.614369ms","start":"2026-03-03T17:48:18.292605Z","end":"2026-03-03T17:48:18.958219Z","steps":["trace[2102768103] 'read index received' (duration: 665.495546ms)","trace[2102768103] 'applied index is now lower than readState.Index' (duration: 117.593µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T17:48:18.958307Z","caller":"traceutil/trace.go:171","msg":"trace[826005289] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"761.799533ms","start":"2026-03-03T17:48:18.196473Z","end":"2026-03-03T17:48:18.958272Z","steps":["trace[826005289] 'process raft request' (duration: 761.626559ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:18.958375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.758453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T17:48:18.958406Z","caller":"traceutil/trace.go:171","msg":"trace[1953067303] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:954; }","duration":"665.866935ms","start":"2026-03-03T17:48:18.292532Z","end":"2026-03-03T17:48:18.958399Z","steps":["trace[1953067303] 'agreement among raft nodes before linearized reading' (duration: 665.798333ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:18.958432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:18.292512Z","time spent":"665.912647ms","remote":"127.0.0.1:58270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-03T17:48:18.958476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:18.196452Z","time spent":"761.930597ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T17:48:18.958482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.150316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-03T17:48:18.958705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.200564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-03T17:48:18.958659Z","caller":"traceutil/trace.go:171","msg":"trace[729637439] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:954; }","duration":"659.442774ms","start":"2026-03-03T17:48:18.299192Z","end":"2026-03-03T17:48:18.958617Z","steps":["trace[729637439] 'agreement among raft nodes before linearized reading' (duration: 659.165716ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:48:18.958795Z","caller":"traceutil/trace.go:171","msg":"trace[1669177265] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:954; }","duration":"396.364278ms","start":"2026-03-03T17:48:18.562409Z","end":"2026-03-03T17:48:18.958773Z","steps":["trace[1669177265] 'agreement among raft nodes before linearized reading' (duration: 396.05676ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:18.958853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:18.562378Z","time spent":"396.465401ms","remote":"127.0.0.1:58246","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-03-03T17:48:18.958839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:18.299161Z","time spent":"659.66173ms","remote":"127.0.0.1:58424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-03-03T17:48:18.958917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.631768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-03T17:48:18.958949Z","caller":"traceutil/trace.go:171","msg":"trace[636148100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:954; }","duration":"206.70052ms","start":"2026-03-03T17:48:18.75224Z","end":"2026-03-03T17:48:18.958941Z","steps":["trace[636148100] 'agreement among raft nodes before linearized reading' (duration: 206.596837ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:18.958963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.662217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-03T17:48:18.958953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.090221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-03T17:48:18.959015Z","caller":"traceutil/trace.go:171","msg":"trace[1883385020] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:954; }","duration":"398.767179ms","start":"2026-03-03T17:48:18.560233Z","end":"2026-03-03T17:48:18.959Z","steps":["trace[1883385020] 'agreement among raft nodes before linearized reading' (duration: 398.527123ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:18.959026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.643997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T17:48:18.959036Z","caller":"traceutil/trace.go:171","msg":"trace[1826010296] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:954; }","duration":"398.205485ms","start":"2026-03-03T17:48:18.56081Z","end":"2026-03-03T17:48:18.959015Z","steps":["trace[1826010296] 'agreement among raft nodes before linearized reading' (duration: 397.931088ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:18.959051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:18.560204Z","time spent":"398.840321ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-03T17:48:18.959055Z","caller":"traceutil/trace.go:171","msg":"trace[1670644875] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:954; }","duration":"302.716498ms","start":"2026-03-03T17:48:18.65633Z","end":"2026-03-03T17:48:18.959046Z","steps":["trace[1670644875] 'agreement among raft nodes before linearized reading' (duration: 302.671917ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:18.959084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:18.656307Z","time spent":"302.77212ms","remote":"127.0.0.1:57954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-03T17:48:18.959088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:18.5608Z","time spent":"398.278726ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-03T17:48:18.959285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.334977ms","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-03-03T17:48:18.959311Z","caller":"traceutil/trace.go:171","msg":"trace[1980603937] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:954; }","duration":"398.376819ms","start":"2026-03-03T17:48:18.560928Z","end":"2026-03-03T17:48:18.959305Z","steps":["trace[1980603937] 'agreement among raft nodes before linearized reading' (duration: 397.798194ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:18.959329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:18.560919Z","time spent":"398.405849ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-03T17:48:19.466672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.516929ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T17:48:19.466963Z","caller":"traceutil/trace.go:171","msg":"trace[2101873213] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"501.904068ms","start":"2026-03-03T17:48:18.965037Z","end":"2026-03-03T17:48:19.466941Z","steps":["trace[2101873213] 'process raft request' (duration: 501.861687ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:19.467068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:18.965026Z","time spent":"501.996401ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T17:48:19.467381Z","caller":"traceutil/trace.go:171","msg":"trace[944867938] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"504.035462ms","start":"2026-03-03T17:48:18.96333Z","end":"2026-03-03T17:48:19.467366Z","steps":["trace[944867938] 'process raft request' (duration: 84.716863ms)","trace[944867938] 'compare' (duration: 418.024627ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T17:48:19.467458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:18.963311Z","time spent":"504.111984ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T17:48:19.467644Z","caller":"traceutil/trace.go:171","msg":"trace[1054932600] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"503.682163ms","start":"2026-03-03T17:48:18.963949Z","end":"2026-03-03T17:48:19.467631Z","steps":["trace[1054932600] 'process raft request' (duration: 502.836062ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:19.467717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:18.963927Z","time spent":"503.754625ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T17:48:19.46784Z","caller":"traceutil/trace.go:171","msg":"trace[992507496] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"503.790806ms","start":"2026-03-03T17:48:18.964041Z","end":"2026-03-03T17:48:19.467831Z","steps":["trace[992507496] 'process raft request' (duration: 502.794741ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:19.467894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:18.964033Z","time spent":"503.835587ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T17:48:19.468002Z","caller":"traceutil/trace.go:171","msg":"trace[408602510] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"503.637771ms","start":"2026-03-03T17:48:18.964356Z","end":"2026-03-03T17:48:19.467994Z","steps":["trace[408602510] 'process raft request' (duration: 502.514883ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:19.468053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:18.964344Z","time spent":"503.684973ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T17:48:21.984845Z","caller":"traceutil/trace.go:171","msg":"trace[699287967] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"508.224919ms","start":"2026-03-03T17:48:21.476599Z","end":"2026-03-03T17:48:21.984824Z","steps":["trace[699287967] 'process raft request' (duration: 508.061045ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:21.985003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:21.476582Z","time spent":"508.334262ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T17:48:22.516874Z","caller":"traceutil/trace.go:171","msg":"trace[1252098143] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"1.039590041s","start":"2026-03-03T17:48:21.477256Z","end":"2026-03-03T17:48:22.516846Z","steps":["trace[1252098143] 'process raft request' (duration: 1.039242063s)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:48:22.516945Z","caller":"traceutil/trace.go:171","msg":"trace[1668500903] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1003; }","duration":"489.451644ms","start":"2026-03-03T17:48:22.027479Z","end":"2026-03-03T17:48:22.51693Z","steps":["trace[1668500903] 'read index received' (duration: 488.933801ms)","trace[1668500903] 'applied index is now lower than readState.Index' (duration: 517.143µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T17:48:22.516928Z","caller":"traceutil/trace.go:171","msg":"trace[1418509843] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"1.039286294s","start":"2026-03-03T17:48:21.477602Z","end":"2026-03-03T17:48:22.516888Z","steps":["trace[1418509843] 'process raft request' (duration: 1.03913349s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:22.517079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:21.477245Z","time spent":"1.039729925s","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T17:48:22.517118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:21.477595Z","time spent":"1.039420438s","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T17:48:22.516913Z","caller":"traceutil/trace.go:171","msg":"trace[751833377] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"1.038900804s","start":"2026-03-03T17:48:21.477983Z","end":"2026-03-03T17:48:22.516884Z","steps":["trace[751833377] 'process raft request' (duration: 1.038810702s)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:48:22.516972Z","caller":"traceutil/trace.go:171","msg":"trace[1703561503] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"1.038296799s","start":"2026-03-03T17:48:21.478654Z","end":"2026-03-03T17:48:22.516951Z","steps":["trace[1703561503] 'process raft request' (duration: 1.038180836s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:22.51724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.752851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.207\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-03T17:48:22.517271Z","caller":"traceutil/trace.go:171","msg":"trace[1811166205] range","detail":"{range_begin:/registry/masterleases/162.253.55.207; range_end:; response_count:1; response_revision:964; }","duration":"489.839433ms","start":"2026-03-03T17:48:22.027423Z","end":"2026-03-03T17:48:22.517263Z","steps":["trace[1811166205] 'agreement among raft nodes before linearized reading' (duration: 489.554486ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:22.517297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:22.027398Z","time spent":"489.893235ms","remote":"127.0.0.1:58018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.207\" "} {"level":"warn","ts":"2026-03-03T17:48:22.517348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:21.478645Z","time spent":"1.038607107s","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T17:48:22.517768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:48:21.477964Z","time spent":"1.039320624s","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T17:48:24.075169Z","caller":"traceutil/trace.go:171","msg":"trace[1805674449] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1013; }","duration":"110.847125ms","start":"2026-03-03T17:48:23.964292Z","end":"2026-03-03T17:48:24.075139Z","steps":["trace[1805674449] 'read index received' (duration: 30.439771ms)","trace[1805674449] 'applied index is now lower than readState.Index' (duration: 80.402604ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T17:48:24.075186Z","caller":"traceutil/trace.go:171","msg":"trace[739414140] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"220.897979ms","start":"2026-03-03T17:48:23.854256Z","end":"2026-03-03T17:48:24.075153Z","steps":["trace[739414140] 'process raft request' (duration: 140.491304ms)","trace[739414140] 'compare' (duration: 80.037506ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T17:48:24.075392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.089891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-03-03T17:48:24.075739Z","caller":"traceutil/trace.go:171","msg":"trace[617210027] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:969; }","duration":"111.485761ms","start":"2026-03-03T17:48:23.96424Z","end":"2026-03-03T17:48:24.075725Z","steps":["trace[617210027] 'agreement among raft nodes before linearized reading' (duration: 111.012299ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:24.075491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.310647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T17:48:24.07588Z","caller":"traceutil/trace.go:171","msg":"trace[1917381009] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:968; }","duration":"262.983963ms","start":"2026-03-03T17:48:23.812881Z","end":"2026-03-03T17:48:24.075865Z","steps":["trace[1917381009] 'range keys from in-memory index tree' (duration: 262.02475ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:48:28.75329Z","caller":"traceutil/trace.go:171","msg":"trace[577375815] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1054; }","duration":"204.306569ms","start":"2026-03-03T17:48:28.548962Z","end":"2026-03-03T17:48:28.753268Z","steps":["trace[577375815] 'read index received' (duration: 204.107344ms)","trace[577375815] 'applied index is now lower than readState.Index' (duration: 198.385µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T17:48:28.753574Z","caller":"traceutil/trace.go:171","msg":"trace[1499693894] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"207.415688ms","start":"2026-03-03T17:48:28.546149Z","end":"2026-03-03T17:48:28.753565Z","steps":["trace[1499693894] 'process raft request' (duration: 206.973227ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:28.753757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.659038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-03T17:48:28.753842Z","caller":"traceutil/trace.go:171","msg":"trace[1139402704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1009; }","duration":"204.76111ms","start":"2026-03-03T17:48:28.549055Z","end":"2026-03-03T17:48:28.753816Z","steps":["trace[1139402704] 'agreement among raft nodes before linearized reading' (duration: 204.527214ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:28.753856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.685569ms","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-03-03T17:48:28.753886Z","caller":"traceutil/trace.go:171","msg":"trace[962419995] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1009; }","duration":"204.77034ms","start":"2026-03-03T17:48:28.549107Z","end":"2026-03-03T17:48:28.753877Z","steps":["trace[962419995] 'agreement among raft nodes before linearized reading' (duration: 204.666678ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:28.753993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.007667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-03T17:48:28.754069Z","caller":"traceutil/trace.go:171","msg":"trace[843517511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1009; }","duration":"205.092879ms","start":"2026-03-03T17:48:28.548959Z","end":"2026-03-03T17:48:28.754052Z","steps":["trace[843517511] 'agreement among raft nodes before linearized reading' (duration: 204.910194ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:48:28.897277Z","caller":"traceutil/trace.go:171","msg":"trace[922109668] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"139.421267ms","start":"2026-03-03T17:48:28.757832Z","end":"2026-03-03T17:48:28.897254Z","steps":["trace[922109668] 'process raft request' (duration: 139.319044ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:48:28.897301Z","caller":"traceutil/trace.go:171","msg":"trace[1021272118] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"140.059963ms","start":"2026-03-03T17:48:28.757208Z","end":"2026-03-03T17:48:28.897268Z","steps":["trace[1021272118] 'process raft request' (duration: 91.668259ms)","trace[1021272118] 'compare' (duration: 48.138038ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T17:48:28.89758Z","caller":"traceutil/trace.go:171","msg":"trace[1215010012] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"138.970095ms","start":"2026-03-03T17:48:28.758419Z","end":"2026-03-03T17:48:28.89739Z","steps":["trace[1215010012] 'process raft request' (duration: 138.780591ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:48:29.228035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.509185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"warn","ts":"2026-03-03T17:48:29.228253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.198633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-03-03T17:48:29.228353Z","caller":"traceutil/trace.go:171","msg":"trace[1970433073] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1012; }","duration":"140.899566ms","start":"2026-03-03T17:48:29.087431Z","end":"2026-03-03T17:48:29.22833Z","steps":["trace[1970433073] 'range keys from in-memory index tree' (duration: 140.222208ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:48:29.228363Z","caller":"traceutil/trace.go:171","msg":"trace[757401692] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1012; }","duration":"128.338527ms","start":"2026-03-03T17:48:29.099997Z","end":"2026-03-03T17:48:29.228335Z","steps":["trace[757401692] 'range keys from in-memory index tree' (duration: 127.899536ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:49:29.433039Z","caller":"traceutil/trace.go:171","msg":"trace[1126660285] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"101.041198ms","start":"2026-03-03T17:49:29.331938Z","end":"2026-03-03T17:49:29.432979Z","steps":["trace[1126660285] 'process raft request' (duration: 100.7826ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:49:36.717673Z","caller":"traceutil/trace.go:171","msg":"trace[192903353] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"191.78195ms","start":"2026-03-03T17:49:36.525852Z","end":"2026-03-03T17:49:36.717634Z","steps":["trace[192903353] 'process raft request' (duration: 191.584485ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:49:37.836731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.809044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-03-03T17:49:37.836812Z","caller":"traceutil/trace.go:171","msg":"trace[920444358] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1292; }","duration":"106.936248ms","start":"2026-03-03T17:49:37.729853Z","end":"2026-03-03T17:49:37.836789Z","steps":["trace[920444358] 'range keys from in-memory index tree' (duration: 106.628109ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:50:15.492784Z","caller":"traceutil/trace.go:171","msg":"trace[1320394859] transaction","detail":"{read_only:false; response_revision:1445; number_of_response:1; }","duration":"131.673633ms","start":"2026-03-03T17:50:15.361087Z","end":"2026-03-03T17:50:15.492761Z","steps":["trace[1320394859] 'process raft request' (duration: 131.523989ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:50:16.206323Z","caller":"traceutil/trace.go:171","msg":"trace[1629237598] transaction","detail":"{read_only:false; response_revision:1450; number_of_response:1; }","duration":"118.196441ms","start":"2026-03-03T17:50:16.088092Z","end":"2026-03-03T17:50:16.206289Z","steps":["trace[1629237598] 'process raft request' (duration: 79.454227ms)","trace[1629237598] 'compare' (duration: 38.623141ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T17:50:18.119048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.645356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-03-03T17:50:18.119149Z","caller":"traceutil/trace.go:171","msg":"trace[1269123799] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1456; }","duration":"104.811312ms","start":"2026-03-03T17:50:18.014317Z","end":"2026-03-03T17:50:18.119128Z","steps":["trace[1269123799] 'range keys from in-memory index tree' (duration: 104.450681ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:51:22.169443Z","caller":"traceutil/trace.go:171","msg":"trace[374201708] transaction","detail":"{read_only:false; response_revision:1738; number_of_response:1; }","duration":"114.726303ms","start":"2026-03-03T17:51:22.05466Z","end":"2026-03-03T17:51:22.169387Z","steps":["trace[374201708] 'process raft request' (duration: 114.396115ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:51:32.756654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.940326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T17:51:32.756764Z","caller":"traceutil/trace.go:171","msg":"trace[1392099538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1786; }","duration":"101.09636ms","start":"2026-03-03T17:51:32.655643Z","end":"2026-03-03T17:51:32.756739Z","steps":["trace[1392099538] 'range keys from in-memory index tree' (duration: 100.819402ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:51:42.047903Z","caller":"traceutil/trace.go:171","msg":"trace[1739762294] linearizableReadLoop","detail":"{readStateIndex:1912; appliedIndex:1911; }","duration":"391.222395ms","start":"2026-03-03T17:51:41.656659Z","end":"2026-03-03T17:51:42.047881Z","steps":["trace[1739762294] 'read index received' (duration: 391.163574ms)","trace[1739762294] 'applied index is now lower than readState.Index' (duration: 57.841µs)"],"step_count":2} {"level":"warn","ts":"2026-03-03T17:51:42.048085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.410841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T17:51:42.048182Z","caller":"traceutil/trace.go:171","msg":"trace[2108059865] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1827; }","duration":"391.557964ms","start":"2026-03-03T17:51:41.656605Z","end":"2026-03-03T17:51:42.048163Z","steps":["trace[2108059865] 'agreement among raft nodes before linearized reading' (duration: 391.354349ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:51:42.048215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:51:41.656585Z","time spent":"391.623686ms","remote":"127.0.0.1:57954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-03T17:51:42.048618Z","caller":"traceutil/trace.go:171","msg":"trace[1299439117] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"635.371932ms","start":"2026-03-03T17:51:41.413224Z","end":"2026-03-03T17:51:42.048596Z","steps":["trace[1299439117] 'process raft request' (duration: 634.517191ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T17:51:42.048894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T17:51:41.413197Z","time spent":"635.483745ms","remote":"127.0.0.1:58246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T17:51:42.069966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.855166ms","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":"info","ts":"2026-03-03T17:51:42.070027Z","caller":"traceutil/trace.go:171","msg":"trace[1157932041] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1828; }","duration":"202.963249ms","start":"2026-03-03T17:51:41.867047Z","end":"2026-03-03T17:51:42.070011Z","steps":["trace[1157932041] 'agreement among raft nodes before linearized reading' (duration: 202.848906ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:51:56.549499Z","caller":"traceutil/trace.go:171","msg":"trace[1342380240] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"168.054159ms","start":"2026-03-03T17:51:56.381412Z","end":"2026-03-03T17:51:56.549466Z","steps":["trace[1342380240] 'process raft request' (duration: 167.974847ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T17:51:56.549638Z","caller":"traceutil/trace.go:171","msg":"trace[817399655] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"168.645053ms","start":"2026-03-03T17:51:56.380972Z","end":"2026-03-03T17:51:56.549617Z","steps":["trace[817399655] 'process raft request' (duration: 102.347242ms)","trace[817399655] 'compare' (duration: 65.929822ms)"],"step_count":2}