{"level":"warn","ts":"2026-04-08T05:01:46.792321Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-08T05:01:46.792406Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.6:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.6:2380","--initial-cluster=instance=https://199.204.45.6:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.6:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.6: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-04-08T05:01:46.792466Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-08T05:01:46.792473Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.6:2380"]} {"level":"info","ts":"2026-04-08T05:01:46.79249Z","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-04-08T05:01:46.793084Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.6:2379"]} {"level":"info","ts":"2026-04-08T05:01:46.793151Z","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.204.45.6:2380"],"listen-peer-urls":["https://199.204.45.6:2380"],"advertise-client-urls":["https://199.204.45.6:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.6:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.6: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-04-08T05:01:46.796714Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.370777ms"} {"level":"info","ts":"2026-04-08T05:01:46.805401Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4ec288cbdba84d36","cluster-id":"9b45faacd38a820f"} {"level":"info","ts":"2026-04-08T05:01:46.805473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T05:01:46.805492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became follower at term 0"} {"level":"info","ts":"2026-04-08T05:01:46.805502Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4ec288cbdba84d36 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T05:01:46.80551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became follower at term 1"} {"level":"info","ts":"2026-04-08T05:01:46.805545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 switched to configuration voters=(5675248889585225014)"} {"level":"warn","ts":"2026-04-08T05:01:46.81192Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T05:01:46.813172Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T05:01:46.814445Z","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-04-08T05:01:46.816441Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4ec288cbdba84d36","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T05:01:46.816533Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4ec288cbdba84d36","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T05:01:46.81729Z","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-04-08T05:01:46.817386Z","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-04-08T05:01:46.817424Z","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-04-08T05:01:46.818006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 switched to configuration voters=(5675248889585225014)"} {"level":"info","ts":"2026-04-08T05:01:46.818158Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9b45faacd38a820f","local-member-id":"4ec288cbdba84d36","added-peer-id":"4ec288cbdba84d36","added-peer-peer-urls":["https://199.204.45.6:2380"]} {"level":"info","ts":"2026-04-08T05:01:46.823836Z","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-04-08T05:01:46.823977Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.6:2380"} {"level":"info","ts":"2026-04-08T05:01:46.824038Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.6:2380"} {"level":"info","ts":"2026-04-08T05:01:46.824123Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4ec288cbdba84d36","initial-advertise-peer-urls":["https://199.204.45.6:2380"],"listen-peer-urls":["https://199.204.45.6:2380"],"advertise-client-urls":["https://199.204.45.6:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.6:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T05:01:46.824174Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T05:01:47.006156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T05:01:47.006219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T05:01:47.00627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 received MsgPreVoteResp from 4ec288cbdba84d36 at term 1"} {"level":"info","ts":"2026-04-08T05:01:47.006292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became candidate at term 2"} {"level":"info","ts":"2026-04-08T05:01:47.006305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 received MsgVoteResp from 4ec288cbdba84d36 at term 2"} {"level":"info","ts":"2026-04-08T05:01:47.006333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became leader at term 2"} {"level":"info","ts":"2026-04-08T05:01:47.006354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4ec288cbdba84d36 elected leader 4ec288cbdba84d36 at term 2"} {"level":"info","ts":"2026-04-08T05:01:47.008085Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T05:01:47.009011Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4ec288cbdba84d36","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.6:2379]}","request-path":"/0/members/4ec288cbdba84d36/attributes","cluster-id":"9b45faacd38a820f","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T05:01:47.009159Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T05:01:47.009268Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T05:01:47.00963Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T05:01:47.009652Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T05:01:47.00979Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9b45faacd38a820f","local-member-id":"4ec288cbdba84d36","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T05:01:47.009863Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T05:01:47.009882Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T05:01:47.013607Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.6:2379"} {"level":"info","ts":"2026-04-08T05:01:47.014711Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-08T05:01:52.804294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14020132s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T05:01:52.804409Z","caller":"traceutil/trace.go:171","msg":"trace[722713310] linearizableReadLoop","detail":"{readStateIndex:286; appliedIndex:285; }","duration":"895.080002ms","start":"2026-04-08T05:01:51.909315Z","end":"2026-04-08T05:01:52.804395Z","steps":["trace[722713310] 'read index received' (duration: 32.781µs)","trace[722713310] 'applied index is now lower than readState.Index' (duration: 895.046001ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T05:01:52.804464Z","caller":"traceutil/trace.go:171","msg":"trace[1170862117] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"1.176954614s","start":"2026-04-08T05:01:51.627453Z","end":"2026-04-08T05:01:52.804408Z","steps":["trace[1170862117] 'process raft request' (duration: 36.087637ms)","trace[1170862117] 'compare' (duration: 1.139987865s)"],"step_count":2} {"level":"warn","ts":"2026-04-08T05:01:52.804543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.226905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T05:01:52.804943Z","caller":"traceutil/trace.go:171","msg":"trace[104042546] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:280; }","duration":"895.679067ms","start":"2026-04-08T05:01:51.909247Z","end":"2026-04-08T05:01:52.804926Z","steps":["trace[104042546] 'agreement among raft nodes before linearized reading' (duration: 895.218394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:01:52.805114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:01:51.909228Z","time spent":"895.868282ms","remote":"127.0.0.1:34372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" "} {"level":"warn","ts":"2026-04-08T05:01:52.804853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:01:51.627435Z","time spent":"1.177296422s","remote":"127.0.0.1:34158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T05:01:53.015103Z","caller":"traceutil/trace.go:171","msg":"trace[1522793684] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"889.941138ms","start":"2026-04-08T05:01:52.125128Z","end":"2026-04-08T05:01:53.015069Z","steps":["trace[1522793684] 'process raft request' (duration: 836.887485ms)","trace[1522793684] 'compare' (duration: 52.5658ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T05:01:53.015457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:01:52.125109Z","time spent":"890.077742ms","remote":"127.0.0.1:34034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T05:01:53.113043Z","caller":"traceutil/trace.go:171","msg":"trace[514505465] transaction","detail":"{read_only:false; number_of_response:0; response_revision:282; }","duration":"873.820524ms","start":"2026-04-08T05:01:52.239189Z","end":"2026-04-08T05:01:53.113009Z","steps":["trace[514505465] 'process raft request' (duration: 873.643569ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:01:53.113224Z","caller":"traceutil/trace.go:171","msg":"trace[1669860174] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"873.967858ms","start":"2026-04-08T05:01:52.239238Z","end":"2026-04-08T05:01:53.113206Z","steps":["trace[1669860174] 'process raft request' (duration: 873.619178ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:01:53.113335Z","caller":"traceutil/trace.go:171","msg":"trace[1688167310] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"875.930128ms","start":"2026-04-08T05:01:52.237389Z","end":"2026-04-08T05:01:53.113319Z","steps":["trace[1688167310] 'process raft request' (duration: 875.378254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:01:53.113422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:01:52.239234Z","time spent":"874.104741ms","remote":"127.0.0.1:34126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T05:01:53.113469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:01:52.237371Z","time spent":"876.02196ms","remote":"127.0.0.1:34126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3953,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T05:01:53.11365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:01:52.239182Z","time spent":"874.060561ms","remote":"127.0.0.1:34126","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T05:01:53.113692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.671898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T05:01:53.114814Z","caller":"traceutil/trace.go:171","msg":"trace[360833511] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:284; }","duration":"389.833387ms","start":"2026-04-08T05:01:52.724962Z","end":"2026-04-08T05:01:53.114795Z","steps":["trace[360833511] 'agreement among raft nodes before linearized reading' (duration: 388.607476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:01:53.114899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:01:52.72494Z","time spent":"389.943651ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-08T05:01:53.113658Z","caller":"traceutil/trace.go:171","msg":"trace[1023143767] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"873.493336ms","start":"2026-04-08T05:01:52.24015Z","end":"2026-04-08T05:01:53.113643Z","steps":["trace[1023143767] 'process raft request' (duration: 872.762818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:01:53.115105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:01:52.240142Z","time spent":"874.916953ms","remote":"127.0.0.1:34126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T05:01:53.113777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"984.925559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T05:01:53.115307Z","caller":"traceutil/trace.go:171","msg":"trace[788831296] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:284; }","duration":"986.458199ms","start":"2026-04-08T05:01:52.128823Z","end":"2026-04-08T05:01:53.115282Z","steps":["trace[788831296] 'agreement among raft nodes before linearized reading' (duration: 984.901979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:01:53.115364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:01:52.128816Z","time spent":"986.532521ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-04-08T05:01:53.113045Z","caller":"traceutil/trace.go:171","msg":"trace[1811833948] transaction","detail":"{read_only:false; number_of_response:0; response_revision:283; }","duration":"873.688471ms","start":"2026-04-08T05:01:52.239303Z","end":"2026-04-08T05:01:53.112992Z","steps":["trace[1811833948] 'process raft request' (duration: 873.588267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:01:53.115499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:01:52.239298Z","time spent":"876.165634ms","remote":"127.0.0.1:34126","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T05:01:53.113096Z","caller":"traceutil/trace.go:171","msg":"trace[1044924250] transaction","detail":"{read_only:false; number_of_response:0; response_revision:281; }","duration":"889.136408ms","start":"2026-04-08T05:01:52.223914Z","end":"2026-04-08T05:01:53.113051Z","steps":["trace[1044924250] 'process raft request' (duration: 888.728657ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:01:53.112972Z","caller":"traceutil/trace.go:171","msg":"trace[2109728638] linearizableReadLoop","detail":"{readStateIndex:293; appliedIndex:286; }","duration":"308.506417ms","start":"2026-04-08T05:01:52.804442Z","end":"2026-04-08T05:01:53.112948Z","steps":["trace[2109728638] 'read index received' (duration: 157.63681ms)","trace[2109728638] 'applied index is now lower than readState.Index' (duration: 150.868727ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T05:01:53.115672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:01:52.223886Z","time spent":"891.757165ms","remote":"127.0.0.1:34116","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T05:01:53.114159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.517137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-08T05:01:53.115772Z","caller":"traceutil/trace.go:171","msg":"trace[1429318130] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:285; }","duration":"991.16591ms","start":"2026-04-08T05:01:52.124599Z","end":"2026-04-08T05:01:53.115765Z","steps":["trace[1429318130] 'agreement among raft nodes before linearized reading' (duration: 989.422635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:01:53.115793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:01:52.124581Z","time spent":"991.206141ms","remote":"127.0.0.1:34336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-08T05:01:53.121519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.545274ms","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-04-08T05:01:53.121561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.807928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T05:01:53.121569Z","caller":"traceutil/trace.go:171","msg":"trace[128980497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:285; }","duration":"309.620725ms","start":"2026-04-08T05:01:52.811939Z","end":"2026-04-08T05:01:53.121559Z","steps":["trace[128980497] 'agreement among raft nodes before linearized reading' (duration: 303.898159ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:01:53.121592Z","caller":"traceutil/trace.go:171","msg":"trace[807089180] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:0; response_revision:285; }","duration":"312.87866ms","start":"2026-04-08T05:01:52.808702Z","end":"2026-04-08T05:01:53.121581Z","steps":["trace[807089180] 'agreement among raft nodes before linearized reading' (duration: 307.093852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:01:53.121756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:01:52.808687Z","time spent":"313.055224ms","remote":"127.0.0.1:34158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-04-08T05:01:53.121596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:01:52.811929Z","time spent":"309.661447ms","remote":"127.0.0.1:34212","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-04-08T05:01:53.121524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.021525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T05:01:53.122152Z","caller":"traceutil/trace.go:171","msg":"trace[747525758] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:0; response_revision:285; }","duration":"308.677341ms","start":"2026-04-08T05:01:52.813461Z","end":"2026-04-08T05:01:53.122138Z","steps":["trace[747525758] 'agreement among raft nodes before linearized reading' (duration: 302.361499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:01:53.122204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:01:52.813449Z","time spent":"308.741964ms","remote":"127.0.0.1:34056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/configmaps/kube-system/coredns\" "} {"level":"warn","ts":"2026-04-08T05:01:53.121622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.501483ms","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-04-08T05:01:53.122353Z","caller":"traceutil/trace.go:171","msg":"trace[1616235969] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:285; }","duration":"310.229371ms","start":"2026-04-08T05:01:52.812102Z","end":"2026-04-08T05:01:53.122331Z","steps":["trace[1616235969] 'agreement among raft nodes before linearized reading' (duration: 303.727944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:01:53.122393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:01:52.812096Z","time spent":"310.288512ms","remote":"127.0.0.1:34212","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-04-08T05:02:32.900755Z","caller":"traceutil/trace.go:171","msg":"trace[1006350951] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"443.716314ms","start":"2026-04-08T05:02:32.457002Z","end":"2026-04-08T05:02:32.900718Z","steps":["trace[1006350951] 'process raft request' (duration: 442.666947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:02:32.901215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:02:32.456967Z","time spent":"443.905719ms","remote":"127.0.0.1:34002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T05:02:32.904622Z","caller":"traceutil/trace.go:171","msg":"trace[1474407493] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:667; }","duration":"284.675089ms","start":"2026-04-08T05:02:32.61973Z","end":"2026-04-08T05:02:32.904405Z","steps":["trace[1474407493] 'read index received' (duration: 278.806917ms)","trace[1474407493] 'applied index is now lower than readState.Index' (duration: 5.867282ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T05:02:32.907203Z","caller":"traceutil/trace.go:171","msg":"trace[2035013522] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"414.795508ms","start":"2026-04-08T05:02:32.489682Z","end":"2026-04-08T05:02:32.904477Z","steps":["trace[2035013522] 'process raft request' (duration: 414.510461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:02:32.90745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:02:32.489656Z","time spent":"417.684712ms","remote":"127.0.0.1:34002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T05:02:32.911267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.508935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T05:02:32.911324Z","caller":"traceutil/trace.go:171","msg":"trace[234950259] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:645; }","duration":"291.591157ms","start":"2026-04-08T05:02:32.619713Z","end":"2026-04-08T05:02:32.911304Z","steps":["trace[234950259] 'agreement among raft nodes before linearized reading' (duration: 291.410992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:02:39.44699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.136644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-59dst\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-04-08T05:02:39.447109Z","caller":"traceutil/trace.go:171","msg":"trace[1107280920] range","detail":"{range_begin:/registry/pods/kube-system/cilium-59dst; range_end:; response_count:1; response_revision:709; }","duration":"179.315189ms","start":"2026-04-08T05:02:39.267768Z","end":"2026-04-08T05:02:39.447083Z","steps":["trace[1107280920] 'range keys from in-memory index tree' (duration: 178.879348ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:02:39.739549Z","caller":"traceutil/trace.go:171","msg":"trace[806066510] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"277.243527ms","start":"2026-04-08T05:02:39.462243Z","end":"2026-04-08T05:02:39.739486Z","steps":["trace[806066510] 'process raft request' (duration: 277.171885ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:02:39.739639Z","caller":"traceutil/trace.go:171","msg":"trace[1102496618] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"287.339487ms","start":"2026-04-08T05:02:39.452262Z","end":"2026-04-08T05:02:39.739601Z","steps":["trace[1102496618] 'process raft request' (duration: 287.07249ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:02:39.73978Z","caller":"traceutil/trace.go:171","msg":"trace[438048429] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"287.541372ms","start":"2026-04-08T05:02:39.452189Z","end":"2026-04-08T05:02:39.73973Z","steps":["trace[438048429] 'process raft request' (duration: 286.206167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:02:40.081322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.566549ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T05:02:40.081615Z","caller":"traceutil/trace.go:171","msg":"trace[1092569655] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"232.337477ms","start":"2026-04-08T05:02:39.849253Z","end":"2026-04-08T05:02:40.08159Z","steps":["trace[1092569655] 'process raft request' (duration: 232.226943ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:02:40.081825Z","caller":"traceutil/trace.go:171","msg":"trace[1630737746] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"534.776435ms","start":"2026-04-08T05:02:39.546997Z","end":"2026-04-08T05:02:40.081774Z","steps":["trace[1630737746] 'process raft request' (duration: 381.655162ms)","trace[1630737746] 'compare' (duration: 152.423555ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T05:02:40.081837Z","caller":"traceutil/trace.go:171","msg":"trace[2114078171] linearizableReadLoop","detail":"{readStateIndex:740; appliedIndex:736; }","duration":"401.970966ms","start":"2026-04-08T05:02:39.679821Z","end":"2026-04-08T05:02:40.081792Z","steps":["trace[2114078171] 'read index received' (duration: 58.294154ms)","trace[2114078171] 'applied index is now lower than readState.Index' (duration: 343.639611ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T05:02:40.082104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:02:39.546965Z","time spent":"534.97634ms","remote":"127.0.0.1:34212","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-04-08T05:02:40.082123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.282734ms","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-04-08T05:02:40.082184Z","caller":"traceutil/trace.go:171","msg":"trace[1450094216] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:715; }","duration":"402.416337ms","start":"2026-04-08T05:02:39.679752Z","end":"2026-04-08T05:02:40.082169Z","steps":["trace[1450094216] 'agreement among raft nodes before linearized reading' (duration: 402.158991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:02:40.082235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:02:39.679719Z","time spent":"402.50563ms","remote":"127.0.0.1:34212","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-04-08T05:02:40.735001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:02:40.181693Z","time spent":"553.295803ms","remote":"127.0.0.1:33974","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-08T05:02:41.399328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.559004ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T05:02:41.399471Z","caller":"traceutil/trace.go:171","msg":"trace[1120998431] linearizableReadLoop","detail":"{readStateIndex:744; appliedIndex:743; }","duration":"258.087572ms","start":"2026-04-08T05:02:41.141363Z","end":"2026-04-08T05:02:41.399451Z","steps":["trace[1120998431] 'read index received' (duration: 142.233792ms)","trace[1120998431] 'applied index is now lower than readState.Index' (duration: 115.85183ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T05:02:41.3995Z","caller":"traceutil/trace.go:171","msg":"trace[1732062273] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"661.70058ms","start":"2026-04-08T05:02:40.737755Z","end":"2026-04-08T05:02:41.399455Z","steps":["trace[1732062273] 'process raft request' (duration: 545.913932ms)","trace[1732062273] 'compare' (duration: 115.07896ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T05:02:41.399603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.233287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T05:02:41.399655Z","caller":"traceutil/trace.go:171","msg":"trace[1413927973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:717; }","duration":"258.327049ms","start":"2026-04-08T05:02:41.141315Z","end":"2026-04-08T05:02:41.399642Z","steps":["trace[1413927973] 'agreement among raft nodes before linearized reading' (duration: 258.185526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:02:41.399735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:02:40.737689Z","time spent":"661.896646ms","remote":"127.0.0.1:33974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":116,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T05:02:49.925316Z","caller":"traceutil/trace.go:171","msg":"trace[83482601] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"110.42065ms","start":"2026-04-08T05:02:49.814658Z","end":"2026-04-08T05:02:49.925079Z","steps":["trace[83482601] 'process raft request' (duration: 108.253184ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:02:58.046251Z","caller":"traceutil/trace.go:171","msg":"trace[216538608] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"663.300243ms","start":"2026-04-08T05:02:57.382922Z","end":"2026-04-08T05:02:58.046222Z","steps":["trace[216538608] 'process raft request' (duration: 663.136329ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:02:58.046282Z","caller":"traceutil/trace.go:171","msg":"trace[985219201] linearizableReadLoop","detail":"{readStateIndex:863; appliedIndex:863; }","duration":"391.013844ms","start":"2026-04-08T05:02:57.655241Z","end":"2026-04-08T05:02:58.046255Z","steps":["trace[985219201] 'read index received' (duration: 391.005074ms)","trace[985219201] 'applied index is now lower than readState.Index' (duration: 7.2µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T05:02:58.046386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:02:57.382888Z","time spent":"663.430926ms","remote":"127.0.0.1:34126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4023,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T05:02:58.046533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.418636ms","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-04-08T05:02:58.046579Z","caller":"traceutil/trace.go:171","msg":"trace[2066847319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:831; }","duration":"130.508478ms","start":"2026-04-08T05:02:57.916056Z","end":"2026-04-08T05:02:58.046565Z","steps":["trace[2066847319] 'agreement among raft nodes before linearized reading' (duration: 130.320633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:02:58.04666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.395353ms","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-04-08T05:02:58.046745Z","caller":"traceutil/trace.go:171","msg":"trace[1744066020] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:831; }","duration":"391.497657ms","start":"2026-04-08T05:02:57.655229Z","end":"2026-04-08T05:02:58.046727Z","steps":["trace[1744066020] 'agreement among raft nodes before linearized reading' (duration: 391.165928ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:02:58.046797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:02:57.655065Z","time spent":"391.723332ms","remote":"127.0.0.1:34212","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-04-08T05:02:58.543223Z","caller":"traceutil/trace.go:171","msg":"trace[1261611850] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"154.973102ms","start":"2026-04-08T05:02:58.388228Z","end":"2026-04-08T05:02:58.543201Z","steps":["trace[1261611850] 'process raft request' (duration: 154.805468ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:03:32.81805Z","caller":"traceutil/trace.go:171","msg":"trace[24360954] linearizableReadLoop","detail":"{readStateIndex:1082; appliedIndex:1081; }","duration":"445.795143ms","start":"2026-04-08T05:03:32.372237Z","end":"2026-04-08T05:03:32.818032Z","steps":["trace[24360954] 'read index received' (duration: 445.66922ms)","trace[24360954] 'applied index is now lower than readState.Index' (duration: 125.203µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T05:03:32.818101Z","caller":"traceutil/trace.go:171","msg":"trace[396927797] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"467.162294ms","start":"2026-04-08T05:03:32.350913Z","end":"2026-04-08T05:03:32.818075Z","steps":["trace[396927797] 'process raft request' (duration: 467.014761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:03:32.818247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:03:32.350894Z","time spent":"467.281588ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T05:03:32.818252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.006569ms","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-04-08T05:03:32.818286Z","caller":"traceutil/trace.go:171","msg":"trace[1154929033] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1042; }","duration":"446.082181ms","start":"2026-04-08T05:03:32.372195Z","end":"2026-04-08T05:03:32.818278Z","steps":["trace[1154929033] 'agreement among raft nodes before linearized reading' (duration: 445.956827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:03:32.818309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:03:32.372178Z","time spent":"446.124442ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-08T05:03:32.819581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.686868ms","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-04-08T05:03:32.819618Z","caller":"traceutil/trace.go:171","msg":"trace[1446339898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1043; }","duration":"443.753999ms","start":"2026-04-08T05:03:32.375854Z","end":"2026-04-08T05:03:32.819608Z","steps":["trace[1446339898] 'agreement among raft nodes before linearized reading' (duration: 443.618935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:03:32.819647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:03:32.375839Z","time spent":"443.802981ms","remote":"127.0.0.1:34212","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-04-08T05:03:32.819808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.120577ms","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-04-08T05:03:32.819848Z","caller":"traceutil/trace.go:171","msg":"trace[760907466] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1043; }","duration":"423.174559ms","start":"2026-04-08T05:03:32.396663Z","end":"2026-04-08T05:03:32.819837Z","steps":["trace[760907466] 'agreement among raft nodes before linearized reading' (duration: 423.073647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:03:32.819869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:03:32.396656Z","time spent":"423.20772ms","remote":"127.0.0.1:34212","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-04-08T05:03:32.819857Z","caller":"traceutil/trace.go:171","msg":"trace[1015255247] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"231.295758ms","start":"2026-04-08T05:03:32.588552Z","end":"2026-04-08T05:03:32.819848Z","steps":["trace[1015255247] 'process raft request' (duration: 230.809026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:03:32.819935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.658259ms","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-04-08T05:03:32.820022Z","caller":"traceutil/trace.go:171","msg":"trace[113597789] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1043; }","duration":"356.772792ms","start":"2026-04-08T05:03:32.463228Z","end":"2026-04-08T05:03:32.820001Z","steps":["trace[113597789] 'agreement among raft nodes before linearized reading' (duration: 356.513216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:03:32.820072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:03:32.46322Z","time spent":"356.842834ms","remote":"127.0.0.1:34212","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-04-08T05:03:33.480628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.426092ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T05:03:33.480895Z","caller":"traceutil/trace.go:171","msg":"trace[1936555210] linearizableReadLoop","detail":"{readStateIndex:1087; appliedIndex:1084; }","duration":"347.51751ms","start":"2026-04-08T05:03:33.13336Z","end":"2026-04-08T05:03:33.480878Z","steps":["trace[1936555210] 'read index received' (duration: 94.550784ms)","trace[1936555210] 'applied index is now lower than readState.Index' (duration: 252.965696ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T05:03:33.481023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.670204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T05:03:33.480991Z","caller":"traceutil/trace.go:171","msg":"trace[754969271] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"658.332623ms","start":"2026-04-08T05:03:32.822619Z","end":"2026-04-08T05:03:33.480951Z","steps":["trace[754969271] 'process raft request' (duration: 405.473799ms)","trace[754969271] 'compare' (duration: 252.021833ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T05:03:33.481053Z","caller":"traceutil/trace.go:171","msg":"trace[1959443055] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1047; }","duration":"347.734715ms","start":"2026-04-08T05:03:33.133306Z","end":"2026-04-08T05:03:33.481041Z","steps":["trace[1959443055] 'agreement among raft nodes before linearized reading' (duration: 347.616003ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:03:33.481028Z","caller":"traceutil/trace.go:171","msg":"trace[1792919130] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"657.852811ms","start":"2026-04-08T05:03:32.823152Z","end":"2026-04-08T05:03:33.481005Z","steps":["trace[1792919130] 'process raft request' (duration: 657.586434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:03:33.481084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:03:33.133283Z","time spent":"347.792607ms","remote":"127.0.0.1:33942","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-04-08T05:03:33.481081Z","caller":"traceutil/trace.go:171","msg":"trace[462898314] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"656.864196ms","start":"2026-04-08T05:03:32.824185Z","end":"2026-04-08T05:03:33.481049Z","steps":["trace[462898314] 'process raft request' (duration: 656.658101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:03:33.481154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:03:32.822604Z","time spent":"658.461677ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T05:03:33.481155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:03:32.82314Z","time spent":"657.941872ms","remote":"127.0.0.1:34212","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-04-08T05:03:33.481618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:03:32.824171Z","time spent":"657.050471ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T05:03:37.013932Z","caller":"traceutil/trace.go:171","msg":"trace[170787383] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"179.358627ms","start":"2026-04-08T05:03:36.834556Z","end":"2026-04-08T05:03:37.013915Z","steps":["trace[170787383] 'process raft request' (duration: 179.253463ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:03:37.088923Z","caller":"traceutil/trace.go:171","msg":"trace[1247166057] linearizableReadLoop","detail":"{readStateIndex:1097; appliedIndex:1095; }","duration":"219.38586ms","start":"2026-04-08T05:03:36.869504Z","end":"2026-04-08T05:03:37.08889Z","steps":["trace[1247166057] 'read index received' (duration: 144.277128ms)","trace[1247166057] 'applied index is now lower than readState.Index' (duration: 75.107552ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T05:03:37.089039Z","caller":"traceutil/trace.go:171","msg":"trace[195666391] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"251.089679ms","start":"2026-04-08T05:03:36.837887Z","end":"2026-04-08T05:03:37.088977Z","steps":["trace[195666391] 'process raft request' (duration: 250.813421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:03:37.089313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.765979ms","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-04-08T05:03:37.089402Z","caller":"traceutil/trace.go:171","msg":"trace[1556942715] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1056; }","duration":"219.919923ms","start":"2026-04-08T05:03:36.869462Z","end":"2026-04-08T05:03:37.089382Z","steps":["trace[1556942715] 'agreement among raft nodes before linearized reading' (duration: 219.596365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:03:37.635876Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563807474559860212,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T05:03:37.890414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.639795ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T05:03:37.890604Z","caller":"traceutil/trace.go:171","msg":"trace[664726960] linearizableReadLoop","detail":"{readStateIndex:1098; appliedIndex:1097; }","duration":"755.034525ms","start":"2026-04-08T05:03:37.135536Z","end":"2026-04-08T05:03:37.890571Z","steps":["trace[664726960] 'read index received' (duration: 621.03207ms)","trace[664726960] 'applied index is now lower than readState.Index' (duration: 133.999025ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T05:03:37.890751Z","caller":"traceutil/trace.go:171","msg":"trace[94158623] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"796.500957ms","start":"2026-04-08T05:03:37.094227Z","end":"2026-04-08T05:03:37.890728Z","steps":["trace[94158623] 'process raft request' (duration: 662.42095ms)","trace[94158623] 'compare' (duration: 133.178644ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T05:03:37.890909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:03:37.094194Z","time spent":"796.601018ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T05:03:37.890928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.968978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T05:03:37.891008Z","caller":"traceutil/trace.go:171","msg":"trace[1014211356] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1057; }","duration":"465.089871ms","start":"2026-04-08T05:03:37.425895Z","end":"2026-04-08T05:03:37.890985Z","steps":["trace[1014211356] 'agreement among raft nodes before linearized reading' (duration: 464.937817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:03:37.891044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.969572ms","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-04-08T05:03:37.891052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:03:37.425878Z","time spent":"465.163993ms","remote":"127.0.0.1:34308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-08T05:03:37.891054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.896938ms","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-04-08T05:03:37.891074Z","caller":"traceutil/trace.go:171","msg":"trace[848097876] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1057; }","duration":"394.014163ms","start":"2026-04-08T05:03:37.497051Z","end":"2026-04-08T05:03:37.891065Z","steps":["trace[848097876] 'agreement among raft nodes before linearized reading' (duration: 393.908801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:03:37.891099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:03:37.497044Z","time spent":"394.050284ms","remote":"127.0.0.1:34212","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-04-08T05:03:37.891101Z","caller":"traceutil/trace.go:171","msg":"trace[477992858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1057; }","duration":"391.961741ms","start":"2026-04-08T05:03:37.499126Z","end":"2026-04-08T05:03:37.891087Z","steps":["trace[477992858] 'agreement among raft nodes before linearized reading' (duration: 391.832067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:03:37.891089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.537077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T05:03:37.891136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:03:37.499114Z","time spent":"392.014323ms","remote":"127.0.0.1:34212","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-04-08T05:03:37.891167Z","caller":"traceutil/trace.go:171","msg":"trace[1494107206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1057; }","duration":"755.630731ms","start":"2026-04-08T05:03:37.135518Z","end":"2026-04-08T05:03:37.891148Z","steps":["trace[1494107206] 'agreement among raft nodes before linearized reading' (duration: 755.477726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:03:37.89115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.073595ms","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-04-08T05:03:37.891201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:03:37.135434Z","time spent":"755.761624ms","remote":"127.0.0.1:33942","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-04-08T05:03:37.891227Z","caller":"traceutil/trace.go:171","msg":"trace[1436101849] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1057; }","duration":"397.192017ms","start":"2026-04-08T05:03:37.494016Z","end":"2026-04-08T05:03:37.891208Z","steps":["trace[1436101849] 'agreement among raft nodes before linearized reading' (duration: 396.98128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:03:37.891288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:03:37.493995Z","time spent":"397.276209ms","remote":"127.0.0.1:34212","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":"info","ts":"2026-04-08T05:03:49.154176Z","caller":"traceutil/trace.go:171","msg":"trace[1294373604] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"103.31907ms","start":"2026-04-08T05:03:49.050833Z","end":"2026-04-08T05:03:49.154152Z","steps":["trace[1294373604] 'process raft request' (duration: 102.963261ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:03:57.433067Z","caller":"traceutil/trace.go:171","msg":"trace[1382349166] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"254.464556ms","start":"2026-04-08T05:03:57.178575Z","end":"2026-04-08T05:03:57.43304Z","steps":["trace[1382349166] 'process raft request' (duration: 254.305092ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:03:57.433508Z","caller":"traceutil/trace.go:171","msg":"trace[200759268] linearizableReadLoop","detail":"{readStateIndex:1166; appliedIndex:1166; }","duration":"227.887288ms","start":"2026-04-08T05:03:57.205607Z","end":"2026-04-08T05:03:57.433494Z","steps":["trace[200759268] 'read index received' (duration: 227.881338ms)","trace[200759268] 'applied index is now lower than readState.Index' (duration: 4.76µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T05:03:57.433849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.39909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T05:03:57.433899Z","caller":"traceutil/trace.go:171","msg":"trace[688954667] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1121; }","duration":"171.495042ms","start":"2026-04-08T05:03:57.262389Z","end":"2026-04-08T05:03:57.433885Z","steps":["trace[688954667] 'agreement among raft nodes before linearized reading' (duration: 171.190065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:03:57.434019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.210758ms","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-04-08T05:03:57.434057Z","caller":"traceutil/trace.go:171","msg":"trace[1102558644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1121; }","duration":"228.515325ms","start":"2026-04-08T05:03:57.205533Z","end":"2026-04-08T05:03:57.434048Z","steps":["trace[1102558644] 'agreement among raft nodes before linearized reading' (duration: 228.118505ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:03:58.350517Z","caller":"traceutil/trace.go:171","msg":"trace[2066298174] linearizableReadLoop","detail":"{readStateIndex:1168; appliedIndex:1167; }","duration":"332.294717ms","start":"2026-04-08T05:03:58.018197Z","end":"2026-04-08T05:03:58.350492Z","steps":["trace[2066298174] 'read index received' (duration: 332.036731ms)","trace[2066298174] 'applied index is now lower than readState.Index' (duration: 256.916µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T05:03:58.350635Z","caller":"traceutil/trace.go:171","msg":"trace[1801198975] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"358.976486ms","start":"2026-04-08T05:03:57.991648Z","end":"2026-04-08T05:03:58.350624Z","steps":["trace[1801198975] 'process raft request' (duration: 358.656779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:03:58.350744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:03:57.99162Z","time spent":"359.045329ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T05:03:58.351118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.79542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-08T05:03:58.351191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.983145ms","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-04-08T05:03:58.351322Z","caller":"traceutil/trace.go:171","msg":"trace[974886742] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1123; }","duration":"333.142799ms","start":"2026-04-08T05:03:58.018154Z","end":"2026-04-08T05:03:58.351297Z","steps":["trace[974886742] 'agreement among raft nodes before linearized reading' (duration: 332.852592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:03:58.351392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:03:58.018139Z","time spent":"333.237162ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-08T05:03:58.351199Z","caller":"traceutil/trace.go:171","msg":"trace[1543358081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1123; }","duration":"325.898832ms","start":"2026-04-08T05:03:58.025281Z","end":"2026-04-08T05:03:58.351179Z","steps":["trace[1543358081] 'agreement among raft nodes before linearized reading' (duration: 325.667626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:03:58.351602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:03:58.025274Z","time spent":"326.253911ms","remote":"127.0.0.1:34212","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-04-08T05:03:58.350996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.750718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T05:03:58.351673Z","caller":"traceutil/trace.go:171","msg":"trace[231518456] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1123; }","duration":"218.464266ms","start":"2026-04-08T05:03:58.133189Z","end":"2026-04-08T05:03:58.351653Z","steps":["trace[231518456] 'agreement among raft nodes before linearized reading' (duration: 217.721337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:03:58.351735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.976076ms","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-04-08T05:03:58.351798Z","caller":"traceutil/trace.go:171","msg":"trace[825385621] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1123; }","duration":"328.0955ms","start":"2026-04-08T05:03:58.023686Z","end":"2026-04-08T05:03:58.351782Z","steps":["trace[825385621] 'agreement among raft nodes before linearized reading' (duration: 327.103204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:03:58.352347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:03:58.023673Z","time spent":"328.658584ms","remote":"127.0.0.1:34212","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-04-08T05:03:58.643218Z","caller":"traceutil/trace.go:171","msg":"trace[2073016764] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"288.570947ms","start":"2026-04-08T05:03:58.354615Z","end":"2026-04-08T05:03:58.643186Z","steps":["trace[2073016764] 'process raft request' (duration: 281.267499ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:03:58.643272Z","caller":"traceutil/trace.go:171","msg":"trace[691935936] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"287.836459ms","start":"2026-04-08T05:03:58.35539Z","end":"2026-04-08T05:03:58.643227Z","steps":["trace[691935936] 'process raft request' (duration: 287.682455ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:03:58.643431Z","caller":"traceutil/trace.go:171","msg":"trace[215522296] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"287.744437ms","start":"2026-04-08T05:03:58.355666Z","end":"2026-04-08T05:03:58.64341Z","steps":["trace[215522296] 'process raft request' (duration: 287.51548ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:04:00.135325Z","caller":"traceutil/trace.go:171","msg":"trace[1981426754] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"623.923727ms","start":"2026-04-08T05:03:59.511316Z","end":"2026-04-08T05:04:00.13524Z","steps":["trace[1981426754] 'process raft request' (duration: 623.411163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:04:00.135696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:03:59.511291Z","time spent":"624.203483ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T05:04:06.901937Z","caller":"traceutil/trace.go:171","msg":"trace[1295102610] linearizableReadLoop","detail":"{readStateIndex:1199; appliedIndex:1197; }","duration":"216.66487ms","start":"2026-04-08T05:04:06.685241Z","end":"2026-04-08T05:04:06.901906Z","steps":["trace[1295102610] 'read index received' (duration: 1.454239ms)","trace[1295102610] 'applied index is now lower than readState.Index' (duration: 215.209371ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T05:04:06.902074Z","caller":"traceutil/trace.go:171","msg":"trace[577855338] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"222.558652ms","start":"2026-04-08T05:04:06.679433Z","end":"2026-04-08T05:04:06.901992Z","steps":["trace[577855338] 'process raft request' (duration: 222.333387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:04:06.902129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.873615ms","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-04-08T05:04:06.902309Z","caller":"traceutil/trace.go:171","msg":"trace[1056932012] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1152; }","duration":"217.0739ms","start":"2026-04-08T05:04:06.685203Z","end":"2026-04-08T05:04:06.902276Z","steps":["trace[1056932012] 'agreement among raft nodes before linearized reading' (duration: 216.803804ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:04:11.665633Z","caller":"traceutil/trace.go:171","msg":"trace[91314882] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"122.639253ms","start":"2026-04-08T05:04:11.542964Z","end":"2026-04-08T05:04:11.665603Z","steps":["trace[91314882] 'process raft request' (duration: 122.49873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:04:13.83216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.339152ms","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-04-08T05:04:13.832244Z","caller":"traceutil/trace.go:171","msg":"trace[1861615301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1222; }","duration":"905.466205ms","start":"2026-04-08T05:04:12.926756Z","end":"2026-04-08T05:04:13.832222Z","steps":["trace[1861615301] 'range keys from in-memory index tree' (duration: 905.123787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:04:13.832285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:04:12.926736Z","time spent":"905.538188ms","remote":"127.0.0.1:34212","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-04-08T05:04:13.832655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.175563ms","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-04-08T05:04:13.832712Z","caller":"traceutil/trace.go:171","msg":"trace[473669059] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1222; }","duration":"841.267085ms","start":"2026-04-08T05:04:12.991435Z","end":"2026-04-08T05:04:13.832702Z","steps":["trace[473669059] 'range keys from in-memory index tree' (duration: 841.0719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:04:13.832742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:04:12.991419Z","time spent":"841.316126ms","remote":"127.0.0.1:34212","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-04-08T05:04:13.832879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.746627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T05:04:13.832912Z","caller":"traceutil/trace.go:171","msg":"trace[815306447] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1222; }","duration":"698.779778ms","start":"2026-04-08T05:04:13.134124Z","end":"2026-04-08T05:04:13.832904Z","steps":["trace[815306447] 'range keys from in-memory index tree' (duration: 698.695646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:04:13.832937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:04:13.134083Z","time spent":"698.84872ms","remote":"127.0.0.1:33942","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-04-08T05:04:13.833593Z","caller":"traceutil/trace.go:171","msg":"trace[28932196] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"157.538076ms","start":"2026-04-08T05:04:13.676017Z","end":"2026-04-08T05:04:13.833555Z","steps":["trace[28932196] 'process raft request' (duration: 156.896249ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:04:26.022915Z","caller":"traceutil/trace.go:171","msg":"trace[797929859] linearizableReadLoop","detail":"{readStateIndex:1315; appliedIndex:1314; }","duration":"125.320852ms","start":"2026-04-08T05:04:25.897556Z","end":"2026-04-08T05:04:26.022877Z","steps":["trace[797929859] 'read index received' (duration: 124.896601ms)","trace[797929859] 'applied index is now lower than readState.Index' (duration: 422.541µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T05:04:26.023016Z","caller":"traceutil/trace.go:171","msg":"trace[725812435] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"144.624762ms","start":"2026-04-08T05:04:25.878346Z","end":"2026-04-08T05:04:26.022971Z","steps":["trace[725812435] 'process raft request' (duration: 144.242182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:04:26.023251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.662071ms","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-04-08T05:04:26.023341Z","caller":"traceutil/trace.go:171","msg":"trace[100038461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1262; }","duration":"125.843376ms","start":"2026-04-08T05:04:25.897478Z","end":"2026-04-08T05:04:26.023321Z","steps":["trace[100038461] 'agreement among raft nodes before linearized reading' (duration: 125.535027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:04:26.023342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.171699ms","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-04-08T05:04:26.023393Z","caller":"traceutil/trace.go:171","msg":"trace[1229083833] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1262; }","duration":"120.261311ms","start":"2026-04-08T05:04:25.903118Z","end":"2026-04-08T05:04:26.023379Z","steps":["trace[1229083833] 'agreement among raft nodes before linearized reading' (duration: 120.115007ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:04:28.427016Z","caller":"traceutil/trace.go:171","msg":"trace[1460035141] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"121.35163ms","start":"2026-04-08T05:04:28.305636Z","end":"2026-04-08T05:04:28.426988Z","steps":["trace[1460035141] 'process raft request' (duration: 71.615703ms)","trace[1460035141] 'compare' (duration: 49.556142ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T05:04:30.33556Z","caller":"traceutil/trace.go:171","msg":"trace[1486711627] linearizableReadLoop","detail":"{readStateIndex:1355; appliedIndex:1354; }","duration":"286.77176ms","start":"2026-04-08T05:04:30.048766Z","end":"2026-04-08T05:04:30.335538Z","steps":["trace[1486711627] 'read index received' (duration: 286.522374ms)","trace[1486711627] 'applied index is now lower than readState.Index' (duration: 248.686µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T05:04:30.33566Z","caller":"traceutil/trace.go:171","msg":"trace[1876842083] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"300.073213ms","start":"2026-04-08T05:04:30.035564Z","end":"2026-04-08T05:04:30.335637Z","steps":["trace[1876842083] 'process raft request' (duration: 299.804326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:04:30.335843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.133473ms","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-04-08T05:04:30.335868Z","caller":"traceutil/trace.go:171","msg":"trace[1538346183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1302; }","duration":"281.236296ms","start":"2026-04-08T05:04:30.054626Z","end":"2026-04-08T05:04:30.335862Z","steps":["trace[1538346183] 'agreement among raft nodes before linearized reading' (duration: 281.156744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:04:30.335844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.061327ms","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-04-08T05:04:30.335897Z","caller":"traceutil/trace.go:171","msg":"trace[523813114] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1302; }","duration":"287.167411ms","start":"2026-04-08T05:04:30.04872Z","end":"2026-04-08T05:04:30.335887Z","steps":["trace[523813114] 'agreement among raft nodes before linearized reading' (duration: 286.907693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:04:30.335949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.652262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T05:04:30.336007Z","caller":"traceutil/trace.go:171","msg":"trace[417450483] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1302; }","duration":"200.762554ms","start":"2026-04-08T05:04:30.135226Z","end":"2026-04-08T05:04:30.335989Z","steps":["trace[417450483] 'agreement among raft nodes before linearized reading' (duration: 200.657902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:04:30.336185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.091153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.6\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-04-08T05:04:30.336256Z","caller":"traceutil/trace.go:171","msg":"trace[146232659] range","detail":"{range_begin:/registry/masterleases/199.204.45.6; range_end:; response_count:1; response_revision:1302; }","duration":"150.224257ms","start":"2026-04-08T05:04:30.186017Z","end":"2026-04-08T05:04:30.336241Z","steps":["trace[146232659] 'agreement among raft nodes before linearized reading' (duration: 150.003332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:04:30.336227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.997258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T05:04:30.336365Z","caller":"traceutil/trace.go:171","msg":"trace[513991960] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1302; }","duration":"204.221684ms","start":"2026-04-08T05:04:30.13211Z","end":"2026-04-08T05:04:30.336331Z","steps":["trace[513991960] 'agreement among raft nodes before linearized reading' (duration: 203.847765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:04:30.336758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:04:30.035538Z","time spent":"300.228167ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T05:04:30.607572Z","caller":"traceutil/trace.go:171","msg":"trace[1743165327] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"265.965131ms","start":"2026-04-08T05:04:30.341587Z","end":"2026-04-08T05:04:30.607552Z","steps":["trace[1743165327] 'process raft request' (duration: 265.875698ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:04:30.607722Z","caller":"traceutil/trace.go:171","msg":"trace[792961049] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"265.164921ms","start":"2026-04-08T05:04:30.342541Z","end":"2026-04-08T05:04:30.607706Z","steps":["trace[792961049] 'process raft request' (duration: 264.970686ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:04:30.607781Z","caller":"traceutil/trace.go:171","msg":"trace[1836702893] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"267.903521ms","start":"2026-04-08T05:04:30.339868Z","end":"2026-04-08T05:04:30.607771Z","steps":["trace[1836702893] 'process raft request' (duration: 267.295905ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:04:31.98775Z","caller":"traceutil/trace.go:171","msg":"trace[1449112007] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"112.274015ms","start":"2026-04-08T05:04:31.875453Z","end":"2026-04-08T05:04:31.987727Z","steps":["trace[1449112007] 'process raft request' (duration: 112.141762ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:04:32.755133Z","caller":"traceutil/trace.go:171","msg":"trace[1515072227] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"140.504235ms","start":"2026-04-08T05:04:32.614556Z","end":"2026-04-08T05:04:32.75506Z","steps":["trace[1515072227] 'process raft request' (duration: 140.174647ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:04:32.786001Z","caller":"traceutil/trace.go:171","msg":"trace[1285198022] linearizableReadLoop","detail":"{readStateIndex:1373; appliedIndex:1370; }","duration":"134.657124ms","start":"2026-04-08T05:04:32.651322Z","end":"2026-04-08T05:04:32.78598Z","steps":["trace[1285198022] 'read index received' (duration: 103.378695ms)","trace[1285198022] 'applied index is now lower than readState.Index' (duration: 31.277739ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T05:04:32.786053Z","caller":"traceutil/trace.go:171","msg":"trace[650726850] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"170.683876ms","start":"2026-04-08T05:04:32.615335Z","end":"2026-04-08T05:04:32.786019Z","steps":["trace[650726850] 'process raft request' (duration: 170.491561ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:04:32.786118Z","caller":"traceutil/trace.go:171","msg":"trace[284413615] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"170.251454ms","start":"2026-04-08T05:04:32.615825Z","end":"2026-04-08T05:04:32.786077Z","steps":["trace[284413615] 'process raft request' (duration: 170.124841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:04:32.7862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.875009ms","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-04-08T05:04:32.786233Z","caller":"traceutil/trace.go:171","msg":"trace[1626474973] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1319; }","duration":"134.970802ms","start":"2026-04-08T05:04:32.651254Z","end":"2026-04-08T05:04:32.786225Z","steps":["trace[1626474973] 'agreement among raft nodes before linearized reading' (duration: 134.816437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:04:32.937133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.587885ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T05:04:32.9373Z","caller":"traceutil/trace.go:171","msg":"trace[363209245] linearizableReadLoop","detail":"{readStateIndex:1374; appliedIndex:1373; }","duration":"137.120408ms","start":"2026-04-08T05:04:32.800157Z","end":"2026-04-08T05:04:32.937277Z","steps":["trace[363209245] 'read index received' (duration: 32.276716ms)","trace[363209245] 'applied index is now lower than readState.Index' (duration: 104.842012ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T05:04:32.937348Z","caller":"traceutil/trace.go:171","msg":"trace[2003586327] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"146.998663ms","start":"2026-04-08T05:04:32.790301Z","end":"2026-04-08T05:04:32.9373Z","steps":["trace[2003586327] 'process raft request' (duration: 42.11612ms)","trace[2003586327] 'compare' (duration: 104.37299ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T05:04:32.937505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.343755ms","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-04-08T05:04:32.937554Z","caller":"traceutil/trace.go:171","msg":"trace[2116421497] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1320; }","duration":"137.413546ms","start":"2026-04-08T05:04:32.800129Z","end":"2026-04-08T05:04:32.937543Z","steps":["trace[2116421497] 'agreement among raft nodes before linearized reading' (duration: 137.23424ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:04:44.917422Z","caller":"traceutil/trace.go:171","msg":"trace[101471269] linearizableReadLoop","detail":"{readStateIndex:1449; appliedIndex:1448; }","duration":"373.063211ms","start":"2026-04-08T05:04:44.544338Z","end":"2026-04-08T05:04:44.917402Z","steps":["trace[101471269] 'read index received' (duration: 372.832966ms)","trace[101471269] 'applied index is now lower than readState.Index' (duration: 229.245µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T05:04:44.91757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.210526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T05:04:44.917544Z","caller":"traceutil/trace.go:171","msg":"trace[175152971] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"444.891141ms","start":"2026-04-08T05:04:44.472622Z","end":"2026-04-08T05:04:44.917513Z","steps":["trace[175152971] 'process raft request' (duration: 444.631864ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:04:44.917601Z","caller":"traceutil/trace.go:171","msg":"trace[374425560] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1393; }","duration":"373.282057ms","start":"2026-04-08T05:04:44.544309Z","end":"2026-04-08T05:04:44.917591Z","steps":["trace[374425560] 'agreement among raft nodes before linearized reading' (duration: 373.213065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:04:44.917632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:04:44.544295Z","time spent":"373.330189ms","remote":"127.0.0.1:33942","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-04-08T05:04:44.917709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:04:44.472589Z","time spent":"445.029995ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T05:04:44.917714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.926987ms","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:535"} {"level":"info","ts":"2026-04-08T05:04:44.917792Z","caller":"traceutil/trace.go:171","msg":"trace[1976933182] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1393; }","duration":"131.05147ms","start":"2026-04-08T05:04:44.786719Z","end":"2026-04-08T05:04:44.917771Z","steps":["trace[1976933182] 'agreement among raft nodes before linearized reading' (duration: 130.844305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:04:45.425879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.62756ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T05:04:45.42608Z","caller":"traceutil/trace.go:171","msg":"trace[436364857] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"504.541573ms","start":"2026-04-08T05:04:44.921521Z","end":"2026-04-08T05:04:45.426062Z","steps":["trace[436364857] 'process raft request' (duration: 235.651917ms)","trace[436364857] 'compare' (duration: 268.446835ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T05:04:45.426151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:04:44.921503Z","time spent":"504.615925ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T05:04:45.42664Z","caller":"traceutil/trace.go:171","msg":"trace[421896064] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"505.101668ms","start":"2026-04-08T05:04:44.921517Z","end":"2026-04-08T05:04:45.426618Z","steps":["trace[421896064] 'process raft request' (duration: 504.474512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:04:45.427067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:04:44.921492Z","time spent":"505.211971ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T05:04:45.427515Z","caller":"traceutil/trace.go:171","msg":"trace[1868978623] linearizableReadLoop","detail":"{readStateIndex:1453; appliedIndex:1449; }","duration":"415.328995ms","start":"2026-04-08T05:04:45.012153Z","end":"2026-04-08T05:04:45.427482Z","steps":["trace[1868978623] 'read index received' (duration: 145.033132ms)","trace[1868978623] 'applied index is now lower than readState.Index' (duration: 270.294833ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T05:04:45.427672Z","caller":"traceutil/trace.go:171","msg":"trace[510409187] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"505.923458ms","start":"2026-04-08T05:04:44.92173Z","end":"2026-04-08T05:04:45.427654Z","steps":["trace[510409187] 'process raft request' (duration: 505.621381ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:04:45.427717Z","caller":"traceutil/trace.go:171","msg":"trace[1450081827] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"505.9948ms","start":"2026-04-08T05:04:44.921695Z","end":"2026-04-08T05:04:45.42769Z","steps":["trace[1450081827] 'process raft request' (duration: 504.81355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:04:45.427793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.388319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-04-08T05:04:45.427864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:04:44.921684Z","time spent":"506.109283ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T05:04:45.427841Z","caller":"traceutil/trace.go:171","msg":"trace[375021820] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1397; }","duration":"414.438633ms","start":"2026-04-08T05:04:45.01339Z","end":"2026-04-08T05:04:45.427829Z","steps":["trace[375021820] 'agreement among raft nodes before linearized reading' (duration: 414.301648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:04:45.42789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.430849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T05:04:45.4279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.722546ms","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-04-08T05:04:45.427974Z","caller":"traceutil/trace.go:171","msg":"trace[901849831] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1397; }","duration":"296.551783ms","start":"2026-04-08T05:04:45.131399Z","end":"2026-04-08T05:04:45.427951Z","steps":["trace[901849831] 'agreement among raft nodes before linearized reading' (duration: 296.399558ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:04:45.427995Z","caller":"traceutil/trace.go:171","msg":"trace[1808489704] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1397; }","duration":"415.844819ms","start":"2026-04-08T05:04:45.012112Z","end":"2026-04-08T05:04:45.427957Z","steps":["trace[1808489704] 'agreement among raft nodes before linearized reading' (duration: 415.557221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:04:45.427991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:04:45.013328Z","time spent":"414.649728ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T05:04:45.427938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:04:44.921714Z","time spent":"506.038011ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T05:04:45.428067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:04:45.012093Z","time spent":"415.936371ms","remote":"127.0.0.1:34212","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-04-08T05:04:45.544043Z","caller":"traceutil/trace.go:171","msg":"trace[1758442494] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"111.740641ms","start":"2026-04-08T05:04:45.432273Z","end":"2026-04-08T05:04:45.544014Z","steps":["trace[1758442494] 'process raft request' (duration: 109.864053ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:04:45.544471Z","caller":"traceutil/trace.go:171","msg":"trace[1241088145] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"111.852434ms","start":"2026-04-08T05:04:45.432578Z","end":"2026-04-08T05:04:45.54443Z","steps":["trace[1241088145] 'process raft request' (duration: 111.364441ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:05:13.693481Z","caller":"traceutil/trace.go:171","msg":"trace[1900337304] transaction","detail":"{read_only:false; response_revision:1526; number_of_response:1; }","duration":"130.902929ms","start":"2026-04-08T05:05:13.562557Z","end":"2026-04-08T05:05:13.69346Z","steps":["trace[1900337304] 'process raft request' (duration: 88.517471ms)","trace[1900337304] 'compare' (duration: 42.242413ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T05:05:23.194824Z","caller":"traceutil/trace.go:171","msg":"trace[128652935] transaction","detail":"{read_only:false; response_revision:1569; number_of_response:1; }","duration":"113.291113ms","start":"2026-04-08T05:05:23.081505Z","end":"2026-04-08T05:05:23.194796Z","steps":["trace[128652935] 'process raft request' (duration: 112.80284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:05:25.860365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.791808ms","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-04-08T05:05:25.860424Z","caller":"traceutil/trace.go:171","msg":"trace[1238378151] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1581; }","duration":"110.898052ms","start":"2026-04-08T05:05:25.749511Z","end":"2026-04-08T05:05:25.860409Z","steps":["trace[1238378151] 'range keys from in-memory index tree' (duration: 110.653625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:05:25.860501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.630018ms","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-04-08T05:05:25.86057Z","caller":"traceutil/trace.go:171","msg":"trace[2132920231] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1581; }","duration":"109.715661ms","start":"2026-04-08T05:05:25.750837Z","end":"2026-04-08T05:05:25.860553Z","steps":["trace[2132920231] 'range keys from in-memory index tree' (duration: 109.504405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:05:25.861189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.492795ms","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-04-08T05:05:25.861331Z","caller":"traceutil/trace.go:171","msg":"trace[212368195] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1581; }","duration":"114.667759ms","start":"2026-04-08T05:05:25.746642Z","end":"2026-04-08T05:05:25.861309Z","steps":["trace[212368195] 'range keys from in-memory index tree' (duration: 113.666933ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:05:28.216208Z","caller":"traceutil/trace.go:171","msg":"trace[831251887] transaction","detail":"{read_only:false; response_revision:1593; number_of_response:1; }","duration":"234.676867ms","start":"2026-04-08T05:05:27.981502Z","end":"2026-04-08T05:05:28.216179Z","steps":["trace[831251887] 'process raft request' (duration: 230.736785ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:05:28.216208Z","caller":"traceutil/trace.go:171","msg":"trace[3677843] transaction","detail":"{read_only:false; response_revision:1594; number_of_response:1; }","duration":"233.625539ms","start":"2026-04-08T05:05:27.982556Z","end":"2026-04-08T05:05:28.216182Z","steps":["trace[3677843] 'process raft request' (duration: 233.540936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:05:28.502556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.55879ms","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-04-08T05:05:28.502645Z","caller":"traceutil/trace.go:171","msg":"trace[1643450363] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1594; }","duration":"162.716714ms","start":"2026-04-08T05:05:28.339911Z","end":"2026-04-08T05:05:28.502628Z","steps":["trace[1643450363] 'count revisions from in-memory index tree' (duration: 162.403334ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:05:52.5611Z","caller":"traceutil/trace.go:171","msg":"trace[165233993] linearizableReadLoop","detail":"{readStateIndex:1772; appliedIndex:1771; }","duration":"163.659398ms","start":"2026-04-08T05:05:52.397417Z","end":"2026-04-08T05:05:52.561076Z","steps":["trace[165233993] 'read index received' (duration: 163.404042ms)","trace[165233993] 'applied index is now lower than readState.Index' (duration: 254.226µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T05:05:52.561244Z","caller":"traceutil/trace.go:171","msg":"trace[972021520] transaction","detail":"{read_only:false; response_revision:1702; number_of_response:1; }","duration":"334.85747ms","start":"2026-04-08T05:05:52.226336Z","end":"2026-04-08T05:05:52.561193Z","steps":["trace[972021520] 'process raft request' (duration: 334.570613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T05:05:52.561465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T05:05:52.226294Z","time spent":"335.046355ms","remote":"127.0.0.1:34212","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-04-08T05:05:52.561451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.995287ms","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-04-08T05:05:52.561553Z","caller":"traceutil/trace.go:171","msg":"trace[1051178622] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1702; }","duration":"164.167391ms","start":"2026-04-08T05:05:52.397361Z","end":"2026-04-08T05:05:52.561528Z","steps":["trace[1051178622] 'agreement among raft nodes before linearized reading' (duration: 163.865653ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:07:06.390979Z","caller":"traceutil/trace.go:171","msg":"trace[763035817] transaction","detail":"{read_only:false; response_revision:2040; number_of_response:1; }","duration":"132.159001ms","start":"2026-04-08T05:07:06.258791Z","end":"2026-04-08T05:07:06.39095Z","steps":["trace[763035817] 'process raft request' (duration: 131.557996ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:07:53.140885Z","caller":"traceutil/trace.go:171","msg":"trace[1878821956] transaction","detail":"{read_only:false; response_revision:2279; number_of_response:1; }","duration":"151.623335ms","start":"2026-04-08T05:07:52.988939Z","end":"2026-04-08T05:07:53.140562Z","steps":["trace[1878821956] 'process raft request' (duration: 151.427861ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T05:08:00.391991Z","caller":"traceutil/trace.go:171","msg":"trace[1738433154] transaction","detail":"{read_only:false; response_revision:2318; number_of_response:1; }","duration":"181.423229ms","start":"2026-04-08T05:08:00.21053Z","end":"2026-04-08T05:08:00.391954Z","steps":["trace[1738433154] 'process raft request' (duration: 113.803248ms)","trace[1738433154] 'compare' (duration: 67.459577ms)"],"step_count":2}