{"level":"warn","ts":"2026-07-01T00:52:05.935887Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T00:52:05.935972Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156: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.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-01T00:52:05.936041Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T00:52:05.936056Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-07-01T00:52:05.936073Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-01T00:52:05.936785Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-07-01T00:52:05.936861Z","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.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-01T00:52:05.942953Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.878886ms"} {"level":"info","ts":"2026-07-01T00:52:05.966547Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-07-01T00:52:05.96663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-07-01T00:52:05.966651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-07-01T00:52:05.966662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-01T00:52:05.966671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-07-01T00:52:05.966714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-07-01T00:52:05.97748Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-01T00:52:05.979032Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-01T00:52:05.980155Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-01T00:52:05.982915Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-01T00:52:05.989598Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-01T00:52:05.98963Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-01T00:52:05.989686Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-01T00:52:05.989696Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-01T00:52:05.989752Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-01T00:52:05.989891Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-07-01T00:52:05.989918Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-07-01T00:52:05.99023Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-01T00:52:05.990279Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-01T00:52:05.997357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-07-01T00:52:05.99753Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-07-01T00:52:06.369597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-07-01T00:52:06.36969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-01T00:52:06.369728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-07-01T00:52:06.369743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-07-01T00:52:06.369752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-07-01T00:52:06.369762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-07-01T00:52:06.369771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-07-01T00:52:06.371865Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T00:52:06.373058Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-07-01T00:52:06.373701Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-01T00:52:06.373782Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-01T00:52:06.373097Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T00:52:06.374074Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T00:52:06.374194Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T00:52:06.374288Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T00:52:06.377609Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-01T00:52:06.382766Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T00:52:06.384842Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"info","ts":"2026-07-01T00:52:12.703705Z","caller":"traceutil/trace.go:171","msg":"trace[2067871418] linearizableReadLoop","detail":"{readStateIndex:293; appliedIndex:292; }","duration":"129.096514ms","start":"2026-07-01T00:52:12.574576Z","end":"2026-07-01T00:52:12.703672Z","steps":["trace[2067871418] 'read index received' (duration: 65.016198ms)","trace[2067871418] 'applied index is now lower than readState.Index' (duration: 64.079096ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:52:12.703779Z","caller":"traceutil/trace.go:171","msg":"trace[316405981] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"164.811321ms","start":"2026-07-01T00:52:12.538919Z","end":"2026-07-01T00:52:12.70373Z","steps":["trace[316405981] 'process raft request' (duration: 100.646593ms)","trace[316405981] 'compare' (duration: 63.954283ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:52:12.703927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.310239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:52:12.704097Z","caller":"traceutil/trace.go:171","msg":"trace[524925920] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/persistent-volume-binder; range_end:; response_count:0; response_revision:283; }","duration":"129.495664ms","start":"2026-07-01T00:52:12.574521Z","end":"2026-07-01T00:52:12.704017Z","steps":["trace[524925920] 'agreement among raft nodes before linearized reading' (duration: 129.279539ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:52:19.733666Z","caller":"traceutil/trace.go:171","msg":"trace[88074418] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"135.572292ms","start":"2026-07-01T00:52:19.59807Z","end":"2026-07-01T00:52:19.733643Z","steps":["trace[88074418] 'process raft request' (duration: 90.766444ms)","trace[88074418] 'compare' (duration: 44.708566ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:52:24.134987Z","caller":"traceutil/trace.go:171","msg":"trace[1799851185] linearizableReadLoop","detail":"{readStateIndex:389; appliedIndex:388; }","duration":"120.28725ms","start":"2026-07-01T00:52:24.014674Z","end":"2026-07-01T00:52:24.134961Z","steps":["trace[1799851185] 'read index received' (duration: 120.195498ms)","trace[1799851185] 'applied index is now lower than readState.Index' (duration: 90.612µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:52:24.135131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.449675ms","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-07-01T00:52:24.135169Z","caller":"traceutil/trace.go:171","msg":"trace[1203575326] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:377; }","duration":"120.539227ms","start":"2026-07-01T00:52:24.014617Z","end":"2026-07-01T00:52:24.135156Z","steps":["trace[1203575326] 'agreement among raft nodes before linearized reading' (duration: 120.436004ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:52:24.135301Z","caller":"traceutil/trace.go:171","msg":"trace[1766141849] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"200.15499ms","start":"2026-07-01T00:52:23.935137Z","end":"2026-07-01T00:52:24.135292Z","steps":["trace[1766141849] 'process raft request' (duration: 199.692369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:24.955802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.362126ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T00:52:24.955914Z","caller":"traceutil/trace.go:171","msg":"trace[1795259542] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"815.042457ms","start":"2026-07-01T00:52:24.140853Z","end":"2026-07-01T00:52:24.955895Z","steps":["trace[1795259542] 'process raft request' (duration: 437.992663ms)","trace[1795259542] 'compare' (duration: 376.241503ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:52:24.955978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:24.140832Z","time spent":"815.115728ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:52:25.064625Z","caller":"traceutil/trace.go:171","msg":"trace[1689362082] linearizableReadLoop","detail":"{readStateIndex:391; appliedIndex:389; }","duration":"899.837816ms","start":"2026-07-01T00:52:24.164763Z","end":"2026-07-01T00:52:25.064601Z","steps":["trace[1689362082] 'read index received' (duration: 414.107404ms)","trace[1689362082] 'applied index is now lower than readState.Index' (duration: 485.729452ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:52:25.064975Z","caller":"traceutil/trace.go:171","msg":"trace[802995302] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"924.026673ms","start":"2026-07-01T00:52:24.140931Z","end":"2026-07-01T00:52:25.064958Z","steps":["trace[802995302] 'process raft request' (duration: 923.53535ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:25.065083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.291307ms","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-07-01T00:52:25.065152Z","caller":"traceutil/trace.go:171","msg":"trace[148387080] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:379; }","duration":"900.39696ms","start":"2026-07-01T00:52:24.164739Z","end":"2026-07-01T00:52:25.065136Z","steps":["trace[148387080] 'agreement among raft nodes before linearized reading' (duration: 900.253997ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:25.065156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:24.140912Z","time spent":"924.120426ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:52:25.065206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:24.164727Z","time spent":"900.465791ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-01T00:52:25.074732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.984024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:52:25.07484Z","caller":"traceutil/trace.go:171","msg":"trace[1244059021] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:380; }","duration":"534.467795ms","start":"2026-07-01T00:52:24.540345Z","end":"2026-07-01T00:52:25.074813Z","steps":["trace[1244059021] 'agreement among raft nodes before linearized reading' (duration: 533.956483ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:25.074916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:24.540324Z","time spent":"534.562058ms","remote":"127.0.0.1:52988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-01T00:52:45.241624Z","caller":"traceutil/trace.go:171","msg":"trace[720000809] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"266.741095ms","start":"2026-07-01T00:52:44.974863Z","end":"2026-07-01T00:52:45.241604Z","steps":["trace[720000809] 'process raft request' (duration: 266.609502ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:52:45.583392Z","caller":"traceutil/trace.go:171","msg":"trace[989359358] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"607.297254ms","start":"2026-07-01T00:52:44.976051Z","end":"2026-07-01T00:52:45.583348Z","steps":["trace[989359358] 'process raft request' (duration: 607.042508ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:52:45.583684Z","caller":"traceutil/trace.go:171","msg":"trace[1445848956] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"590.324311ms","start":"2026-07-01T00:52:44.993325Z","end":"2026-07-01T00:52:45.58365Z","steps":["trace[1445848956] 'process raft request' (duration: 590.120006ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:52:45.583707Z","caller":"traceutil/trace.go:171","msg":"trace[2044821102] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"574.575659ms","start":"2026-07-01T00:52:45.009069Z","end":"2026-07-01T00:52:45.583645Z","steps":["trace[2044821102] 'process raft request' (duration: 574.411275ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:45.583837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:44.99332Z","time spent":"590.431775ms","remote":"127.0.0.1:53012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T00:52:45.583789Z","caller":"traceutil/trace.go:171","msg":"trace[1548015456] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"570.726496ms","start":"2026-07-01T00:52:45.013022Z","end":"2026-07-01T00:52:45.583749Z","steps":["trace[1548015456] 'process raft request' (duration: 570.552982ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:52:45.583904Z","caller":"traceutil/trace.go:171","msg":"trace[1523858637] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"563.052369ms","start":"2026-07-01T00:52:45.02083Z","end":"2026-07-01T00:52:45.583883Z","steps":["trace[1523858637] 'process raft request' (duration: 562.813433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:45.583934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:45.009049Z","time spent":"574.806814ms","remote":"127.0.0.1:53012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T00:52:45.583967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:45.013008Z","time spent":"570.914331ms","remote":"127.0.0.1:53012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T00:52:45.584003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:45.020813Z","time spent":"563.151912ms","remote":"127.0.0.1:53012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T00:52:45.58409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:44.976024Z","time spent":"607.645672ms","remote":"127.0.0.1:53012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T00:52:45.584128Z","caller":"traceutil/trace.go:171","msg":"trace[819481910] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"574.992689ms","start":"2026-07-01T00:52:45.009123Z","end":"2026-07-01T00:52:45.584116Z","steps":["trace[819481910] 'process raft request' (duration: 574.407274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:45.58424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:45.00912Z","time spent":"575.083692ms","remote":"127.0.0.1:53012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T00:52:45.583463Z","caller":"traceutil/trace.go:171","msg":"trace[746879618] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"604.156018ms","start":"2026-07-01T00:52:44.979263Z","end":"2026-07-01T00:52:45.583419Z","steps":["trace[746879618] 'process raft request' (duration: 603.946623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:45.584347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:44.979234Z","time spent":"605.093411ms","remote":"127.0.0.1:53012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T00:52:45.583549Z","caller":"traceutil/trace.go:171","msg":"trace[164786254] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"590.283051ms","start":"2026-07-01T00:52:44.993257Z","end":"2026-07-01T00:52:45.58354Z","steps":["trace[164786254] 'process raft request' (duration: 590.090746ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:52:45.583597Z","caller":"traceutil/trace.go:171","msg":"trace[2065245527] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"597.555437ms","start":"2026-07-01T00:52:44.986034Z","end":"2026-07-01T00:52:45.58359Z","steps":["trace[2065245527] 'process raft request' (duration: 597.216919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:45.585184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:44.986013Z","time spent":"599.106665ms","remote":"127.0.0.1:53012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T00:52:45.585199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:44.993237Z","time spent":"591.83889ms","remote":"127.0.0.1:53012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T00:52:45.587207Z","caller":"traceutil/trace.go:171","msg":"trace[1510126230] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:505; }","duration":"501.877434ms","start":"2026-07-01T00:52:45.085313Z","end":"2026-07-01T00:52:45.58719Z","steps":["trace[1510126230] 'read index received' (duration: 156.234963ms)","trace[1510126230] 'applied index is now lower than readState.Index' (duration: 345.641861ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:52:45.587291Z","caller":"traceutil/trace.go:171","msg":"trace[861750876] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"534.68933ms","start":"2026-07-01T00:52:45.052595Z","end":"2026-07-01T00:52:45.587285Z","steps":["trace[861750876] 'process raft request' (duration: 534.467615ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:52:45.587351Z","caller":"traceutil/trace.go:171","msg":"trace[568707229] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"428.047511ms","start":"2026-07-01T00:52:45.159292Z","end":"2026-07-01T00:52:45.587339Z","steps":["trace[568707229] 'process raft request' (duration: 427.853116ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:45.587413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:45.159273Z","time spent":"428.118513ms","remote":"127.0.0.1:53012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T00:52:45.587419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.111059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-01T00:52:45.587356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:45.052571Z","time spent":"534.740401ms","remote":"127.0.0.1:53012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T00:52:45.587603Z","caller":"traceutil/trace.go:171","msg":"trace[275395850] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:500; }","duration":"502.326796ms","start":"2026-07-01T00:52:45.085245Z","end":"2026-07-01T00:52:45.587572Z","steps":["trace[275395850] 'agreement among raft nodes before linearized reading' (duration: 502.129021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:45.587667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:45.085217Z","time spent":"502.429578ms","remote":"127.0.0.1:53246","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-07-01T00:52:45.58777Z","caller":"traceutil/trace.go:171","msg":"trace[1031711886] transaction","detail":"{read_only:false; number_of_response:1; response_revision:502; }","duration":"338.608701ms","start":"2026-07-01T00:52:45.24915Z","end":"2026-07-01T00:52:45.587759Z","steps":["trace[1031711886] 'process raft request' (duration: 338.57567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:45.587801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.391686ms","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-07-01T00:52:45.587825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:45.249139Z","time spent":"338.655613ms","remote":"127.0.0.1:53012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7365,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:52:45.587835Z","caller":"traceutil/trace.go:171","msg":"trace[283471791] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:502; }","duration":"364.430247ms","start":"2026-07-01T00:52:45.223395Z","end":"2026-07-01T00:52:45.587825Z","steps":["trace[283471791] 'agreement among raft nodes before linearized reading' (duration: 364.355706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:45.587903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:45.223359Z","time spent":"364.53802ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-01T00:52:45.587889Z","caller":"traceutil/trace.go:171","msg":"trace[1248141697] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"339.321908ms","start":"2026-07-01T00:52:45.248557Z","end":"2026-07-01T00:52:45.587879Z","steps":["trace[1248141697] 'process raft request' (duration: 339.128353ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:45.587969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.799422ms","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-07-01T00:52:45.58809Z","caller":"traceutil/trace.go:171","msg":"trace[1880070840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:502; }","duration":"351.966786ms","start":"2026-07-01T00:52:45.236115Z","end":"2026-07-01T00:52:45.588082Z","steps":["trace[1880070840] 'agreement among raft nodes before linearized reading' (duration: 351.826562ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:45.588117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:45.236094Z","time spent":"352.016567ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-01T00:52:45.587989Z","caller":"traceutil/trace.go:171","msg":"trace[355575035] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"341.551642ms","start":"2026-07-01T00:52:45.246418Z","end":"2026-07-01T00:52:45.587969Z","steps":["trace[355575035] 'process raft request' (duration: 341.187163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:45.588234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:45.246392Z","time spent":"341.821389ms","remote":"127.0.0.1:53520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T00:52:45.588041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:45.248545Z","time spent":"339.438521ms","remote":"127.0.0.1:53012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:52:52.223239Z","caller":"traceutil/trace.go:171","msg":"trace[93314220] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"346.713077ms","start":"2026-07-01T00:52:51.876501Z","end":"2026-07-01T00:52:52.223214Z","steps":["trace[93314220] 'process raft request' (duration: 346.168984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:52.223421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:51.876471Z","time spent":"346.844521ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:52:52.799139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.881278ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T00:52:52.799353Z","caller":"traceutil/trace.go:171","msg":"trace[2071620680] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"558.083339ms","start":"2026-07-01T00:52:52.241242Z","end":"2026-07-01T00:52:52.799325Z","steps":["trace[2071620680] 'process raft request' (duration: 290.894523ms)","trace[2071620680] 'compare' (duration: 266.724695ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:52:52.79954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:52.24121Z","time spent":"558.217022ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:52:52.804292Z","caller":"traceutil/trace.go:171","msg":"trace[1305137760] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:624; }","duration":"500.951082ms","start":"2026-07-01T00:52:52.303325Z","end":"2026-07-01T00:52:52.804276Z","steps":["trace[1305137760] 'read index received' (duration: 228.757014ms)","trace[1305137760] 'applied index is now lower than readState.Index' (duration: 272.193128ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:52:52.804411Z","caller":"traceutil/trace.go:171","msg":"trace[1485473169] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"517.380511ms","start":"2026-07-01T00:52:52.286993Z","end":"2026-07-01T00:52:52.804374Z","steps":["trace[1485473169] 'process raft request' (duration: 517.164145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:52.804552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.234798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T00:52:52.804569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:52.286957Z","time spent":"517.544495ms","remote":"127.0.0.1:53212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T00:52:52.804572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.239539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-01T00:52:52.804623Z","caller":"traceutil/trace.go:171","msg":"trace[1301791499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:602; }","duration":"501.320721ms","start":"2026-07-01T00:52:52.303289Z","end":"2026-07-01T00:52:52.804609Z","steps":["trace[1301791499] 'agreement among raft nodes before linearized reading' (duration: 501.094996ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:52:52.804622Z","caller":"traceutil/trace.go:171","msg":"trace[1996232548] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"267.335351ms","start":"2026-07-01T00:52:52.53726Z","end":"2026-07-01T00:52:52.804595Z","steps":["trace[1996232548] 'agreement among raft nodes before linearized reading' (duration: 267.225058ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:52.804677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:52.303274Z","time spent":"501.396173ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-01T00:52:52.805145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.996129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-cr7l4\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-01T00:52:52.805235Z","caller":"traceutil/trace.go:171","msg":"trace[1303586682] range","detail":"{range_begin:/registry/pods/kube-system/cilium-cr7l4; range_end:; response_count:1; response_revision:602; }","duration":"257.384449ms","start":"2026-07-01T00:52:52.547832Z","end":"2026-07-01T00:52:52.805217Z","steps":["trace[1303586682] 'agreement among raft nodes before linearized reading' (duration: 256.671682ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:54.037628Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.140436996s","expected-duration":"1s"} {"level":"info","ts":"2026-07-01T00:52:54.065967Z","caller":"traceutil/trace.go:171","msg":"trace[1316495129] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"527.358692ms","start":"2026-07-01T00:52:53.538587Z","end":"2026-07-01T00:52:54.065945Z","steps":["trace[1316495129] 'read index received' (duration: 500.013448ms)","trace[1316495129] 'applied index is now lower than readState.Index' (duration: 27.343674ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:52:54.066079Z","caller":"traceutil/trace.go:171","msg":"trace[1430699616] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"1.16899474s","start":"2026-07-01T00:52:52.897056Z","end":"2026-07-01T00:52:54.06605Z","steps":["trace[1430699616] 'process raft request' (duration: 1.141607145s)","trace[1430699616] 'compare' (duration: 26.847632ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:52:54.066194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:52.897038Z","time spent":"1.169102902s","remote":"127.0.0.1:53150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T00:52:54.066555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.72437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-01T00:52:54.066623Z","caller":"traceutil/trace.go:171","msg":"trace[145960835] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:605; }","duration":"391.834823ms","start":"2026-07-01T00:52:53.674768Z","end":"2026-07-01T00:52:54.066603Z","steps":["trace[145960835] 'agreement among raft nodes before linearized reading' (duration: 391.642368ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:54.066656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:53.674732Z","time spent":"391.917125ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-01T00:52:54.06676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.349653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-01T00:52:54.066811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.228043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:52:54.06683Z","caller":"traceutil/trace.go:171","msg":"trace[1977703117] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:605; }","duration":"415.440915ms","start":"2026-07-01T00:52:53.651371Z","end":"2026-07-01T00:52:54.066812Z","steps":["trace[1977703117] 'agreement among raft nodes before linearized reading' (duration: 415.095627ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:52:54.066855Z","caller":"traceutil/trace.go:171","msg":"trace[869059154] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"528.348176ms","start":"2026-07-01T00:52:53.538495Z","end":"2026-07-01T00:52:54.066843Z","steps":["trace[869059154] 'agreement among raft nodes before linearized reading' (duration: 527.586707ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:54.066885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:53.651363Z","time spent":"415.510196ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-01T00:52:54.066892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:53.53846Z","time spent":"528.425428ms","remote":"127.0.0.1:52988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T00:52:54.067031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.274715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-01T00:52:54.067068Z","caller":"traceutil/trace.go:171","msg":"trace[469291429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:605; }","duration":"416.350247ms","start":"2026-07-01T00:52:53.650708Z","end":"2026-07-01T00:52:54.067059Z","steps":["trace[469291429] 'agreement among raft nodes before linearized reading' (duration: 415.699411ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:54.067097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:53.650684Z","time spent":"416.407479ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-01T00:52:56.068563Z","caller":"traceutil/trace.go:171","msg":"trace[655036874] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"185.126114ms","start":"2026-07-01T00:52:55.883411Z","end":"2026-07-01T00:52:56.068537Z","steps":["trace[655036874] 'process raft request' (duration: 184.97468ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:52:56.288976Z","caller":"traceutil/trace.go:171","msg":"trace[246982802] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"212.506459ms","start":"2026-07-01T00:52:56.076444Z","end":"2026-07-01T00:52:56.28895Z","steps":["trace[246982802] 'process raft request' (duration: 136.969135ms)","trace[246982802] 'compare' (duration: 75.399071ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:52:56.341434Z","caller":"traceutil/trace.go:171","msg":"trace[1327901409] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:670; }","duration":"237.187537ms","start":"2026-07-01T00:52:56.104226Z","end":"2026-07-01T00:52:56.341414Z","steps":["trace[1327901409] 'read index received' (duration: 109.195591ms)","trace[1327901409] 'applied index is now lower than readState.Index' (duration: 127.990896ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:52:56.34163Z","caller":"traceutil/trace.go:171","msg":"trace[902308380] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"260.465143ms","start":"2026-07-01T00:52:56.081155Z","end":"2026-07-01T00:52:56.34162Z","steps":["trace[902308380] 'process raft request' (duration: 260.166826ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:56.341915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.660869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-01T00:52:56.341994Z","caller":"traceutil/trace.go:171","msg":"trace[1605700987] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:647; }","duration":"237.801033ms","start":"2026-07-01T00:52:56.104167Z","end":"2026-07-01T00:52:56.341968Z","steps":["trace[1605700987] 'agreement among raft nodes before linearized reading' (duration: 237.468874ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:56.37377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.616141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-01T00:52:56.373845Z","caller":"traceutil/trace.go:171","msg":"trace[1143961371] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:649; }","duration":"268.730144ms","start":"2026-07-01T00:52:56.105098Z","end":"2026-07-01T00:52:56.373828Z","steps":["trace[1143961371] 'agreement among raft nodes before linearized reading' (duration: 268.503939ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:56.373812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.218262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-01T00:52:56.37398Z","caller":"traceutil/trace.go:171","msg":"trace[98912690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:649; }","duration":"268.377996ms","start":"2026-07-01T00:52:56.105553Z","end":"2026-07-01T00:52:56.373931Z","steps":["trace[98912690] 'agreement among raft nodes before linearized reading' (duration: 268.14024ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:06.141884Z","caller":"traceutil/trace.go:171","msg":"trace[1399538181] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"451.580644ms","start":"2026-07-01T00:53:05.690276Z","end":"2026-07-01T00:53:06.141856Z","steps":["trace[1399538181] 'process raft request' (duration: 429.843686ms)","trace[1399538181] 'compare' (duration: 21.146193ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:53:06.142048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:05.69026Z","time spent":"451.708796ms","remote":"127.0.0.1:53150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4854,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:53:06.369449Z","caller":"traceutil/trace.go:171","msg":"trace[1342916723] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"222.281016ms","start":"2026-07-01T00:53:06.147139Z","end":"2026-07-01T00:53:06.36942Z","steps":["trace[1342916723] 'process raft request' (duration: 204.221058ms)","trace[1342916723] 'compare' (duration: 17.864383ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:53:06.369677Z","caller":"traceutil/trace.go:171","msg":"trace[1422425925] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"218.513305ms","start":"2026-07-01T00:53:06.151137Z","end":"2026-07-01T00:53:06.36965Z","steps":["trace[1422425925] 'process raft request' (duration: 218.232679ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:06.59036Z","caller":"traceutil/trace.go:171","msg":"trace[2102968137] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"132.350883ms","start":"2026-07-01T00:53:06.457976Z","end":"2026-07-01T00:53:06.590327Z","steps":["trace[2102968137] 'process raft request' (duration: 95.042858ms)","trace[2102968137] 'compare' (duration: 37.159472ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:53:06.608838Z","caller":"traceutil/trace.go:171","msg":"trace[253889791] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"150.7964ms","start":"2026-07-01T00:53:06.458015Z","end":"2026-07-01T00:53:06.608812Z","steps":["trace[253889791] 'process raft request' (duration: 150.38727ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:06.608875Z","caller":"traceutil/trace.go:171","msg":"trace[2060094685] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"150.693628ms","start":"2026-07-01T00:53:06.458145Z","end":"2026-07-01T00:53:06.608839Z","steps":["trace[2060094685] 'process raft request' (duration: 150.37411ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:09.248276Z","caller":"traceutil/trace.go:171","msg":"trace[1390629620] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"322.406677ms","start":"2026-07-01T00:53:08.925837Z","end":"2026-07-01T00:53:09.248243Z","steps":["trace[1390629620] 'process raft request' (duration: 322.267904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:09.248414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:08.92582Z","time spent":"322.525849ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:53:09.604928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.725883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-01T00:53:09.605023Z","caller":"traceutil/trace.go:171","msg":"trace[905036357] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:768; }","duration":"256.856355ms","start":"2026-07-01T00:53:09.348134Z","end":"2026-07-01T00:53:09.604991Z","steps":["trace[905036357] 'range keys from in-memory index tree' (duration: 256.547488ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:16.027231Z","caller":"traceutil/trace.go:171","msg":"trace[1986158123] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"137.400435ms","start":"2026-07-01T00:53:15.889745Z","end":"2026-07-01T00:53:16.027145Z","steps":["trace[1986158123] 'process raft request' (duration: 137.18173ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:42.947263Z","caller":"traceutil/trace.go:171","msg":"trace[209712330] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"163.607026ms","start":"2026-07-01T00:53:42.78364Z","end":"2026-07-01T00:53:42.947247Z","steps":["trace[209712330] 'process raft request' (duration: 163.273078ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:43.822157Z","caller":"traceutil/trace.go:171","msg":"trace[1921869297] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1015; }","duration":"285.101918ms","start":"2026-07-01T00:53:43.53704Z","end":"2026-07-01T00:53:43.822142Z","steps":["trace[1921869297] 'read index received' (duration: 285.055387ms)","trace[1921869297] 'applied index is now lower than readState.Index' (duration: 45.891µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:53:43.822202Z","caller":"traceutil/trace.go:171","msg":"trace[851267899] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"349.075444ms","start":"2026-07-01T00:53:43.473106Z","end":"2026-07-01T00:53:43.822182Z","steps":["trace[851267899] 'process raft request' (duration: 348.93876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:43.822271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.222731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:53:43.822291Z","caller":"traceutil/trace.go:171","msg":"trace[629672353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:980; }","duration":"285.275942ms","start":"2026-07-01T00:53:43.53701Z","end":"2026-07-01T00:53:43.822286Z","steps":["trace[629672353] 'agreement among raft nodes before linearized reading' (duration: 285.235941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:43.82234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:43.473084Z","time spent":"349.161596ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:53:44.227776Z","caller":"traceutil/trace.go:171","msg":"trace[1808257057] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"401.560615ms","start":"2026-07-01T00:53:43.826193Z","end":"2026-07-01T00:53:44.227754Z","steps":["trace[1808257057] 'process raft request' (duration: 401.385601ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:44.22793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:43.826182Z","time spent":"401.676899ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:53:44.561533Z","caller":"traceutil/trace.go:171","msg":"trace[1790437760] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"717.707435ms","start":"2026-07-01T00:53:43.843806Z","end":"2026-07-01T00:53:44.561513Z","steps":["trace[1790437760] 'process raft request' (duration: 684.560892ms)","trace[1790437760] 'compare' (duration: 32.770073ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:53:44.561562Z","caller":"traceutil/trace.go:171","msg":"trace[1528333050] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"596.682054ms","start":"2026-07-01T00:53:43.964856Z","end":"2026-07-01T00:53:44.561538Z","steps":["trace[1528333050] 'process raft request' (duration: 596.394617ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:44.561634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:43.843779Z","time spent":"717.802717ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:53:44.561696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:43.96483Z","time spent":"596.793446ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:53:56.738074Z","caller":"traceutil/trace.go:171","msg":"trace[307442881] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"121.75323ms","start":"2026-07-01T00:53:56.616293Z","end":"2026-07-01T00:53:56.738047Z","steps":["trace[307442881] 'process raft request' (duration: 121.572965ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:58.095925Z","caller":"traceutil/trace.go:171","msg":"trace[1071277141] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"212.11223ms","start":"2026-07-01T00:53:57.883786Z","end":"2026-07-01T00:53:58.095898Z","steps":["trace[1071277141] 'process raft request' (duration: 211.952106ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:58.42898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.749798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-01T00:53:58.429067Z","caller":"traceutil/trace.go:171","msg":"trace[801317922] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1026; }","duration":"100.896732ms","start":"2026-07-01T00:53:58.328153Z","end":"2026-07-01T00:53:58.42905Z","steps":["trace[801317922] 'range keys from in-memory index tree' (duration: 100.578093ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:07.619408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.731051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T00:54:07.619514Z","caller":"traceutil/trace.go:171","msg":"trace[798910992] linearizableReadLoop","detail":"{readStateIndex:1096; appliedIndex:1095; }","duration":"558.099837ms","start":"2026-07-01T00:54:07.061397Z","end":"2026-07-01T00:54:07.619497Z","steps":["trace[798910992] 'read index received' (duration: 402.134401ms)","trace[798910992] 'applied index is now lower than readState.Index' (duration: 155.963886ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:54:07.619759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.975423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T00:54:07.619802Z","caller":"traceutil/trace.go:171","msg":"trace[370848634] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1055; }","duration":"510.041106ms","start":"2026-07-01T00:54:07.109749Z","end":"2026-07-01T00:54:07.61979Z","steps":["trace[370848634] 'agreement among raft nodes before linearized reading' (duration: 509.973004ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:07.619836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:07.109737Z","time spent":"510.090486ms","remote":"127.0.0.1:53230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-07-01T00:54:07.61997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.551268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-01T00:54:07.620094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.364877ms","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-07-01T00:54:07.620113Z","caller":"traceutil/trace.go:171","msg":"trace[1015697804] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1055; }","duration":"558.700002ms","start":"2026-07-01T00:54:07.061349Z","end":"2026-07-01T00:54:07.620049Z","steps":["trace[1015697804] 'agreement among raft nodes before linearized reading' (duration: 558.245731ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:07.620225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:07.06133Z","time spent":"558.874517ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-01T00:54:07.62033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.608389ms","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-07-01T00:54:07.620403Z","caller":"traceutil/trace.go:171","msg":"trace[1902783589] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"810.153707ms","start":"2026-07-01T00:54:06.810194Z","end":"2026-07-01T00:54:07.620348Z","steps":["trace[1902783589] 'process raft request' (duration: 653.38666ms)","trace[1902783589] 'compare' (duration: 155.557977ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:54:07.620437Z","caller":"traceutil/trace.go:171","msg":"trace[1602772372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1055; }","duration":"468.769183ms","start":"2026-07-01T00:54:07.151647Z","end":"2026-07-01T00:54:07.620416Z","steps":["trace[1602772372] 'agreement among raft nodes before linearized reading' (duration: 468.450084ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:07.62051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:07.151626Z","time spent":"468.875146ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-01T00:54:07.620129Z","caller":"traceutil/trace.go:171","msg":"trace[862630114] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1055; }","duration":"419.440119ms","start":"2026-07-01T00:54:07.20068Z","end":"2026-07-01T00:54:07.620121Z","steps":["trace[862630114] 'agreement among raft nodes before linearized reading' (duration: 419.326446ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:07.620595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:06.810173Z","time spent":"810.327752ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:54:07.620658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:07.200669Z","time spent":"419.937782ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-01T00:54:07.805164Z","caller":"traceutil/trace.go:171","msg":"trace[932796833] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"179.512942ms","start":"2026-07-01T00:54:07.625625Z","end":"2026-07-01T00:54:07.805138Z","steps":["trace[932796833] 'process raft request' (duration: 179.4435ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:54:07.805222Z","caller":"traceutil/trace.go:171","msg":"trace[1930556618] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"180.077075ms","start":"2026-07-01T00:54:07.625115Z","end":"2026-07-01T00:54:07.805192Z","steps":["trace[1930556618] 'process raft request' (duration: 97.915187ms)","trace[1930556618] 'compare' (duration: 81.789168ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:54:09.018281Z","caller":"traceutil/trace.go:171","msg":"trace[334912507] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"407.840932ms","start":"2026-07-01T00:54:08.610417Z","end":"2026-07-01T00:54:09.018258Z","steps":["trace[334912507] 'process raft request' (duration: 407.660298ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:09.018438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:08.610401Z","time spent":"407.954996ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:54:11.220675Z","caller":"traceutil/trace.go:171","msg":"trace[1535836950] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"189.93953ms","start":"2026-07-01T00:54:11.030701Z","end":"2026-07-01T00:54:11.220641Z","steps":["trace[1535836950] 'process raft request' (duration: 189.750105ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:26.528162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.802483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-01T00:54:26.528484Z","caller":"traceutil/trace.go:171","msg":"trace[1673491788] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1170; }","duration":"258.158412ms","start":"2026-07-01T00:54:26.270301Z","end":"2026-07-01T00:54:26.528459Z","steps":["trace[1673491788] 'range keys from in-memory index tree' (duration: 257.639049ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:54:26.875687Z","caller":"traceutil/trace.go:171","msg":"trace[1890311068] linearizableReadLoop","detail":"{readStateIndex:1218; appliedIndex:1217; }","duration":"339.032681ms","start":"2026-07-01T00:54:26.536615Z","end":"2026-07-01T00:54:26.875648Z","steps":["trace[1890311068] 'read index received' (duration: 338.865077ms)","trace[1890311068] 'applied index is now lower than readState.Index' (duration: 166.934µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:54:26.875725Z","caller":"traceutil/trace.go:171","msg":"trace[1599587418] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"339.824001ms","start":"2026-07-01T00:54:26.535871Z","end":"2026-07-01T00:54:26.875695Z","steps":["trace[1599587418] 'process raft request' (duration: 339.667707ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:26.875877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.621825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T00:54:26.875907Z","caller":"traceutil/trace.go:171","msg":"trace[812410670] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1171; }","duration":"272.684486ms","start":"2026-07-01T00:54:26.603214Z","end":"2026-07-01T00:54:26.875898Z","steps":["trace[812410670] 'agreement among raft nodes before linearized reading' (duration: 272.638875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:26.87594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:26.535849Z","time spent":"340.015046ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:54:26.875829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.202016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:54:26.876168Z","caller":"traceutil/trace.go:171","msg":"trace[411582929] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1171; }","duration":"339.572915ms","start":"2026-07-01T00:54:26.536581Z","end":"2026-07-01T00:54:26.876154Z","steps":["trace[411582929] 'agreement among raft nodes before linearized reading' (duration: 339.181595ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:26.876209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:26.536568Z","time spent":"339.631027ms","remote":"127.0.0.1:52988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-01T00:54:39.690899Z","caller":"traceutil/trace.go:171","msg":"trace[1305599668] linearizableReadLoop","detail":"{readStateIndex:1260; appliedIndex:1259; }","duration":"161.223697ms","start":"2026-07-01T00:54:39.529652Z","end":"2026-07-01T00:54:39.690875Z","steps":["trace[1305599668] 'read index received' (duration: 161.031252ms)","trace[1305599668] 'applied index is now lower than readState.Index' (duration: 191.595µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:54:39.691035Z","caller":"traceutil/trace.go:171","msg":"trace[556959055] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"261.834897ms","start":"2026-07-01T00:54:39.429164Z","end":"2026-07-01T00:54:39.690999Z","steps":["trace[556959055] 'process raft request' (duration: 261.5758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:39.691119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.448012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.156\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-01T00:54:39.691186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.387568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:54:39.691191Z","caller":"traceutil/trace.go:171","msg":"trace[982982990] range","detail":"{range_begin:/registry/masterleases/199.204.45.156; range_end:; response_count:1; response_revision:1211; }","duration":"161.584197ms","start":"2026-07-01T00:54:39.529596Z","end":"2026-07-01T00:54:39.69118Z","steps":["trace[982982990] 'agreement among raft nodes before linearized reading' (duration: 161.401922ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:54:39.691238Z","caller":"traceutil/trace.go:171","msg":"trace[1457843931] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1211; }","duration":"152.458661ms","start":"2026-07-01T00:54:39.538764Z","end":"2026-07-01T00:54:39.691223Z","steps":["trace[1457843931] 'agreement among raft nodes before linearized reading' (duration: 152.351368ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:54:44.032905Z","caller":"traceutil/trace.go:171","msg":"trace[1806278576] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"156.466544ms","start":"2026-07-01T00:54:43.876421Z","end":"2026-07-01T00:54:44.032888Z","steps":["trace[1806278576] 'process raft request' (duration: 156.325171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:45.721432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.494932ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-01T00:54:45.778431Z","caller":"traceutil/trace.go:171","msg":"trace[1417068678] linearizableReadLoop","detail":"{readStateIndex:1301; appliedIndex:1299; }","duration":"823.856241ms","start":"2026-07-01T00:54:44.954554Z","end":"2026-07-01T00:54:45.778411Z","steps":["trace[1417068678] 'read index received' (duration: 381.372136ms)","trace[1417068678] 'applied index is now lower than readState.Index' (duration: 442.483115ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:54:45.778577Z","caller":"traceutil/trace.go:171","msg":"trace[1574199095] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"991.819832ms","start":"2026-07-01T00:54:44.786745Z","end":"2026-07-01T00:54:45.778565Z","steps":["trace[1574199095] 'process raft request' (duration: 991.502504ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:45.778653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.995014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T00:54:45.778909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.340203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-01T00:54:45.778979Z","caller":"traceutil/trace.go:171","msg":"trace[1522604487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1250; }","duration":"824.461296ms","start":"2026-07-01T00:54:44.954501Z","end":"2026-07-01T00:54:45.778962Z","steps":["trace[1522604487] 'agreement among raft nodes before linearized reading' (duration: 824.234901ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:45.778958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.051293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T00:54:45.779021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:44.954472Z","time spent":"824.540859ms","remote":"127.0.0.1:53246","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-07-01T00:54:45.779036Z","caller":"traceutil/trace.go:171","msg":"trace[813228045] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1250; }","duration":"589.180647ms","start":"2026-07-01T00:54:45.189837Z","end":"2026-07-01T00:54:45.779018Z","steps":["trace[813228045] 'agreement among raft nodes before linearized reading' (duration: 589.027333ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:54:45.778731Z","caller":"traceutil/trace.go:171","msg":"trace[956667916] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1250; }","duration":"651.132558ms","start":"2026-07-01T00:54:45.12758Z","end":"2026-07-01T00:54:45.778713Z","steps":["trace[956667916] 'agreement among raft nodes before linearized reading' (duration: 650.992984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:45.77908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:45.189823Z","time spent":"589.248108ms","remote":"127.0.0.1:53094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-07-01T00:54:45.778683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:44.786721Z","time spent":"991.889684ms","remote":"127.0.0.1:53138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5640,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:54:45.779383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:45.127557Z","time spent":"651.551599ms","remote":"127.0.0.1:41882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"info","ts":"2026-07-01T00:54:46.056029Z","caller":"traceutil/trace.go:171","msg":"trace[986842036] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"269.776221ms","start":"2026-07-01T00:54:45.786233Z","end":"2026-07-01T00:54:46.05601Z","steps":["trace[986842036] 'process raft request' (duration: 269.698109ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:54:46.056062Z","caller":"traceutil/trace.go:171","msg":"trace[381424833] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"272.145063ms","start":"2026-07-01T00:54:45.783892Z","end":"2026-07-01T00:54:46.056037Z","steps":["trace[381424833] 'process raft request' (duration: 212.265665ms)","trace[381424833] 'compare' (duration: 59.535999ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:54:46.52584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.80391ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T00:54:46.525942Z","caller":"traceutil/trace.go:171","msg":"trace[1248380374] linearizableReadLoop","detail":"{readStateIndex:1305; appliedIndex:1304; }","duration":"464.470644ms","start":"2026-07-01T00:54:46.061455Z","end":"2026-07-01T00:54:46.525926Z","steps":["trace[1248380374] 'read index received' (duration: 268.450148ms)","trace[1248380374] 'applied index is now lower than readState.Index' (duration: 196.019416ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:54:46.526184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.144777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-01T00:54:46.526247Z","caller":"traceutil/trace.go:171","msg":"trace[2116776960] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1254; }","duration":"437.245569ms","start":"2026-07-01T00:54:46.088983Z","end":"2026-07-01T00:54:46.526228Z","steps":["trace[2116776960] 'agreement among raft nodes before linearized reading' (duration: 437.044254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:46.526321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:46.088961Z","time spent":"437.342372ms","remote":"127.0.0.1:53246","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-07-01T00:54:46.526324Z","caller":"traceutil/trace.go:171","msg":"trace[661084798] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"486.696228ms","start":"2026-07-01T00:54:46.039603Z","end":"2026-07-01T00:54:46.526299Z","steps":["trace[661084798] 'process raft request' (duration: 290.353684ms)","trace[661084798] 'compare' (duration: 195.538203ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:54:46.526234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.73395ms","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-07-01T00:54:46.52644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:46.039579Z","time spent":"486.799201ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:54:46.52648Z","caller":"traceutil/trace.go:171","msg":"trace[1858301966] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1254; }","duration":"465.033978ms","start":"2026-07-01T00:54:46.061424Z","end":"2026-07-01T00:54:46.526457Z","steps":["trace[1858301966] 'agreement among raft nodes before linearized reading' (duration: 464.598477ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:46.526549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:46.061412Z","time spent":"465.11761ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-01T00:54:46.526603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.165742ms","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-07-01T00:54:46.526688Z","caller":"traceutil/trace.go:171","msg":"trace[1589475333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1254; }","duration":"436.298226ms","start":"2026-07-01T00:54:46.090369Z","end":"2026-07-01T00:54:46.526667Z","steps":["trace[1589475333] 'agreement among raft nodes before linearized reading' (duration: 436.043679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:46.526744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:46.090351Z","time spent":"436.381657ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-01T00:54:46.527284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.055397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T00:54:46.527352Z","caller":"traceutil/trace.go:171","msg":"trace[1736261565] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1254; }","duration":"107.16389ms","start":"2026-07-01T00:54:46.420171Z","end":"2026-07-01T00:54:46.527335Z","steps":["trace[1736261565] 'agreement among raft nodes before linearized reading' (duration: 107.054177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:46.81686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.267197ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T00:54:46.816954Z","caller":"traceutil/trace.go:171","msg":"trace[1401289660] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"286.769881ms","start":"2026-07-01T00:54:46.530167Z","end":"2026-07-01T00:54:46.816937Z","steps":["trace[1401289660] 'process raft request' (duration: 185.35405ms)","trace[1401289660] 'compare' (duration: 101.169635ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:54:47.027959Z","caller":"traceutil/trace.go:171","msg":"trace[524048795] linearizableReadLoop","detail":"{readStateIndex:1308; appliedIndex:1305; }","duration":"489.258384ms","start":"2026-07-01T00:54:46.538665Z","end":"2026-07-01T00:54:47.027923Z","steps":["trace[524048795] 'read index received' (duration: 176.86938ms)","trace[524048795] 'applied index is now lower than readState.Index' (duration: 312.387994ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:54:47.028016Z","caller":"traceutil/trace.go:171","msg":"trace[24272142] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"496.563032ms","start":"2026-07-01T00:54:46.531414Z","end":"2026-07-01T00:54:47.027977Z","steps":["trace[24272142] 'process raft request' (duration: 415.796955ms)","trace[24272142] 'compare' (duration: 80.521741ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:54:47.028117Z","caller":"traceutil/trace.go:171","msg":"trace[676790394] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"496.425889ms","start":"2026-07-01T00:54:46.53168Z","end":"2026-07-01T00:54:47.028106Z","steps":["trace[676790394] 'process raft request' (duration: 496.185683ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:47.028198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:46.531386Z","time spent":"496.730747ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:54:47.028204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:46.531667Z","time spent":"496.479701ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:54:47.028079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.392717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:54:47.028323Z","caller":"traceutil/trace.go:171","msg":"trace[400938422] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1257; }","duration":"489.658065ms","start":"2026-07-01T00:54:46.538644Z","end":"2026-07-01T00:54:47.028303Z","steps":["trace[400938422] 'agreement among raft nodes before linearized reading' (duration: 489.388808ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:47.02841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:46.538638Z","time spent":"489.734876ms","remote":"127.0.0.1:52988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T00:54:47.537694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.520468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:54:47.537755Z","caller":"traceutil/trace.go:171","msg":"trace[1656468961] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1257; }","duration":"129.595899ms","start":"2026-07-01T00:54:47.408146Z","end":"2026-07-01T00:54:47.537742Z","steps":["trace[1656468961] 'count revisions from in-memory index tree' (duration: 129.470315ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:48.561203Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398341740582328770,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-01T00:54:48.577408Z","caller":"traceutil/trace.go:171","msg":"trace[1237565376] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"756.546542ms","start":"2026-07-01T00:54:47.820832Z","end":"2026-07-01T00:54:48.577379Z","steps":["trace[1237565376] 'process raft request' (duration: 756.358717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:48.577574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:47.820816Z","time spent":"756.668665ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:54:48.626395Z","caller":"traceutil/trace.go:171","msg":"trace[2090371342] linearizableReadLoop","detail":"{readStateIndex:1310; appliedIndex:1308; }","duration":"566.143941ms","start":"2026-07-01T00:54:48.06023Z","end":"2026-07-01T00:54:48.626374Z","steps":["trace[2090371342] 'read index received' (duration: 517.017032ms)","trace[2090371342] 'applied index is now lower than readState.Index' (duration: 49.126129ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:54:48.626598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.353266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-01T00:54:48.626636Z","caller":"traceutil/trace.go:171","msg":"trace[304263284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1259; }","duration":"566.428648ms","start":"2026-07-01T00:54:48.060198Z","end":"2026-07-01T00:54:48.626626Z","steps":["trace[304263284] 'agreement among raft nodes before linearized reading' (duration: 566.293615ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:48.626669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:48.060183Z","time spent":"566.477959ms","remote":"127.0.0.1:53246","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-07-01T00:54:48.776105Z","caller":"traceutil/trace.go:171","msg":"trace[116506852] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"129.209599ms","start":"2026-07-01T00:54:48.646877Z","end":"2026-07-01T00:54:48.776087Z","steps":["trace[116506852] 'process raft request' (duration: 128.732587ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:54:49.770467Z","caller":"traceutil/trace.go:171","msg":"trace[1873271506] linearizableReadLoop","detail":"{readStateIndex:1316; appliedIndex:1315; }","duration":"271.852705ms","start":"2026-07-01T00:54:49.498586Z","end":"2026-07-01T00:54:49.770439Z","steps":["trace[1873271506] 'read index received' (duration: 271.63185ms)","trace[1873271506] 'applied index is now lower than readState.Index' (duration: 219.835µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:54:49.770658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.056651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:54:49.770735Z","caller":"traceutil/trace.go:171","msg":"trace[1988210175] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1265; }","duration":"272.185885ms","start":"2026-07-01T00:54:49.498536Z","end":"2026-07-01T00:54:49.770722Z","steps":["trace[1988210175] 'agreement among raft nodes before linearized reading' (duration: 272.01839ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:49.770801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.035474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.156\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-01T00:54:49.770868Z","caller":"traceutil/trace.go:171","msg":"trace[103848284] range","detail":"{range_begin:/registry/masterleases/199.204.45.156; range_end:; response_count:1; response_revision:1265; }","duration":"240.154367ms","start":"2026-07-01T00:54:49.530702Z","end":"2026-07-01T00:54:49.770856Z","steps":["trace[103848284] 'agreement among raft nodes before linearized reading' (duration: 239.87096ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:49.771135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.180496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:54:49.77118Z","caller":"traceutil/trace.go:171","msg":"trace[973954440] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1265; }","duration":"233.261908ms","start":"2026-07-01T00:54:49.537908Z","end":"2026-07-01T00:54:49.771169Z","steps":["trace[973954440] 'agreement among raft nodes before linearized reading' (duration: 233.198977ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:54:49.771485Z","caller":"traceutil/trace.go:171","msg":"trace[1889939723] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"355.345024ms","start":"2026-07-01T00:54:49.415159Z","end":"2026-07-01T00:54:49.770504Z","steps":["trace[1889939723] 'process raft request' (duration: 355.151659ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:49.771633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:49.415132Z","time spent":"356.40226ms","remote":"127.0.0.1:53048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T00:54:49.996721Z","caller":"traceutil/trace.go:171","msg":"trace[1598693585] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"193.659526ms","start":"2026-07-01T00:54:49.803031Z","end":"2026-07-01T00:54:49.996691Z","steps":["trace[1598693585] 'process raft request' (duration: 193.576873ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:54:49.99674Z","caller":"traceutil/trace.go:171","msg":"trace[868312203] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"218.094037ms","start":"2026-07-01T00:54:49.778621Z","end":"2026-07-01T00:54:49.996715Z","steps":["trace[868312203] 'process raft request' (duration: 201.695482ms)","trace[868312203] 'compare' (duration: 16.084106ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:54:50.002676Z","caller":"traceutil/trace.go:171","msg":"trace[1809174702] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"197.969576ms","start":"2026-07-01T00:54:49.804352Z","end":"2026-07-01T00:54:50.002322Z","steps":["trace[1809174702] 'process raft request' (duration: 197.814231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:55.152276Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398341740582328851,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-01T00:54:55.613847Z","caller":"traceutil/trace.go:171","msg":"trace[881802420] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"962.280959ms","start":"2026-07-01T00:54:54.651542Z","end":"2026-07-01T00:54:55.613823Z","steps":["trace[881802420] 'process raft request' (duration: 962.133716ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:55.614389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:54.651521Z","time spent":"962.418343ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:54:56.566323Z","caller":"traceutil/trace.go:171","msg":"trace[1380954164] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1350; }","duration":"1.914525038s","start":"2026-07-01T00:54:54.651773Z","end":"2026-07-01T00:54:56.566298Z","steps":["trace[1380954164] 'read index received' (duration: 961.92498ms)","trace[1380954164] 'applied index is now lower than readState.Index' (duration: 952.597258ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:54:56.566746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.91496369s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-01T00:54:56.566781Z","caller":"traceutil/trace.go:171","msg":"trace[1359720308] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1299; }","duration":"1.915035591s","start":"2026-07-01T00:54:54.651736Z","end":"2026-07-01T00:54:56.566771Z","steps":["trace[1359720308] 'agreement among raft nodes before linearized reading' (duration: 1.914682452s)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:54:56.566704Z","caller":"traceutil/trace.go:171","msg":"trace[647612468] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"365.857765ms","start":"2026-07-01T00:54:56.20077Z","end":"2026-07-01T00:54:56.566628Z","steps":["trace[647612468] 'process raft request' (duration: 365.472155ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:56.566805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:54.651726Z","time spent":"1.915072403s","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-01T00:54:56.566973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.170377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T00:54:56.566991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.027043452s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T00:54:56.567008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:56.200748Z","time spent":"366.080071ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:54:56.567038Z","caller":"traceutil/trace.go:171","msg":"trace[181025653] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1300; }","duration":"370.257549ms","start":"2026-07-01T00:54:56.196766Z","end":"2026-07-01T00:54:56.567024Z","steps":["trace[181025653] 'agreement among raft nodes before linearized reading' (duration: 370.130636ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:56.567126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.325240478s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-01T00:54:56.56714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.891226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:54:56.567151Z","caller":"traceutil/trace.go:171","msg":"trace[233705734] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1300; }","duration":"1.32530144s","start":"2026-07-01T00:54:55.241844Z","end":"2026-07-01T00:54:56.567146Z","steps":["trace[233705734] 'agreement among raft nodes before linearized reading' (duration: 1.325234558s)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:54:56.567164Z","caller":"traceutil/trace.go:171","msg":"trace[1530662540] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1300; }","duration":"272.131432ms","start":"2026-07-01T00:54:56.295026Z","end":"2026-07-01T00:54:56.567157Z","steps":["trace[1530662540] 'agreement among raft nodes before linearized reading' (duration: 272.082711ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:56.567152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:56.196715Z","time spent":"370.425413ms","remote":"127.0.0.1:53124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-07-01T00:54:56.567175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:55.241826Z","time spent":"1.325343612s","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-01T00:54:56.567135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.491032533s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-01T00:54:56.567045Z","caller":"traceutil/trace.go:171","msg":"trace[873523876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1300; }","duration":"1.027178546s","start":"2026-07-01T00:54:55.539854Z","end":"2026-07-01T00:54:56.567032Z","steps":["trace[873523876] 'agreement among raft nodes before linearized reading' (duration: 1.027084714s)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:54:56.567244Z","caller":"traceutil/trace.go:171","msg":"trace[580096418] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1300; }","duration":"1.491163116s","start":"2026-07-01T00:54:55.076069Z","end":"2026-07-01T00:54:56.567232Z","steps":["trace[580096418] 'agreement among raft nodes before linearized reading' (duration: 1.490878079s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:56.567336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:55.075995Z","time spent":"1.49132898s","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-01T00:54:56.567282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:55.539833Z","time spent":"1.027437663s","remote":"127.0.0.1:52988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T00:54:56.567842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.828151135s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-01T00:54:56.56789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.314822499s","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-07-01T00:54:56.567903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.637761804s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-01T00:54:56.567923Z","caller":"traceutil/trace.go:171","msg":"trace[1282797867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1300; }","duration":"1.31489795s","start":"2026-07-01T00:54:55.253017Z","end":"2026-07-01T00:54:56.567915Z","steps":["trace[1282797867] 'agreement among raft nodes before linearized reading' (duration: 1.313841034s)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:54:56.567934Z","caller":"traceutil/trace.go:171","msg":"trace[1377927028] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1300; }","duration":"1.637858407s","start":"2026-07-01T00:54:54.930067Z","end":"2026-07-01T00:54:56.567925Z","steps":["trace[1377927028] 'agreement among raft nodes before linearized reading' (duration: 1.636909843s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:56.567943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:55.252998Z","time spent":"1.314940102s","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-01T00:54:56.567985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:54.930044Z","time spent":"1.637913449s","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-01T00:54:56.567981Z","caller":"traceutil/trace.go:171","msg":"trace[218320408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1300; }","duration":"1.828264518s","start":"2026-07-01T00:54:54.739642Z","end":"2026-07-01T00:54:56.567906Z","steps":["trace[218320408] 'agreement among raft nodes before linearized reading' (duration: 1.827209251s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:56.568147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:54.739622Z","time spent":"1.828457683s","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-01T00:54:57.078431Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398341740582328857,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-01T00:54:58.211475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.614637ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T00:54:58.211665Z","caller":"traceutil/trace.go:171","msg":"trace[2090894843] linearizableReadLoop","detail":"{readStateIndex:1359; appliedIndex:1354; }","duration":"1.633536555s","start":"2026-07-01T00:54:56.578119Z","end":"2026-07-01T00:54:58.211655Z","steps":["trace[2090894843] 'read index received' (duration: 973.651341ms)","trace[2090894843] 'applied index is now lower than readState.Index' (duration: 659.884564ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:54:58.211713Z","caller":"traceutil/trace.go:171","msg":"trace[1178561403] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"1.640107826s","start":"2026-07-01T00:54:56.571599Z","end":"2026-07-01T00:54:58.211707Z","steps":["trace[1178561403] 'process raft request' (duration: 1.639987753s)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:54:58.211705Z","caller":"traceutil/trace.go:171","msg":"trace[971139934] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"1.640117755s","start":"2026-07-01T00:54:56.571567Z","end":"2026-07-01T00:54:58.211685Z","steps":["trace[971139934] 'process raft request' (duration: 1.639974111s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:58.211742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:56.571595Z","time spent":"1.640129716s","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:54:58.2118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:56.571563Z","time spent":"1.640183118s","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:54:58.211815Z","caller":"traceutil/trace.go:171","msg":"trace[808210660] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"1.639710265s","start":"2026-07-01T00:54:56.5721Z","end":"2026-07-01T00:54:58.21181Z","steps":["trace[808210660] 'process raft request' (duration: 1.63950351s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:58.211837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:56.572089Z","time spent":"1.639736216s","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:54:58.211874Z","caller":"traceutil/trace.go:171","msg":"trace[1956487262] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"1.639342916s","start":"2026-07-01T00:54:56.572527Z","end":"2026-07-01T00:54:58.21187Z","steps":["trace[1956487262] 'process raft request' (duration: 1.63910602s)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:54:58.211886Z","caller":"traceutil/trace.go:171","msg":"trace[1311976059] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"1.640395054s","start":"2026-07-01T00:54:56.571483Z","end":"2026-07-01T00:54:58.211878Z","steps":["trace[1311976059] 'process raft request' (duration: 980.322165ms)","trace[1311976059] 'compare' (duration: 659.500494ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:54:58.211919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.633800612s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T00:54:58.211926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:56.571464Z","time spent":"1.640442835s","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:54:58.211933Z","caller":"traceutil/trace.go:171","msg":"trace[543385839] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1306; }","duration":"1.633840343s","start":"2026-07-01T00:54:56.578089Z","end":"2026-07-01T00:54:58.211929Z","steps":["trace[543385839] 'agreement among raft nodes before linearized reading' (duration: 1.633814733s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:58.211946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:56.578077Z","time spent":"1.633867024s","remote":"127.0.0.1:52988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T00:54:58.211894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:56.572514Z","time spent":"1.639367936s","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:54:58.712662Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398341740582328865,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-01T00:54:59.212864Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398341740582328865,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-01T00:54:59.340652Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.721043268s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-01T00:54:59.360832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.026709ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:54:59.360926Z","caller":"traceutil/trace.go:171","msg":"trace[1931537164] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1306; }","duration":"751.130052ms","start":"2026-07-01T00:54:58.609782Z","end":"2026-07-01T00:54:59.360912Z","steps":["trace[1931537164] 'range keys from in-memory index tree' (duration: 751.012949ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:54:59.361041Z","caller":"traceutil/trace.go:171","msg":"trace[2082534171] linearizableReadLoop","detail":"{readStateIndex:1360; appliedIndex:1359; }","duration":"1.149333023s","start":"2026-07-01T00:54:58.211685Z","end":"2026-07-01T00:54:59.361018Z","steps":["trace[2082534171] 'read index received' (duration: 1.12910868s)","trace[2082534171] 'applied index is now lower than readState.Index' (duration: 20.223132ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:54:59.361108Z","caller":"traceutil/trace.go:171","msg":"trace[720684765] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"1.741632779s","start":"2026-07-01T00:54:57.619453Z","end":"2026-07-01T00:54:59.361085Z","steps":["trace[720684765] 'process raft request' (duration: 1.721433247s)","trace[720684765] 'compare' (duration: 19.683529ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:54:59.361231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.713653826s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T00:54:59.36125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.148373588s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:54:59.361272Z","caller":"traceutil/trace.go:171","msg":"trace[52274893] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1307; }","duration":"1.14842736s","start":"2026-07-01T00:54:58.212838Z","end":"2026-07-01T00:54:59.361265Z","steps":["trace[52274893] 'agreement among raft nodes before linearized reading' (duration: 1.148328957s)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:54:59.361264Z","caller":"traceutil/trace.go:171","msg":"trace[943885936] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1307; }","duration":"1.713745059s","start":"2026-07-01T00:54:57.64751Z","end":"2026-07-01T00:54:59.361255Z","steps":["trace[943885936] 'agreement among raft nodes before linearized reading' (duration: 1.713625326s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:59.361293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:58.212823Z","time spent":"1.14846452s","remote":"127.0.0.1:52992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T00:54:59.361306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:57.647488Z","time spent":"1.71380882s","remote":"127.0.0.1:53262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":27,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-01T00:54:59.36141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:57.619432Z","time spent":"1.741770562s","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:54:59.361409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.365032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T00:54:59.361488Z","caller":"traceutil/trace.go:171","msg":"trace[1770540495] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1307; }","duration":"264.494195ms","start":"2026-07-01T00:54:59.096976Z","end":"2026-07-01T00:54:59.361471Z","steps":["trace[1770540495] 'agreement among raft nodes before linearized reading' (duration: 264.352511ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:59.361543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.629495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T00:54:59.361598Z","caller":"traceutil/trace.go:171","msg":"trace[1313341676] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1307; }","duration":"852.700206ms","start":"2026-07-01T00:54:58.508864Z","end":"2026-07-01T00:54:59.361564Z","steps":["trace[1313341676] 'agreement among raft nodes before linearized reading' (duration: 852.654145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:59.361624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:58.508839Z","time spent":"852.777869ms","remote":"127.0.0.1:53412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-01T00:54:59.361481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.706154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-01T00:54:59.36198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.240734778s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-01T00:54:59.361984Z","caller":"traceutil/trace.go:171","msg":"trace[858820833] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1307; }","duration":"779.230149ms","start":"2026-07-01T00:54:58.582734Z","end":"2026-07-01T00:54:59.361964Z","steps":["trace[858820833] 'agreement among raft nodes before linearized reading' (duration: 778.675154ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:54:59.362036Z","caller":"traceutil/trace.go:171","msg":"trace[1334567062] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1307; }","duration":"2.240818181s","start":"2026-07-01T00:54:57.121197Z","end":"2026-07-01T00:54:59.362016Z","steps":["trace[1334567062] 'agreement among raft nodes before linearized reading' (duration: 2.240730559s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:59.362049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:58.582715Z","time spent":"779.314061ms","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-01T00:54:59.362068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:57.121178Z","time spent":"2.240883382s","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-01T00:54:59.361887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.287709ms","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:533"} {"level":"info","ts":"2026-07-01T00:54:59.362117Z","caller":"traceutil/trace.go:171","msg":"trace[935363861] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1307; }","duration":"192.565586ms","start":"2026-07-01T00:54:59.169536Z","end":"2026-07-01T00:54:59.362102Z","steps":["trace[935363861] 'agreement among raft nodes before linearized reading' (duration: 192.219667ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:55:00.32322Z","caller":"traceutil/trace.go:171","msg":"trace[970977164] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"102.88349ms","start":"2026-07-01T00:55:00.220316Z","end":"2026-07-01T00:55:00.3232Z","steps":["trace[970977164] 'process raft request' (duration: 100.282132ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:55:00.323264Z","caller":"traceutil/trace.go:171","msg":"trace[1759153362] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"102.746786ms","start":"2026-07-01T00:55:00.220471Z","end":"2026-07-01T00:55:00.323217Z","steps":["trace[1759153362] 'process raft request' (duration: 102.625523ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:55:00.32341Z","caller":"traceutil/trace.go:171","msg":"trace[218859353] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"101.855383ms","start":"2026-07-01T00:55:00.221547Z","end":"2026-07-01T00:55:00.323403Z","steps":["trace[218859353] 'process raft request' (duration: 101.593736ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:55:01.915729Z","caller":"traceutil/trace.go:171","msg":"trace[1311516792] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"125.36736ms","start":"2026-07-01T00:55:01.790314Z","end":"2026-07-01T00:55:01.915681Z","steps":["trace[1311516792] 'process raft request' (duration: 64.887998ms)","trace[1311516792] 'compare' (duration: 60.250667ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:55:01.916847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.836256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:55:01.917031Z","caller":"traceutil/trace.go:171","msg":"trace[2007021570] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:0; response_revision:1328; }","duration":"101.088462ms","start":"2026-07-01T00:55:01.815913Z","end":"2026-07-01T00:55:01.917002Z","steps":["trace[2007021570] 'agreement among raft nodes before linearized reading' (duration: 99.927172ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:56:08.436942Z","caller":"traceutil/trace.go:171","msg":"trace[1545474670] transaction","detail":"{read_only:false; response_revision:1640; number_of_response:1; }","duration":"115.100089ms","start":"2026-07-01T00:56:08.321824Z","end":"2026-07-01T00:56:08.436925Z","steps":["trace[1545474670] 'process raft request' (duration: 114.72486ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:58:49.72107Z","caller":"traceutil/trace.go:171","msg":"trace[975546321] linearizableReadLoop","detail":"{readStateIndex:2510; appliedIndex:2509; }","duration":"111.848755ms","start":"2026-07-01T00:58:49.609198Z","end":"2026-07-01T00:58:49.721047Z","steps":["trace[975546321] 'read index received' (duration: 111.65699ms)","trace[975546321] 'applied index is now lower than readState.Index' (duration: 190.755µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:58:49.721124Z","caller":"traceutil/trace.go:171","msg":"trace[104284691] transaction","detail":"{read_only:false; response_revision:2409; number_of_response:1; }","duration":"160.120607ms","start":"2026-07-01T00:58:49.56097Z","end":"2026-07-01T00:58:49.721091Z","steps":["trace[104284691] 'process raft request' (duration: 159.931492ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:58:49.721444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.196983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T00:58:49.72151Z","caller":"traceutil/trace.go:171","msg":"trace[786017914] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:2409; }","duration":"112.334267ms","start":"2026-07-01T00:58:49.609162Z","end":"2026-07-01T00:58:49.721496Z","steps":["trace[786017914] 'agreement among raft nodes before linearized reading' (duration: 111.982118ms)"],"step_count":1}