{"level":"warn","ts":"2026-03-30T17:55:40.174187Z","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-30T17:55:40.17426Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.226:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.226:2380","--initial-cluster=instance=https://199.19.213.226:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.226:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.226: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-30T17:55:40.174318Z","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-30T17:55:40.17433Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.226:2380"]} {"level":"info","ts":"2026-03-30T17:55:40.174345Z","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-30T17:55:40.174951Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.226:2379"]} {"level":"info","ts":"2026-03-30T17:55:40.175018Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.226:2380"],"listen-peer-urls":["https://199.19.213.226:2380"],"advertise-client-urls":["https://199.19.213.226:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.226:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.226: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-30T17:55:40.178681Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.468412ms"} {"level":"info","ts":"2026-03-30T17:55:40.185173Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c1a97aa766b87c7e","cluster-id":"34571fc6a0261e75"} {"level":"info","ts":"2026-03-30T17:55:40.185241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c1a97aa766b87c7e switched to configuration voters=()"} {"level":"info","ts":"2026-03-30T17:55:40.185276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c1a97aa766b87c7e became follower at term 0"} {"level":"info","ts":"2026-03-30T17:55:40.185293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c1a97aa766b87c7e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-30T17:55:40.185305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c1a97aa766b87c7e became follower at term 1"} {"level":"info","ts":"2026-03-30T17:55:40.185348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c1a97aa766b87c7e switched to configuration voters=(13954819779785686142)"} {"level":"warn","ts":"2026-03-30T17:55:40.193542Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-30T17:55:40.194694Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-30T17:55:40.19787Z","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-30T17:55:40.199799Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c1a97aa766b87c7e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-30T17:55:40.201706Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c1a97aa766b87c7e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-30T17:55:40.202791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c1a97aa766b87c7e switched to configuration voters=(13954819779785686142)"} {"level":"info","ts":"2026-03-30T17:55:40.203083Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"34571fc6a0261e75","local-member-id":"c1a97aa766b87c7e","added-peer-id":"c1a97aa766b87c7e","added-peer-peer-urls":["https://199.19.213.226:2380"]} {"level":"info","ts":"2026-03-30T17:55:40.202826Z","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-30T17:55:40.203161Z","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-30T17:55:40.203174Z","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-30T17:55:40.205827Z","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-30T17:55:40.205943Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.226:2380"} {"level":"info","ts":"2026-03-30T17:55:40.205982Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.226:2380"} {"level":"info","ts":"2026-03-30T17:55:40.206088Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c1a97aa766b87c7e","initial-advertise-peer-urls":["https://199.19.213.226:2380"],"listen-peer-urls":["https://199.19.213.226:2380"],"advertise-client-urls":["https://199.19.213.226:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.226:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-30T17:55:40.206133Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-30T17:55:40.2864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c1a97aa766b87c7e is starting a new election at term 1"} {"level":"info","ts":"2026-03-30T17:55:40.286468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c1a97aa766b87c7e became pre-candidate at term 1"} {"level":"info","ts":"2026-03-30T17:55:40.286499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c1a97aa766b87c7e received MsgPreVoteResp from c1a97aa766b87c7e at term 1"} {"level":"info","ts":"2026-03-30T17:55:40.28652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c1a97aa766b87c7e became candidate at term 2"} {"level":"info","ts":"2026-03-30T17:55:40.286531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c1a97aa766b87c7e received MsgVoteResp from c1a97aa766b87c7e at term 2"} {"level":"info","ts":"2026-03-30T17:55:40.286547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c1a97aa766b87c7e became leader at term 2"} {"level":"info","ts":"2026-03-30T17:55:40.28656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c1a97aa766b87c7e elected leader c1a97aa766b87c7e at term 2"} {"level":"info","ts":"2026-03-30T17:55:40.287675Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c1a97aa766b87c7e","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.226:2379]}","request-path":"/0/members/c1a97aa766b87c7e/attributes","cluster-id":"34571fc6a0261e75","publish-timeout":"7s"} {"level":"info","ts":"2026-03-30T17:55:40.287902Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-30T17:55:40.287974Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-30T17:55:40.287976Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-30T17:55:40.288701Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-30T17:55:40.288739Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-30T17:55:40.288934Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"34571fc6a0261e75","local-member-id":"c1a97aa766b87c7e","cluster-version":"3.5"} {"level":"info","ts":"2026-03-30T17:55:40.289036Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-30T17:55:40.289124Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-30T17:55:40.293656Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.226:2379"} {"level":"info","ts":"2026-03-30T17:55:40.293599Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-30T17:55:50.119015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.863007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-03-30T17:55:50.119114Z","caller":"traceutil/trace.go:171","msg":"trace[1465011602] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:364; }","duration":"124.039201ms","start":"2026-03-30T17:55:49.995057Z","end":"2026-03-30T17:55:50.119096Z","steps":["trace[1465011602] 'range keys from in-memory index tree' (duration: 123.676483ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:55:50.249557Z","caller":"traceutil/trace.go:171","msg":"trace[1402320634] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"122.76357ms","start":"2026-03-30T17:55:50.126769Z","end":"2026-03-30T17:55:50.249533Z","steps":["trace[1402320634] 'process raft request' (duration: 122.627577ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:55:50.271077Z","caller":"traceutil/trace.go:171","msg":"trace[1094706610] linearizableReadLoop","detail":"{readStateIndex:378; appliedIndex:376; }","duration":"125.729781ms","start":"2026-03-30T17:55:50.145321Z","end":"2026-03-30T17:55:50.271051Z","steps":["trace[1094706610] 'read index received' (duration: 104.101892ms)","trace[1094706610] 'applied index is now lower than readState.Index' (duration: 21.626999ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:55:50.271256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.910525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-30T17:55:50.271297Z","caller":"traceutil/trace.go:171","msg":"trace[782986621] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:366; }","duration":"126.001767ms","start":"2026-03-30T17:55:50.145285Z","end":"2026-03-30T17:55:50.271287Z","steps":["trace[782986621] 'agreement among raft nodes before linearized reading' (duration: 125.890435ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:55:50.271245Z","caller":"traceutil/trace.go:171","msg":"trace[852856457] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"142.81154ms","start":"2026-03-30T17:55:50.128311Z","end":"2026-03-30T17:55:50.271122Z","steps":["trace[852856457] 'process raft request' (duration: 142.618505ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:55:55.964718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.783027ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-30T17:55:55.964939Z","caller":"traceutil/trace.go:171","msg":"trace[712458683] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:424; }","duration":"187.760682ms","start":"2026-03-30T17:55:55.777157Z","end":"2026-03-30T17:55:55.964918Z","steps":["trace[712458683] 'read index received' (duration: 60.032µs)","trace[712458683] 'applied index is now lower than readState.Index' (duration: 187.69891ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:55:55.964975Z","caller":"traceutil/trace.go:171","msg":"trace[161761996] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"455.1833ms","start":"2026-03-30T17:55:55.509758Z","end":"2026-03-30T17:55:55.964941Z","steps":["trace[161761996] 'process raft request' (duration: 49.379469ms)","trace[161761996] 'compare' (duration: 404.612003ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:55:55.965047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.894716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T17:55:55.96508Z","caller":"traceutil/trace.go:171","msg":"trace[1639535329] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:411; }","duration":"187.963298ms","start":"2026-03-30T17:55:55.777108Z","end":"2026-03-30T17:55:55.965071Z","steps":["trace[1639535329] 'agreement among raft nodes before linearized reading' (duration: 187.907016ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:55:55.96513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:55:55.509732Z","time spent":"455.320595ms","remote":"127.0.0.1:43994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T17:55:55.965444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.31315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-30T17:55:55.965487Z","caller":"traceutil/trace.go:171","msg":"trace[319377113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:411; }","duration":"106.394342ms","start":"2026-03-30T17:55:55.859083Z","end":"2026-03-30T17:55:55.965477Z","steps":["trace[319377113] 'agreement among raft nodes before linearized reading' (duration: 106.31971ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:56:19.580948Z","caller":"traceutil/trace.go:171","msg":"trace[1996966654] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"156.396517ms","start":"2026-03-30T17:56:19.424528Z","end":"2026-03-30T17:56:19.580924Z","steps":["trace[1996966654] 'process raft request' (duration: 156.09148ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:56:25.617215Z","caller":"traceutil/trace.go:171","msg":"trace[786065888] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"197.27926ms","start":"2026-03-30T17:56:25.419915Z","end":"2026-03-30T17:56:25.617194Z","steps":["trace[786065888] 'process raft request' (duration: 197.143927ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:56:26.679447Z","caller":"traceutil/trace.go:171","msg":"trace[647242660] linearizableReadLoop","detail":"{readStateIndex:722; appliedIndex:721; }","duration":"190.97373ms","start":"2026-03-30T17:56:26.48845Z","end":"2026-03-30T17:56:26.679424Z","steps":["trace[647242660] 'read index received' (duration: 190.801275ms)","trace[647242660] 'applied index is now lower than readState.Index' (duration: 171.575µs)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:56:26.679564Z","caller":"traceutil/trace.go:171","msg":"trace[1519014505] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"501.503447ms","start":"2026-03-30T17:56:26.178051Z","end":"2026-03-30T17:56:26.679554Z","steps":["trace[1519014505] 'process raft request' (duration: 501.246581ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:56:26.679664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:56:26.178032Z","time spent":"501.559509ms","remote":"127.0.0.1:43994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T17:56:26.679879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.41689ms","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-30T17:56:26.67994Z","caller":"traceutil/trace.go:171","msg":"trace[1677823698] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:697; }","duration":"191.511573ms","start":"2026-03-30T17:56:26.488415Z","end":"2026-03-30T17:56:26.679927Z","steps":["trace[1677823698] 'agreement among raft nodes before linearized reading' (duration: 191.41097ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:56:26.922764Z","caller":"traceutil/trace.go:171","msg":"trace[1595276054] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"239.571749ms","start":"2026-03-30T17:56:26.683153Z","end":"2026-03-30T17:56:26.922725Z","steps":["trace[1595276054] 'process raft request' (duration: 188.032958ms)","trace[1595276054] 'compare' (duration: 51.399637ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:56:26.922966Z","caller":"traceutil/trace.go:171","msg":"trace[803421817] linearizableReadLoop","detail":"{readStateIndex:725; appliedIndex:722; }","duration":"145.996726ms","start":"2026-03-30T17:56:26.776946Z","end":"2026-03-30T17:56:26.922942Z","steps":["trace[803421817] 'read index received' (duration: 94.351273ms)","trace[803421817] 'applied index is now lower than readState.Index' (duration: 51.644473ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:56:26.922983Z","caller":"traceutil/trace.go:171","msg":"trace[1760480341] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"239.414684ms","start":"2026-03-30T17:56:26.683547Z","end":"2026-03-30T17:56:26.922962Z","steps":["trace[1760480341] 'process raft request' (duration: 239.22497ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:56:26.923031Z","caller":"traceutil/trace.go:171","msg":"trace[1251762122] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"161.462548ms","start":"2026-03-30T17:56:26.761543Z","end":"2026-03-30T17:56:26.923006Z","steps":["trace[1251762122] 'process raft request' (duration: 161.355505ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:56:26.923174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.207132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T17:56:26.923265Z","caller":"traceutil/trace.go:171","msg":"trace[1335213815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:700; }","duration":"146.341815ms","start":"2026-03-30T17:56:26.776906Z","end":"2026-03-30T17:56:26.923248Z","steps":["trace[1335213815] 'agreement among raft nodes before linearized reading' (duration: 146.212052ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:56:44.826914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.118308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rw65c\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-03-30T17:56:44.826981Z","caller":"traceutil/trace.go:171","msg":"trace[1823545979] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rw65c; range_end:; response_count:1; response_revision:816; }","duration":"317.325333ms","start":"2026-03-30T17:56:44.509641Z","end":"2026-03-30T17:56:44.826966Z","steps":["trace[1823545979] 'range keys from in-memory index tree' (duration: 316.910963ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:56:44.82701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:56:44.509623Z","time spent":"317.381124ms","remote":"127.0.0.1:43900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rw65c\" "} {"level":"info","ts":"2026-03-30T17:57:14.26415Z","caller":"traceutil/trace.go:171","msg":"trace[1793895297] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"153.063325ms","start":"2026-03-30T17:57:14.111066Z","end":"2026-03-30T17:57:14.264129Z","steps":["trace[1793895297] 'process raft request' (duration: 152.946532ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:19.527601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.630103ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-30T17:57:19.527672Z","caller":"traceutil/trace.go:171","msg":"trace[515612494] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1067; }","duration":"295.540304ms","start":"2026-03-30T17:57:19.232119Z","end":"2026-03-30T17:57:19.527659Z","steps":["trace[515612494] 'read index received' (duration: 23.19484ms)","trace[515612494] 'applied index is now lower than readState.Index' (duration: 272.344504ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:57:19.527708Z","caller":"traceutil/trace.go:171","msg":"trace[1513426870] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"351.566376ms","start":"2026-03-30T17:57:19.176107Z","end":"2026-03-30T17:57:19.527673Z","steps":["trace[1513426870] 'process raft request' (duration: 237.79744ms)","trace[1513426870] 'compare' (duration: 113.420608ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:57:19.527762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.702327ms","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-30T17:57:19.527783Z","caller":"traceutil/trace.go:171","msg":"trace[960102449] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1030; }","duration":"295.760548ms","start":"2026-03-30T17:57:19.232015Z","end":"2026-03-30T17:57:19.527776Z","steps":["trace[960102449] 'agreement among raft nodes before linearized reading' (duration: 295.675116ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:19.527849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:19.176086Z","time spent":"351.693509ms","remote":"127.0.0.1:43994","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-30T17:57:19.670467Z","caller":"traceutil/trace.go:171","msg":"trace[517849875] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"138.871182ms","start":"2026-03-30T17:57:19.531568Z","end":"2026-03-30T17:57:19.670439Z","steps":["trace[517849875] 'process raft request' (duration: 120.382305ms)","trace[517849875] 'compare' (duration: 18.379564ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:57:25.432206Z","caller":"traceutil/trace.go:171","msg":"trace[1574312210] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"151.25118ms","start":"2026-03-30T17:57:25.280918Z","end":"2026-03-30T17:57:25.432169Z","steps":["trace[1574312210] 'process raft request' (duration: 151.030375ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:43.866575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.052471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-30T17:57:43.866672Z","caller":"traceutil/trace.go:171","msg":"trace[2016115290] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1108; }","duration":"104.193455ms","start":"2026-03-30T17:57:43.762459Z","end":"2026-03-30T17:57:43.866652Z","steps":["trace[2016115290] 'range keys from in-memory index tree' (duration: 103.884637ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:43.866606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.827085ms","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-30T17:57:43.866935Z","caller":"traceutil/trace.go:171","msg":"trace[1890701872] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1108; }","duration":"232.187394ms","start":"2026-03-30T17:57:43.634734Z","end":"2026-03-30T17:57:43.866921Z","steps":["trace[1890701872] 'range keys from in-memory index tree' (duration: 231.680122ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:57:44.035368Z","caller":"traceutil/trace.go:171","msg":"trace[599661665] linearizableReadLoop","detail":"{readStateIndex:1155; appliedIndex:1153; }","duration":"143.789461ms","start":"2026-03-30T17:57:43.891566Z","end":"2026-03-30T17:57:44.035355Z","steps":["trace[599661665] 'read index received' (duration: 83.896895ms)","trace[599661665] 'applied index is now lower than readState.Index' (duration: 59.891046ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:57:44.035512Z","caller":"traceutil/trace.go:171","msg":"trace[1853727345] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"164.760537ms","start":"2026-03-30T17:57:43.870722Z","end":"2026-03-30T17:57:44.035483Z","steps":["trace[1853727345] 'process raft request' (duration: 164.397448ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:44.035575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.996535ms","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-30T17:57:44.035607Z","caller":"traceutil/trace.go:171","msg":"trace[1070173450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1111; }","duration":"144.069457ms","start":"2026-03-30T17:57:43.891529Z","end":"2026-03-30T17:57:44.035598Z","steps":["trace[1070173450] 'agreement among raft nodes before linearized reading' (duration: 143.903283ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:57:44.035299Z","caller":"traceutil/trace.go:171","msg":"trace[805485471] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"164.535432ms","start":"2026-03-30T17:57:43.870664Z","end":"2026-03-30T17:57:44.035199Z","steps":["trace[805485471] 'process raft request' (duration: 104.891342ms)","trace[805485471] 'compare' (duration: 59.337863ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:57:57.842348Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8970780405486986087,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-30T17:57:58.033452Z","caller":"traceutil/trace.go:171","msg":"trace[1833636297] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"807.605402ms","start":"2026-03-30T17:57:57.225821Z","end":"2026-03-30T17:57:58.033426Z","steps":["trace[1833636297] 'process raft request' (duration: 807.475699ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:58.033588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:57.225782Z","time spent":"807.735815ms","remote":"127.0.0.1:43994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T17:57:58.033751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.447023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-30T17:57:58.033864Z","caller":"traceutil/trace.go:171","msg":"trace[1725756041] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1204; }","duration":"692.617597ms","start":"2026-03-30T17:57:57.341227Z","end":"2026-03-30T17:57:58.033844Z","steps":["trace[1725756041] 'agreement among raft nodes before linearized reading' (duration: 692.433613ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:58.033958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:57.341208Z","time spent":"692.73235ms","remote":"127.0.0.1:43848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-03-30T17:57:58.033598Z","caller":"traceutil/trace.go:171","msg":"trace[673768514] linearizableReadLoop","detail":"{readStateIndex:1252; appliedIndex:1252; }","duration":"692.29595ms","start":"2026-03-30T17:57:57.341273Z","end":"2026-03-30T17:57:58.033569Z","steps":["trace[673768514] 'read index received' (duration: 692.268759ms)","trace[673768514] 'applied index is now lower than readState.Index' (duration: 25.271µs)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:57:58.034647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.24342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-30T17:57:58.034942Z","caller":"traceutil/trace.go:171","msg":"trace[2113072955] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1205; }","duration":"378.577908ms","start":"2026-03-30T17:57:57.656343Z","end":"2026-03-30T17:57:58.034921Z","steps":["trace[2113072955] 'agreement among raft nodes before linearized reading' (duration: 378.156068ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:57:58.035096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:57:57.656328Z","time spent":"378.751934ms","remote":"127.0.0.1:43994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-30T17:57:58.034841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.810964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T17:57:58.03523Z","caller":"traceutil/trace.go:171","msg":"trace[553358792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1205; }","duration":"258.228494ms","start":"2026-03-30T17:57:57.776985Z","end":"2026-03-30T17:57:58.035213Z","steps":["trace[553358792] 'agreement among raft nodes before linearized reading' (duration: 257.815433ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:58:13.97Z","caller":"traceutil/trace.go:171","msg":"trace[343736092] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"122.843261ms","start":"2026-03-30T17:58:13.847122Z","end":"2026-03-30T17:58:13.969965Z","steps":["trace[343736092] 'process raft request' (duration: 122.612316ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:58:18.434828Z","caller":"traceutil/trace.go:171","msg":"trace[1004324471] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"439.009204ms","start":"2026-03-30T17:58:17.99579Z","end":"2026-03-30T17:58:18.434799Z","steps":["trace[1004324471] 'process raft request' (duration: 438.856861ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:18.434993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:17.995773Z","time spent":"439.140878ms","remote":"127.0.0.1:43894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6320,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T17:58:18.695264Z","caller":"traceutil/trace.go:171","msg":"trace[922140874] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"592.221485ms","start":"2026-03-30T17:58:18.102988Z","end":"2026-03-30T17:58:18.695209Z","steps":["trace[922140874] 'process raft request' (duration: 591.942587ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:18.695721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:18.102964Z","time spent":"592.42924ms","remote":"127.0.0.1:43994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-30T17:58:18.821699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.53653ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-30T17:58:18.821915Z","caller":"traceutil/trace.go:171","msg":"trace[1327462012] linearizableReadLoop","detail":"{readStateIndex:1364; appliedIndex:1362; }","duration":"712.144305ms","start":"2026-03-30T17:58:18.109752Z","end":"2026-03-30T17:58:18.821896Z","steps":["trace[1327462012] 'read index received' (duration: 324.856433ms)","trace[1327462012] 'applied index is now lower than readState.Index' (duration: 387.286152ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:58:18.822134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.424537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-30T17:58:18.822208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.428761ms","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-30T17:58:18.822261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.99819ms","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-30T17:58:18.822311Z","caller":"traceutil/trace.go:171","msg":"trace[176001254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1312; }","duration":"712.581885ms","start":"2026-03-30T17:58:18.10971Z","end":"2026-03-30T17:58:18.822292Z","steps":["trace[176001254] 'agreement among raft nodes before linearized reading' (duration: 712.306629ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:58:18.822344Z","caller":"traceutil/trace.go:171","msg":"trace[232873442] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1312; }","duration":"587.134613ms","start":"2026-03-30T17:58:18.235192Z","end":"2026-03-30T17:58:18.822326Z","steps":["trace[232873442] 'agreement among raft nodes before linearized reading' (duration: 586.887937ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:18.822358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:18.109692Z","time spent":"712.657486ms","remote":"127.0.0.1:43994","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-30T17:58:18.822398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:18.235166Z","time spent":"587.217945ms","remote":"127.0.0.1:43994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-30T17:58:18.822283Z","caller":"traceutil/trace.go:171","msg":"trace[5979044] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1312; }","duration":"238.56074ms","start":"2026-03-30T17:58:18.583652Z","end":"2026-03-30T17:58:18.822212Z","steps":["trace[5979044] 'agreement among raft nodes before linearized reading' (duration: 238.412597ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:18.822505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.418003ms","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-30T17:58:18.822173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.083694ms","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-30T17:58:18.822695Z","caller":"traceutil/trace.go:171","msg":"trace[1565184614] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1312; }","duration":"702.631547ms","start":"2026-03-30T17:58:18.120035Z","end":"2026-03-30T17:58:18.822667Z","steps":["trace[1565184614] 'agreement among raft nodes before linearized reading' (duration: 702.005232ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:18.822761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:18.120018Z","time spent":"702.724739ms","remote":"127.0.0.1:43994","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-30T17:58:18.82251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.06571ms","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-30T17:58:18.82285Z","caller":"traceutil/trace.go:171","msg":"trace[1923590375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1312; }","duration":"537.49623ms","start":"2026-03-30T17:58:18.285341Z","end":"2026-03-30T17:58:18.822837Z","steps":["trace[1923590375] 'agreement among raft nodes before linearized reading' (duration: 536.874845ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:58:18.822599Z","caller":"traceutil/trace.go:171","msg":"trace[2005263605] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1312; }","duration":"588.546726ms","start":"2026-03-30T17:58:18.234039Z","end":"2026-03-30T17:58:18.822586Z","steps":["trace[2005263605] 'agreement among raft nodes before linearized reading' (duration: 588.159797ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:18.822915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:18.28532Z","time spent":"537.582422ms","remote":"127.0.0.1:43994","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-30T17:58:18.822922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:18.23402Z","time spent":"588.887995ms","remote":"127.0.0.1:43994","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-30T17:58:19.372286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.680275ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-30T17:58:19.372438Z","caller":"traceutil/trace.go:171","msg":"trace[1938967870] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"245.506616ms","start":"2026-03-30T17:58:19.12691Z","end":"2026-03-30T17:58:19.372417Z","steps":["trace[1938967870] 'process raft request' (duration: 28.608496ms)","trace[1938967870] 'compare' (duration: 216.545362ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:58:29.552851Z","caller":"traceutil/trace.go:171","msg":"trace[1550990705] linearizableReadLoop","detail":"{readStateIndex:1436; appliedIndex:1435; }","duration":"166.273693ms","start":"2026-03-30T17:58:29.386547Z","end":"2026-03-30T17:58:29.552821Z","steps":["trace[1550990705] 'read index received' (duration: 165.922255ms)","trace[1550990705] 'applied index is now lower than readState.Index' (duration: 350.408µs)"],"step_count":2} {"level":"info","ts":"2026-03-30T17:58:29.55296Z","caller":"traceutil/trace.go:171","msg":"trace[586784712] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"254.022101ms","start":"2026-03-30T17:58:29.298905Z","end":"2026-03-30T17:58:29.552927Z","steps":["trace[586784712] 'process raft request' (duration: 253.755634ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T17:58:29.553111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.54668ms","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":"info","ts":"2026-03-30T17:58:29.553159Z","caller":"traceutil/trace.go:171","msg":"trace[289656060] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1381; }","duration":"166.648893ms","start":"2026-03-30T17:58:29.386498Z","end":"2026-03-30T17:58:29.553147Z","steps":["trace[289656060] 'agreement among raft nodes before linearized reading' (duration: 166.442438ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:58:29.979259Z","caller":"traceutil/trace.go:171","msg":"trace[1818445707] linearizableReadLoop","detail":"{readStateIndex:1437; appliedIndex:1436; }","duration":"214.44214ms","start":"2026-03-30T17:58:29.764793Z","end":"2026-03-30T17:58:29.979235Z","steps":["trace[1818445707] 'read index received' (duration: 191.221782ms)","trace[1818445707] 'applied index is now lower than readState.Index' (duration: 23.219198ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:58:29.979391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.576384ms","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-30T17:58:29.979431Z","caller":"traceutil/trace.go:171","msg":"trace[2052952801] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1382; }","duration":"214.700787ms","start":"2026-03-30T17:58:29.764719Z","end":"2026-03-30T17:58:29.97942Z","steps":["trace[2052952801] 'agreement among raft nodes before linearized reading' (duration: 214.613035ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T17:58:29.979414Z","caller":"traceutil/trace.go:171","msg":"trace[1166545647] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"422.025657ms","start":"2026-03-30T17:58:29.557352Z","end":"2026-03-30T17:58:29.979378Z","steps":["trace[1166545647] 'process raft request' (duration: 398.627384ms)","trace[1166545647] 'compare' (duration: 23.145157ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T17:58:29.979564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-30T17:58:29.557333Z","time spent":"422.142549ms","remote":"127.0.0.1:43994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-30T18:01:20.539128Z","caller":"traceutil/trace.go:171","msg":"trace[518302091] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"133.607444ms","start":"2026-03-30T18:01:20.405504Z","end":"2026-03-30T18:01:20.539111Z","steps":["trace[518302091] 'process raft request' (duration: 133.487381ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T18:01:35.802057Z","caller":"traceutil/trace.go:171","msg":"trace[1056896405] transaction","detail":"{read_only:false; response_revision:2259; number_of_response:1; }","duration":"148.394491ms","start":"2026-03-30T18:01:35.653631Z","end":"2026-03-30T18:01:35.802026Z","steps":["trace[1056896405] 'process raft request' (duration: 53.777275ms)","trace[1056896405] 'compare' (duration: 94.490333ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T18:02:05.684097Z","caller":"traceutil/trace.go:171","msg":"trace[490857873] transaction","detail":"{read_only:false; response_revision:2409; number_of_response:1; }","duration":"253.597317ms","start":"2026-03-30T18:02:05.430464Z","end":"2026-03-30T18:02:05.684061Z","steps":["trace[490857873] 'process raft request' (duration: 186.010667ms)","trace[490857873] 'compare' (duration: 67.305314ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T18:02:05.68408Z","caller":"traceutil/trace.go:171","msg":"trace[1008262568] linearizableReadLoop","detail":"{readStateIndex:2509; appliedIndex:2508; }","duration":"242.389342ms","start":"2026-03-30T18:02:05.441651Z","end":"2026-03-30T18:02:05.684041Z","steps":["trace[1008262568] 'read index received' (duration: 174.764551ms)","trace[1008262568] 'applied index is now lower than readState.Index' (duration: 67.623361ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T18:02:05.684283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.494935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-dp4d7\" ","response":"range_response_count:1 size:4482"} {"level":"info","ts":"2026-03-30T18:02:05.684319Z","caller":"traceutil/trace.go:171","msg":"trace[1953956928] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-dp4d7; range_end:; response_count:1; response_revision:2409; }","duration":"199.583797ms","start":"2026-03-30T18:02:05.484724Z","end":"2026-03-30T18:02:05.684308Z","steps":["trace[1953956928] 'agreement among raft nodes before linearized reading' (duration: 199.492625ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T18:02:05.685007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.324582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:133 size:102573"} {"level":"info","ts":"2026-03-30T18:02:05.685124Z","caller":"traceutil/trace.go:171","msg":"trace[1689118975] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:133; response_revision:2409; }","duration":"243.475137ms","start":"2026-03-30T18:02:05.441603Z","end":"2026-03-30T18:02:05.685078Z","steps":["trace[1689118975] 'agreement among raft nodes before linearized reading' (duration: 242.536165ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T18:02:05.794456Z","caller":"traceutil/trace.go:171","msg":"trace[1218432357] transaction","detail":"{read_only:false; response_revision:2410; number_of_response:1; }","duration":"104.864599ms","start":"2026-03-30T18:02:05.68957Z","end":"2026-03-30T18:02:05.794435Z","steps":["trace[1218432357] 'process raft request' (duration: 102.379792ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T18:02:06.066532Z","caller":"traceutil/trace.go:171","msg":"trace[1413319039] linearizableReadLoop","detail":"{readStateIndex:2511; appliedIndex:2510; }","duration":"182.838476ms","start":"2026-03-30T18:02:05.883664Z","end":"2026-03-30T18:02:06.066502Z","steps":["trace[1413319039] 'read index received' (duration: 166.570286ms)","trace[1413319039] 'applied index is now lower than readState.Index' (duration: 16.26717ms)"],"step_count":2} {"level":"warn","ts":"2026-03-30T18:02:06.066722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.03327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4663"} {"level":"info","ts":"2026-03-30T18:02:06.066684Z","caller":"traceutil/trace.go:171","msg":"trace[8338670] transaction","detail":"{read_only:false; response_revision:2411; number_of_response:1; }","duration":"198.253916ms","start":"2026-03-30T18:02:05.868391Z","end":"2026-03-30T18:02:06.066645Z","steps":["trace[8338670] 'process raft request' (duration: 182.034387ms)","trace[8338670] 'compare' (duration: 15.773919ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T18:02:06.066795Z","caller":"traceutil/trace.go:171","msg":"trace[598156304] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:2411; }","duration":"183.138912ms","start":"2026-03-30T18:02:05.883645Z","end":"2026-03-30T18:02:06.066784Z","steps":["trace[598156304] 'agreement among raft nodes before linearized reading' (duration: 183.00738ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T18:02:06.066953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.853447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4134"} {"level":"info","ts":"2026-03-30T18:02:06.067049Z","caller":"traceutil/trace.go:171","msg":"trace[223147754] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:2411; }","duration":"179.99473ms","start":"2026-03-30T18:02:05.887031Z","end":"2026-03-30T18:02:06.067026Z","steps":["trace[223147754] 'agreement among raft nodes before linearized reading' (duration: 179.824006ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T18:02:06.067302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.691833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-30T18:02:06.067346Z","caller":"traceutil/trace.go:171","msg":"trace[1914857139] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2411; }","duration":"110.775214ms","start":"2026-03-30T18:02:05.956558Z","end":"2026-03-30T18:02:06.067333Z","steps":["trace[1914857139] 'agreement among raft nodes before linearized reading' (duration: 110.637571ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T18:02:06.067396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.120261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-30T18:02:06.067454Z","caller":"traceutil/trace.go:171","msg":"trace[1178956254] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2411; }","duration":"125.216263ms","start":"2026-03-30T18:02:05.942222Z","end":"2026-03-30T18:02:06.067438Z","steps":["trace[1178956254] 'agreement among raft nodes before linearized reading' (duration: 124.999058ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T18:02:06.067519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.606259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-30T18:02:06.067573Z","caller":"traceutil/trace.go:171","msg":"trace[773305768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2411; }","duration":"106.804503ms","start":"2026-03-30T18:02:05.960753Z","end":"2026-03-30T18:02:06.067557Z","steps":["trace[773305768] 'agreement among raft nodes before linearized reading' (duration: 106.539917ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T18:02:06.067815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.690738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-30T18:02:06.06818Z","caller":"traceutil/trace.go:171","msg":"trace[404700585] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2411; }","duration":"153.063636ms","start":"2026-03-30T18:02:05.915097Z","end":"2026-03-30T18:02:06.06816Z","steps":["trace[404700585] 'agreement among raft nodes before linearized reading' (duration: 152.621486ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T18:02:11.970456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.47291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-30T18:02:11.970554Z","caller":"traceutil/trace.go:171","msg":"trace[762031780] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2437; }","duration":"104.604283ms","start":"2026-03-30T18:02:11.865932Z","end":"2026-03-30T18:02:11.970536Z","steps":["trace[762031780] 'range keys from in-memory index tree' (duration: 104.431919ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T18:02:11.970575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.537762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pvc-protection-controller\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-03-30T18:02:11.970638Z","caller":"traceutil/trace.go:171","msg":"trace[1271874492] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pvc-protection-controller; range_end:; response_count:1; response_revision:2437; }","duration":"115.637803ms","start":"2026-03-30T18:02:11.854983Z","end":"2026-03-30T18:02:11.970621Z","steps":["trace[1271874492] 'range keys from in-memory index tree' (duration: 115.438019ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T18:02:12.078662Z","caller":"traceutil/trace.go:171","msg":"trace[1224809921] linearizableReadLoop","detail":"{readStateIndex:2539; appliedIndex:2538; }","duration":"102.757171ms","start":"2026-03-30T18:02:11.975891Z","end":"2026-03-30T18:02:12.078648Z","steps":["trace[1224809921] 'read index received' (duration: 102.634838ms)","trace[1224809921] 'applied index is now lower than readState.Index' (duration: 121.693µs)"],"step_count":2} {"level":"info","ts":"2026-03-30T18:02:12.078731Z","caller":"traceutil/trace.go:171","msg":"trace[1597849554] transaction","detail":"{read_only:false; response_revision:2438; number_of_response:1; }","duration":"103.067128ms","start":"2026-03-30T18:02:11.975621Z","end":"2026-03-30T18:02:12.078689Z","steps":["trace[1597849554] 'process raft request' (duration: 102.928845ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T18:02:12.078842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.915105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-03-30T18:02:12.078937Z","caller":"traceutil/trace.go:171","msg":"trace[49668957] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pv-protection-controller; range_end:; response_count:1; response_revision:2438; }","duration":"103.076659ms","start":"2026-03-30T18:02:11.975836Z","end":"2026-03-30T18:02:12.078913Z","steps":["trace[49668957] 'agreement among raft nodes before linearized reading' (duration: 102.882224ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T18:02:12.372838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.986129ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-30T18:02:12.373057Z","caller":"traceutil/trace.go:171","msg":"trace[517657525] transaction","detail":"{read_only:false; response_revision:2441; number_of_response:1; }","duration":"268.390185ms","start":"2026-03-30T18:02:12.104649Z","end":"2026-03-30T18:02:12.373039Z","steps":["trace[517657525] 'process raft request' (duration: 268.349914ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T18:02:12.373064Z","caller":"traceutil/trace.go:171","msg":"trace[992690705] linearizableReadLoop","detail":"{readStateIndex:2540; appliedIndex:2539; }","duration":"270.865431ms","start":"2026-03-30T18:02:12.102176Z","end":"2026-03-30T18:02:12.373041Z","steps":["trace[992690705] 'read index received' (duration: 105.568665ms)","trace[992690705] 'applied index is now lower than readState.Index' (duration: 165.295556ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T18:02:12.373172Z","caller":"traceutil/trace.go:171","msg":"trace[2032885103] transaction","detail":"{read_only:false; response_revision:2439; number_of_response:1; }","duration":"271.447724ms","start":"2026-03-30T18:02:12.101713Z","end":"2026-03-30T18:02:12.37316Z","steps":["trace[2032885103] 'process raft request' (duration: 106.058787ms)","trace[2032885103] 'compare' (duration: 164.865075ms)"],"step_count":2} {"level":"info","ts":"2026-03-30T18:02:12.373415Z","caller":"traceutil/trace.go:171","msg":"trace[117585769] transaction","detail":"{read_only:false; response_revision:2440; number_of_response:1; }","duration":"269.897109ms","start":"2026-03-30T18:02:12.103506Z","end":"2026-03-30T18:02:12.373403Z","steps":["trace[117585769] 'process raft request' (duration: 269.441648ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T18:02:12.373424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.230919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-30T18:02:12.373495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.673927ms","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-30T18:02:12.373525Z","caller":"traceutil/trace.go:171","msg":"trace[1949686685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2441; }","duration":"270.708017ms","start":"2026-03-30T18:02:12.102807Z","end":"2026-03-30T18:02:12.373515Z","steps":["trace[1949686685] 'agreement among raft nodes before linearized reading' (duration: 270.642316ms)"],"step_count":1} {"level":"info","ts":"2026-03-30T18:02:12.373593Z","caller":"traceutil/trace.go:171","msg":"trace[171458997] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2441; }","duration":"271.380152ms","start":"2026-03-30T18:02:12.102137Z","end":"2026-03-30T18:02:12.373517Z","steps":["trace[171458997] 'agreement among raft nodes before linearized reading' (duration: 271.140377ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T18:02:12.374126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.852383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:133 size:102573"} {"level":"info","ts":"2026-03-30T18:02:12.374195Z","caller":"traceutil/trace.go:171","msg":"trace[1513548631] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:133; response_revision:2441; }","duration":"260.941984ms","start":"2026-03-30T18:02:12.113235Z","end":"2026-03-30T18:02:12.374177Z","steps":["trace[1513548631] 'agreement among raft nodes before linearized reading' (duration: 260.270889ms)"],"step_count":1} {"level":"warn","ts":"2026-03-30T18:02:12.374473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.396053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:133 size:102573"} {"level":"info","ts":"2026-03-30T18:02:12.374534Z","caller":"traceutil/trace.go:171","msg":"trace[810580014] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:133; response_revision:2441; }","duration":"257.497826ms","start":"2026-03-30T18:02:12.117023Z","end":"2026-03-30T18:02:12.37452Z","steps":["trace[810580014] 'agreement among raft nodes before linearized reading' (duration: 256.429182ms)"],"step_count":1}