{"level":"warn","ts":"2026-07-21T20:53:49.406062Z","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-21T20:53:49.406244Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53: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.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53: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-21T20:53:49.406381Z","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-21T20:53:49.406405Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-07-21T20:53:49.406443Z","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-21T20:53:49.407827Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-07-21T20:53:49.407988Z","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":16,"max-cpu-available":16,"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.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53: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-21T20:53:49.412173Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.748897ms"} {"level":"info","ts":"2026-07-21T20:53:49.417707Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-07-21T20:53:49.417802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-07-21T20:53:49.417826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-07-21T20:53:49.417837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-21T20:53:49.417852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-07-21T20:53:49.417892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-07-21T20:53:49.421616Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-21T20:53:49.422886Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-21T20:53:49.424169Z","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-21T20:53:49.426812Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-21T20:53:49.427044Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-21T20:53:49.427165Z","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-21T20:53:49.427271Z","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-21T20:53:49.427293Z","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-21T20:53:49.428568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-07-21T20:53:49.42878Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-07-21T20:53:49.432403Z","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-21T20:53:49.432533Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-07-21T20:53:49.4326Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-07-21T20:53:49.432761Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-21T20:53:49.432825Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-21T20:53:50.118281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-07-21T20:53:50.118343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-07-21T20:53:50.118366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-07-21T20:53:50.118386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-07-21T20:53:50.118398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-07-21T20:53:50.118413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-07-21T20:53:50.118441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-07-21T20:53:50.11953Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-07-21T20:53:50.119574Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T20:53:50.119608Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T20:53:50.119787Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T20:53:50.119916Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-21T20:53:50.120142Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-21T20:53:50.120851Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T20:53:50.120972Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T20:53:50.121018Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T20:53:50.123056Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-21T20:53:50.123112Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-07-21T20:53:51.491743Z","caller":"traceutil/trace.go:171","msg":"trace[1422360757] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"133.452857ms","start":"2026-07-21T20:53:51.358267Z","end":"2026-07-21T20:53:51.491719Z","steps":["trace[1422360757] 'process raft request' (duration: 133.235812ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:51.54966Z","caller":"traceutil/trace.go:171","msg":"trace[710756511] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"102.713516ms","start":"2026-07-21T20:53:51.446916Z","end":"2026-07-21T20:53:51.549629Z","steps":["trace[710756511] 'process raft request' (duration: 102.571763ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:51.549709Z","caller":"traceutil/trace.go:171","msg":"trace[557456092] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"190.477222ms","start":"2026-07-21T20:53:51.359207Z","end":"2026-07-21T20:53:51.549684Z","steps":["trace[557456092] 'read index received' (duration: 132.333014ms)","trace[557456092] 'applied index is now lower than readState.Index' (duration: 58.143438ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:53:51.549808Z","caller":"traceutil/trace.go:171","msg":"trace[749268711] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"102.079312ms","start":"2026-07-21T20:53:51.447717Z","end":"2026-07-21T20:53:51.549796Z","steps":["trace[749268711] 'process raft request' (duration: 101.860918ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:51.550022Z","caller":"traceutil/trace.go:171","msg":"trace[237396148] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"102.242355ms","start":"2026-07-21T20:53:51.447772Z","end":"2026-07-21T20:53:51.550015Z","steps":["trace[237396148] 'process raft request' (duration: 101.822226ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:51.550063Z","caller":"traceutil/trace.go:171","msg":"trace[314552587] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"102.641684ms","start":"2026-07-21T20:53:51.447367Z","end":"2026-07-21T20:53:51.550009Z","steps":["trace[314552587] 'process raft request' (duration: 102.153754ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:51.550221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.975763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3174"} {"level":"info","ts":"2026-07-21T20:53:51.550278Z","caller":"traceutil/trace.go:171","msg":"trace[1833494754] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"191.079725ms","start":"2026-07-21T20:53:51.359191Z","end":"2026-07-21T20:53:51.55027Z","steps":["trace[1833494754] 'agreement among raft nodes before linearized reading' (duration: 190.895611ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:51.550308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.928492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-21T20:53:51.549685Z","caller":"traceutil/trace.go:171","msg":"trace[545264868] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"102.216754ms","start":"2026-07-21T20:53:51.447438Z","end":"2026-07-21T20:53:51.549655Z","steps":["trace[545264868] 'process raft request' (duration: 102.114322ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:51.550383Z","caller":"traceutil/trace.go:171","msg":"trace[317729374] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"141.029054ms","start":"2026-07-21T20:53:51.409334Z","end":"2026-07-21T20:53:51.550363Z","steps":["trace[317729374] 'agreement among raft nodes before linearized reading' (duration: 140.903571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:51.550407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.301827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-21T20:53:51.550182Z","caller":"traceutil/trace.go:171","msg":"trace[327123888] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"191.703987ms","start":"2026-07-21T20:53:51.358332Z","end":"2026-07-21T20:53:51.550035Z","steps":["trace[327123888] 'process raft request' (duration: 190.998373ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:51.550469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.367799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-21T20:53:51.550522Z","caller":"traceutil/trace.go:171","msg":"trace[1597026865] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"103.471341ms","start":"2026-07-21T20:53:51.447021Z","end":"2026-07-21T20:53:51.550493Z","steps":["trace[1597026865] 'agreement among raft nodes before linearized reading' (duration: 103.229436ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:51.55054Z","caller":"traceutil/trace.go:171","msg":"trace[1069933722] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"103.507212ms","start":"2026-07-21T20:53:51.446999Z","end":"2026-07-21T20:53:51.550506Z","steps":["trace[1069933722] 'agreement among raft nodes before linearized reading' (duration: 103.366519ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:53:51.550333Z","caller":"traceutil/trace.go:171","msg":"trace[1993883332] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"102.48722ms","start":"2026-07-21T20:53:51.44782Z","end":"2026-07-21T20:53:51.550307Z","steps":["trace[1993883332] 'process raft request' (duration: 101.790235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:51.5506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.019452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-21T20:53:51.550625Z","caller":"traceutil/trace.go:171","msg":"trace[603997733] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"139.057033ms","start":"2026-07-21T20:53:51.411561Z","end":"2026-07-21T20:53:51.550618Z","steps":["trace[603997733] 'agreement among raft nodes before linearized reading' (duration: 139.018662ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:51.550626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.740357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-21T20:53:51.550769Z","caller":"traceutil/trace.go:171","msg":"trace[1775221912] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:0; response_revision:11; }","duration":"105.934141ms","start":"2026-07-21T20:53:51.444818Z","end":"2026-07-21T20:53:51.550753Z","steps":["trace[1775221912] 'agreement among raft nodes before linearized reading' (duration: 105.787178ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:53:51.550579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.725617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-21T20:53:51.550896Z","caller":"traceutil/trace.go:171","msg":"trace[1174284065] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"139.040793ms","start":"2026-07-21T20:53:51.411826Z","end":"2026-07-21T20:53:51.550867Z","steps":["trace[1174284065] 'agreement among raft nodes before linearized reading' (duration: 138.693056ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:01.267305Z","caller":"traceutil/trace.go:171","msg":"trace[1204271032] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"126.578289ms","start":"2026-07-21T20:54:01.140679Z","end":"2026-07-21T20:54:01.267257Z","steps":["trace[1204271032] 'process raft request' (duration: 126.403166ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:27.911458Z","caller":"traceutil/trace.go:171","msg":"trace[275557470] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"320.265545ms","start":"2026-07-21T20:54:27.591163Z","end":"2026-07-21T20:54:27.911429Z","steps":["trace[275557470] 'process raft request' (duration: 291.606851ms)","trace[275557470] 'compare' (duration: 28.377038ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:27.911612Z","caller":"traceutil/trace.go:171","msg":"trace[178678958] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"312.439616ms","start":"2026-07-21T20:54:27.599148Z","end":"2026-07-21T20:54:27.911588Z","steps":["trace[178678958] 'process raft request' (duration: 312.219372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:27.911972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:27.591149Z","time spent":"320.393478ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:54:27.912088Z","caller":"traceutil/trace.go:171","msg":"trace[1869138529] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"308.763671ms","start":"2026-07-21T20:54:27.603309Z","end":"2026-07-21T20:54:27.912073Z","steps":["trace[1869138529] 'process raft request' (duration: 308.306602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:27.912316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:27.599137Z","time spent":"312.525658ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:54:27.912432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:54:27.6033Z","time spent":"308.810752ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:54:33.207854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.0223ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:54:33.207992Z","caller":"traceutil/trace.go:171","msg":"trace[1767386484] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"138.295778ms","start":"2026-07-21T20:54:33.069658Z","end":"2026-07-21T20:54:33.207954Z","steps":["trace[1767386484] 'compare' (duration: 129.892597ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:33.691141Z","caller":"traceutil/trace.go:171","msg":"trace[1835008253] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"257.576108ms","start":"2026-07-21T20:54:33.433536Z","end":"2026-07-21T20:54:33.691112Z","steps":["trace[1835008253] 'read index received' (duration: 254.629898ms)","trace[1835008253] 'applied index is now lower than readState.Index' (duration: 2.94503ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:33.691211Z","caller":"traceutil/trace.go:171","msg":"trace[240941811] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"259.882236ms","start":"2026-07-21T20:54:33.431281Z","end":"2026-07-21T20:54:33.691163Z","steps":["trace[240941811] 'process raft request' (duration: 258.056369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:33.691324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.822105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T20:54:33.691369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.831293ms","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-21T20:54:33.691377Z","caller":"traceutil/trace.go:171","msg":"trace[1266439900] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"181.928757ms","start":"2026-07-21T20:54:33.509429Z","end":"2026-07-21T20:54:33.691358Z","steps":["trace[1266439900] 'agreement among raft nodes before linearized reading' (duration: 181.836615ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:33.691416Z","caller":"traceutil/trace.go:171","msg":"trace[2006988475] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:580; }","duration":"257.924636ms","start":"2026-07-21T20:54:33.433481Z","end":"2026-07-21T20:54:33.691406Z","steps":["trace[2006988475] 'agreement among raft nodes before linearized reading' (duration: 257.778513ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:54:33.88743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.119802ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:54:33.887895Z","caller":"traceutil/trace.go:171","msg":"trace[1990894374] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"191.380959ms","start":"2026-07-21T20:54:33.696453Z","end":"2026-07-21T20:54:33.887834Z","steps":["trace[1990894374] 'process raft request' (duration: 57.706716ms)","trace[1990894374] 'compare' (duration: 132.912468ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:54:33.887952Z","caller":"traceutil/trace.go:171","msg":"trace[1524058062] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"136.827857ms","start":"2026-07-21T20:54:33.751052Z","end":"2026-07-21T20:54:33.88788Z","steps":["trace[1524058062] 'process raft request' (duration: 136.524421ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:54:49.483895Z","caller":"traceutil/trace.go:171","msg":"trace[496200320] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"146.509626ms","start":"2026-07-21T20:54:49.337361Z","end":"2026-07-21T20:54:49.483871Z","steps":["trace[496200320] 'process raft request' (duration: 146.380333ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:55:38.367562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.040352ms","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-21T20:55:38.36766Z","caller":"traceutil/trace.go:171","msg":"trace[437604722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1406; }","duration":"137.194486ms","start":"2026-07-21T20:55:38.230446Z","end":"2026-07-21T20:55:38.367641Z","steps":["trace[437604722] 'range keys from in-memory index tree' (duration: 136.856208ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:55:38.587294Z","caller":"traceutil/trace.go:171","msg":"trace[1542913768] linearizableReadLoop","detail":"{readStateIndex:1452; appliedIndex:1451; }","duration":"108.107142ms","start":"2026-07-21T20:55:38.479161Z","end":"2026-07-21T20:55:38.587268Z","steps":["trace[1542913768] 'read index received' (duration: 61.944782ms)","trace[1542913768] 'applied index is now lower than readState.Index' (duration: 46.16141ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:55:38.587369Z","caller":"traceutil/trace.go:171","msg":"trace[80973209] transaction","detail":"{read_only:false; response_revision:1408; number_of_response:1; }","duration":"140.999633ms","start":"2026-07-21T20:55:38.446283Z","end":"2026-07-21T20:55:38.587282Z","steps":["trace[80973209] 'process raft request' (duration: 94.899024ms)","trace[80973209] 'compare' (duration: 45.938455ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:55:38.587575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.397838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-21T20:55:38.587676Z","caller":"traceutil/trace.go:171","msg":"trace[2078131852] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1408; }","duration":"108.551461ms","start":"2026-07-21T20:55:38.479105Z","end":"2026-07-21T20:55:38.587656Z","steps":["trace[2078131852] 'agreement among raft nodes before linearized reading' (duration: 108.281656ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:56:02.157179Z","caller":"traceutil/trace.go:171","msg":"trace[856793283] linearizableReadLoop","detail":"{readStateIndex:1890; appliedIndex:1889; }","duration":"213.996001ms","start":"2026-07-21T20:56:01.943159Z","end":"2026-07-21T20:56:02.157155Z","steps":["trace[856793283] 'read index received' (duration: 213.764976ms)","trace[856793283] 'applied index is now lower than readState.Index' (duration: 230.145µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:56:02.157265Z","caller":"traceutil/trace.go:171","msg":"trace[348077958] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"252.154308ms","start":"2026-07-21T20:56:01.905072Z","end":"2026-07-21T20:56:02.157226Z","steps":["trace[348077958] 'process raft request' (duration: 251.932444ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:56:02.157379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.195266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T20:56:02.157487Z","caller":"traceutil/trace.go:171","msg":"trace[1822267605] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1819; }","duration":"214.361028ms","start":"2026-07-21T20:56:01.9431Z","end":"2026-07-21T20:56:02.157461Z","steps":["trace[1822267605] 'agreement among raft nodes before linearized reading' (duration: 214.163294ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:56:13.055569Z","caller":"traceutil/trace.go:171","msg":"trace[203741170] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"142.682582ms","start":"2026-07-21T20:56:12.912867Z","end":"2026-07-21T20:56:13.05555Z","steps":["trace[203741170] 'process raft request' (duration: 142.583691ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:56:13.886539Z","caller":"traceutil/trace.go:171","msg":"trace[407868273] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"278.410584ms","start":"2026-07-21T20:56:13.608093Z","end":"2026-07-21T20:56:13.886503Z","steps":["trace[407868273] 'process raft request' (duration: 278.250361ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:56:46.940926Z","caller":"traceutil/trace.go:171","msg":"trace[1918038110] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"143.629861ms","start":"2026-07-21T20:56:46.797264Z","end":"2026-07-21T20:56:46.940894Z","steps":["trace[1918038110] 'process raft request' (duration: 143.399047ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:56:47.3282Z","caller":"traceutil/trace.go:171","msg":"trace[1764114373] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"126.636036ms","start":"2026-07-21T20:56:47.201527Z","end":"2026-07-21T20:56:47.328163Z","steps":["trace[1764114373] 'process raft request' (duration: 126.386381ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:56:53.087945Z","caller":"traceutil/trace.go:171","msg":"trace[1876092046] linearizableReadLoop","detail":"{readStateIndex:2224; appliedIndex:2223; }","duration":"114.53321ms","start":"2026-07-21T20:56:52.973389Z","end":"2026-07-21T20:56:53.087922Z","steps":["trace[1876092046] 'read index received' (duration: 114.303734ms)","trace[1876092046] 'applied index is now lower than readState.Index' (duration: 228.656µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:56:53.088007Z","caller":"traceutil/trace.go:171","msg":"trace[1660623091] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"117.126952ms","start":"2026-07-21T20:56:52.970861Z","end":"2026-07-21T20:56:53.087987Z","steps":["trace[1660623091] 'process raft request' (duration: 116.897847ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:56:53.088161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.766584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-21T20:56:53.088199Z","caller":"traceutil/trace.go:171","msg":"trace[1091375499] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2142; }","duration":"114.869686ms","start":"2026-07-21T20:56:52.97332Z","end":"2026-07-21T20:56:53.08819Z","steps":["trace[1091375499] 'agreement among raft nodes before linearized reading' (duration: 114.726083ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:56:53.088298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.327462ms","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-21T20:56:53.088389Z","caller":"traceutil/trace.go:171","msg":"trace[213462536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2142; }","duration":"101.447904ms","start":"2026-07-21T20:56:52.986922Z","end":"2026-07-21T20:56:53.08837Z","steps":["trace[213462536] 'agreement among raft nodes before linearized reading' (duration: 101.24428ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:56:53.595186Z","caller":"traceutil/trace.go:171","msg":"trace[1461073653] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"246.925453ms","start":"2026-07-21T20:56:53.348193Z","end":"2026-07-21T20:56:53.595118Z","steps":["trace[1461073653] 'process raft request' (duration: 246.672357ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:56:53.596977Z","caller":"traceutil/trace.go:171","msg":"trace[45339323] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"200.377546ms","start":"2026-07-21T20:56:53.396575Z","end":"2026-07-21T20:56:53.596953Z","steps":["trace[45339323] 'process raft request' (duration: 200.218153ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:58:15.680992Z","caller":"traceutil/trace.go:171","msg":"trace[1982073794] linearizableReadLoop","detail":"{readStateIndex:2892; appliedIndex:2891; }","duration":"161.234043ms","start":"2026-07-21T20:58:15.519724Z","end":"2026-07-21T20:58:15.680958Z","steps":["trace[1982073794] 'read index received' (duration: 161.06117ms)","trace[1982073794] 'applied index is now lower than readState.Index' (duration: 170.873µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:58:15.681119Z","caller":"traceutil/trace.go:171","msg":"trace[2000095909] transaction","detail":"{read_only:false; response_revision:2783; number_of_response:1; }","duration":"166.833498ms","start":"2026-07-21T20:58:15.514245Z","end":"2026-07-21T20:58:15.681078Z","steps":["trace[2000095909] 'process raft request' (duration: 166.563842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:15.681264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.283545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-21T20:58:15.681303Z","caller":"traceutil/trace.go:171","msg":"trace[2086128351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2783; }","duration":"161.388777ms","start":"2026-07-21T20:58:15.519902Z","end":"2026-07-21T20:58:15.681291Z","steps":["trace[2086128351] 'agreement among raft nodes before linearized reading' (duration: 161.239584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:15.681284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.5456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-21T20:58:15.681502Z","caller":"traceutil/trace.go:171","msg":"trace[913816157] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2783; }","duration":"161.713204ms","start":"2026-07-21T20:58:15.519706Z","end":"2026-07-21T20:58:15.681419Z","steps":["trace[913816157] 'agreement among raft nodes before linearized reading' (duration: 161.406178ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:15.681705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.58451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-21T20:58:15.681839Z","caller":"traceutil/trace.go:171","msg":"trace[1388911299] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2783; }","duration":"161.782205ms","start":"2026-07-21T20:58:15.520033Z","end":"2026-07-21T20:58:15.681816Z","steps":["trace[1388911299] 'agreement among raft nodes before linearized reading' (duration: 161.361336ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:58:15.919231Z","caller":"traceutil/trace.go:171","msg":"trace[1986886347] transaction","detail":"{read_only:false; response_revision:2785; number_of_response:1; }","duration":"233.321592ms","start":"2026-07-21T20:58:15.685891Z","end":"2026-07-21T20:58:15.919213Z","steps":["trace[1986886347] 'process raft request' (duration: 232.051366ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:58:15.919426Z","caller":"traceutil/trace.go:171","msg":"trace[1643099434] transaction","detail":"{read_only:false; response_revision:2786; number_of_response:1; }","duration":"232.357432ms","start":"2026-07-21T20:58:15.687029Z","end":"2026-07-21T20:58:15.919386Z","steps":["trace[1643099434] 'process raft request' (duration: 232.149697ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:58:15.919911Z","caller":"traceutil/trace.go:171","msg":"trace[280843053] linearizableReadLoop","detail":"{readStateIndex:2895; appliedIndex:2893; }","duration":"103.09776ms","start":"2026-07-21T20:58:15.816793Z","end":"2026-07-21T20:58:15.919891Z","steps":["trace[280843053] 'read index received' (duration: 101.18047ms)","trace[280843053] 'applied index is now lower than readState.Index' (duration: 1.91513ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:58:15.920143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.333584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-21T20:58:15.920175Z","caller":"traceutil/trace.go:171","msg":"trace[2080170328] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2788; }","duration":"103.416876ms","start":"2026-07-21T20:58:15.816748Z","end":"2026-07-21T20:58:15.920165Z","steps":["trace[2080170328] 'agreement among raft nodes before linearized reading' (duration: 103.218392ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:02:10.303004Z","caller":"traceutil/trace.go:171","msg":"trace[1326610878] linearizableReadLoop","detail":"{readStateIndex:4880; appliedIndex:4879; }","duration":"139.246178ms","start":"2026-07-21T21:02:10.163734Z","end":"2026-07-21T21:02:10.30298Z","steps":["trace[1326610878] 'read index received' (duration: 139.168217ms)","trace[1326610878] 'applied index is now lower than readState.Index' (duration: 76.661µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:02:10.30313Z","caller":"traceutil/trace.go:171","msg":"trace[2040376383] transaction","detail":"{read_only:false; response_revision:4696; number_of_response:1; }","duration":"142.218849ms","start":"2026-07-21T21:02:10.1609Z","end":"2026-07-21T21:02:10.303119Z","steps":["trace[2040376383] 'process raft request' (duration: 141.931273ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:02:10.303372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.622876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-07-21T21:02:10.303407Z","caller":"traceutil/trace.go:171","msg":"trace[1907945937] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4696; }","duration":"139.703749ms","start":"2026-07-21T21:02:10.163695Z","end":"2026-07-21T21:02:10.303399Z","steps":["trace[1907945937] 'agreement among raft nodes before linearized reading' (duration: 139.538945ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:02:10.307995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.896827ms","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-21T21:02:10.308088Z","caller":"traceutil/trace.go:171","msg":"trace[1023613573] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4697; }","duration":"127.044599ms","start":"2026-07-21T21:02:10.181024Z","end":"2026-07-21T21:02:10.308068Z","steps":["trace[1023613573] 'agreement among raft nodes before linearized reading' (duration: 126.796724ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:02:10.308275Z","caller":"traceutil/trace.go:171","msg":"trace[1824006641] transaction","detail":"{read_only:false; response_revision:4697; number_of_response:1; }","duration":"138.705318ms","start":"2026-07-21T21:02:10.16953Z","end":"2026-07-21T21:02:10.308235Z","steps":["trace[1824006641] 'process raft request' (duration: 138.129906ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:02:16.715479Z","caller":"traceutil/trace.go:171","msg":"trace[695399529] transaction","detail":"{read_only:false; response_revision:4874; number_of_response:1; }","duration":"111.167606ms","start":"2026-07-21T21:02:16.604282Z","end":"2026-07-21T21:02:16.715449Z","steps":["trace[695399529] 'process raft request' (duration: 110.935501ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:02:18.311462Z","caller":"traceutil/trace.go:171","msg":"trace[2047189099] transaction","detail":"{read_only:false; response_revision:4984; number_of_response:1; }","duration":"109.312129ms","start":"2026-07-21T21:02:18.202128Z","end":"2026-07-21T21:02:18.31144Z","steps":["trace[2047189099] 'process raft request' (duration: 109.147126ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:02:18.316408Z","caller":"traceutil/trace.go:171","msg":"trace[654439868] transaction","detail":"{read_only:false; response_revision:4985; number_of_response:1; }","duration":"109.8745ms","start":"2026-07-21T21:02:18.206506Z","end":"2026-07-21T21:02:18.31638Z","steps":["trace[654439868] 'process raft request' (duration: 109.713527ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:02:22.944755Z","caller":"traceutil/trace.go:171","msg":"trace[1419631339] transaction","detail":"{read_only:false; response_revision:5079; number_of_response:1; }","duration":"129.847587ms","start":"2026-07-21T21:02:22.814885Z","end":"2026-07-21T21:02:22.944733Z","steps":["trace[1419631339] 'process raft request' (duration: 124.64539ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:02:22.944986Z","caller":"traceutil/trace.go:171","msg":"trace[1523860159] linearizableReadLoop","detail":"{readStateIndex:5277; appliedIndex:5275; }","duration":"126.511489ms","start":"2026-07-21T21:02:22.818435Z","end":"2026-07-21T21:02:22.944947Z","steps":["trace[1523860159] 'read index received' (duration: 121.112729ms)","trace[1523860159] 'applied index is now lower than readState.Index' (duration: 5.39609ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:02:22.945096Z","caller":"traceutil/trace.go:171","msg":"trace[602935633] transaction","detail":"{read_only:false; response_revision:5080; number_of_response:1; }","duration":"130.120792ms","start":"2026-07-21T21:02:22.814904Z","end":"2026-07-21T21:02:22.945025Z","steps":["trace[602935633] 'process raft request' (duration: 129.794846ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:02:22.945194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.635288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:02:22.945243Z","caller":"traceutil/trace.go:171","msg":"trace[1127611828] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:5080; }","duration":"116.72435ms","start":"2026-07-21T21:02:22.828505Z","end":"2026-07-21T21:02:22.945229Z","steps":["trace[1127611828] 'agreement among raft nodes before linearized reading' (duration: 116.599007ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:02:22.945316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.857266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:16201"} {"level":"info","ts":"2026-07-21T21:02:22.945438Z","caller":"traceutil/trace.go:171","msg":"trace[1269486660] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5080; }","duration":"127.028569ms","start":"2026-07-21T21:02:22.818378Z","end":"2026-07-21T21:02:22.945406Z","steps":["trace[1269486660] 'agreement among raft nodes before linearized reading' (duration: 126.679922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:02:31.789527Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794900745376307130,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T21:02:31.848121Z","caller":"traceutil/trace.go:171","msg":"trace[1357351023] transaction","detail":"{read_only:false; response_revision:5232; number_of_response:1; }","duration":"610.281244ms","start":"2026-07-21T21:02:31.237815Z","end":"2026-07-21T21:02:31.848096Z","steps":["trace[1357351023] 'process raft request' (duration: 610.10665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:02:31.84827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:02:31.237781Z","time spent":"610.415207ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":125360,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:02:31.857423Z","caller":"traceutil/trace.go:171","msg":"trace[1187546282] linearizableReadLoop","detail":"{readStateIndex:5436; appliedIndex:5435; }","duration":"568.290688ms","start":"2026-07-21T21:02:31.28911Z","end":"2026-07-21T21:02:31.857401Z","steps":["trace[1187546282] 'read index received' (duration: 558.996578ms)","trace[1187546282] 'applied index is now lower than readState.Index' (duration: 9.29325ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:02:31.857516Z","caller":"traceutil/trace.go:171","msg":"trace[958457124] transaction","detail":"{read_only:false; response_revision:5233; number_of_response:1; }","duration":"552.174909ms","start":"2026-07-21T21:02:31.305312Z","end":"2026-07-21T21:02:31.857487Z","steps":["trace[958457124] 'process raft request' (duration: 551.997705ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:02:31.857581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.476651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-21T21:02:31.857621Z","caller":"traceutil/trace.go:171","msg":"trace[1950117189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5233; }","duration":"568.571243ms","start":"2026-07-21T21:02:31.28904Z","end":"2026-07-21T21:02:31.857611Z","steps":["trace[1950117189] 'agreement among raft nodes before linearized reading' (duration: 568.457711ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:02:31.857642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:02:31.289004Z","time spent":"568.633595ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T21:02:31.857655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:02:31.305279Z","time spent":"552.306172ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:02:31.859126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.145859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:02:31.859161Z","caller":"traceutil/trace.go:171","msg":"trace[115210829] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5234; }","duration":"351.219281ms","start":"2026-07-21T21:02:31.507933Z","end":"2026-07-21T21:02:31.859153Z","steps":["trace[115210829] 'agreement among raft nodes before linearized reading' (duration: 351.16382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:02:31.859133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.743799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-21T21:02:31.859185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:02:31.507913Z","time spent":"351.266291ms","remote":"127.0.0.1:41356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-21T21:02:31.859222Z","caller":"traceutil/trace.go:171","msg":"trace[1502476234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5234; }","duration":"549.881432ms","start":"2026-07-21T21:02:31.309321Z","end":"2026-07-21T21:02:31.859202Z","steps":["trace[1502476234] 'agreement among raft nodes before linearized reading' (duration: 549.641277ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:02:31.859264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:02:31.309302Z","time spent":"549.954143ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-21T21:02:31.859306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.983989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T21:02:31.859364Z","caller":"traceutil/trace.go:171","msg":"trace[1829627027] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5234; }","duration":"436.093621ms","start":"2026-07-21T21:02:31.423253Z","end":"2026-07-21T21:02:31.859347Z","steps":["trace[1829627027] 'agreement among raft nodes before linearized reading' (duration: 435.989519ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:02:31.859392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.004069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-x9mcd\" ","response":"range_response_count:1 size:7817"} {"level":"warn","ts":"2026-07-21T21:02:31.859405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:02:31.423231Z","time spent":"436.163133ms","remote":"127.0.0.1:56892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-07-21T21:02:31.859419Z","caller":"traceutil/trace.go:171","msg":"trace[468298896] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-x9mcd; range_end:; response_count:1; response_revision:5234; }","duration":"536.052869ms","start":"2026-07-21T21:02:31.323358Z","end":"2026-07-21T21:02:31.859411Z","steps":["trace[468298896] 'agreement among raft nodes before linearized reading' (duration: 535.991188ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:02:31.859436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:02:31.323345Z","time spent":"536.08825ms","remote":"127.0.0.1:41548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":7841,"request content":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-x9mcd\" "} {"level":"info","ts":"2026-07-21T21:02:40.081633Z","caller":"traceutil/trace.go:171","msg":"trace[637497377] transaction","detail":"{read_only:false; response_revision:5424; number_of_response:1; }","duration":"158.433247ms","start":"2026-07-21T21:02:39.923173Z","end":"2026-07-21T21:02:40.081606Z","steps":["trace[637497377] 'process raft request' (duration: 58.912004ms)","trace[637497377] 'compare' (duration: 99.317549ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:02:40.081779Z","caller":"traceutil/trace.go:171","msg":"trace[850571487] transaction","detail":"{read_only:false; response_revision:5425; number_of_response:1; }","duration":"158.327235ms","start":"2026-07-21T21:02:39.923426Z","end":"2026-07-21T21:02:40.081753Z","steps":["trace[850571487] 'process raft request' (duration: 158.138111ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:03:50.142607Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2977} {"level":"info","ts":"2026-07-21T21:03:50.221065Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2977,"took":"76.689817ms","hash":2056435177,"current-db-size-bytes":58830848,"current-db-size":"59 MB","current-db-size-in-use-bytes":34205696,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-21T21:03:50.221173Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2056435177,"revision":2977,"compact-revision":-1} {"level":"info","ts":"2026-07-21T21:08:50.148825Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6076} {"level":"info","ts":"2026-07-21T21:08:50.257426Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6076,"took":"106.593316ms","hash":787679688,"current-db-size-bytes":58830848,"current-db-size":"59 MB","current-db-size-in-use-bytes":21422080,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-07-21T21:08:50.257517Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":787679688,"revision":6076,"compact-revision":2977} {"level":"info","ts":"2026-07-21T21:08:57.152249Z","caller":"traceutil/trace.go:171","msg":"trace[1891246665] linearizableReadLoop","detail":"{readStateIndex:8607; appliedIndex:8606; }","duration":"139.771633ms","start":"2026-07-21T21:08:57.012454Z","end":"2026-07-21T21:08:57.152226Z","steps":["trace[1891246665] 'read index received' (duration: 139.631ms)","trace[1891246665] 'applied index is now lower than readState.Index' (duration: 139.843µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:08:57.152372Z","caller":"traceutil/trace.go:171","msg":"trace[437959964] transaction","detail":"{read_only:false; response_revision:8309; number_of_response:1; }","duration":"148.086292ms","start":"2026-07-21T21:08:57.004264Z","end":"2026-07-21T21:08:57.15235Z","steps":["trace[437959964] 'process raft request' (duration: 147.820367ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:08:57.152437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.975586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-21T21:08:57.152486Z","caller":"traceutil/trace.go:171","msg":"trace[1843412636] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8309; }","duration":"140.063058ms","start":"2026-07-21T21:08:57.012403Z","end":"2026-07-21T21:08:57.152466Z","steps":["trace[1843412636] 'agreement among raft nodes before linearized reading' (duration: 139.940386ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:08:57.294704Z","caller":"traceutil/trace.go:171","msg":"trace[1385114928] linearizableReadLoop","detail":"{readStateIndex:8608; appliedIndex:8607; }","duration":"123.4662ms","start":"2026-07-21T21:08:57.171208Z","end":"2026-07-21T21:08:57.294675Z","steps":["trace[1385114928] 'read index received' (duration: 122.02917ms)","trace[1385114928] 'applied index is now lower than readState.Index' (duration: 1.43594ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:08:57.294892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.668644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-54bc68d55d-g9d84.18c46a34c1fc601f\" ","response":"range_response_count:1 size:1134"} {"level":"info","ts":"2026-07-21T21:08:57.294919Z","caller":"traceutil/trace.go:171","msg":"trace[773277471] range","detail":"{range_begin:/registry/events/openstack/barbican-api-54bc68d55d-g9d84.18c46a34c1fc601f; range_end:; response_count:1; response_revision:8310; }","duration":"123.763225ms","start":"2026-07-21T21:08:57.171147Z","end":"2026-07-21T21:08:57.29491Z","steps":["trace[773277471] 'agreement among raft nodes before linearized reading' (duration: 123.618822ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:08:57.294838Z","caller":"traceutil/trace.go:171","msg":"trace[1260802931] transaction","detail":"{read_only:false; response_revision:8310; number_of_response:1; }","duration":"137.842494ms","start":"2026-07-21T21:08:57.156933Z","end":"2026-07-21T21:08:57.294776Z","steps":["trace[1260802931] 'process raft request' (duration: 136.339653ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:09:08.486248Z","caller":"traceutil/trace.go:171","msg":"trace[724074967] transaction","detail":"{read_only:false; response_revision:8368; number_of_response:1; }","duration":"121.308634ms","start":"2026-07-21T21:09:08.364896Z","end":"2026-07-21T21:09:08.486205Z","steps":["trace[724074967] 'process raft request' (duration: 28.086493ms)","trace[724074967] 'compare' (duration: 93.107738ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:09:08.487396Z","caller":"traceutil/trace.go:171","msg":"trace[1027869482] transaction","detail":"{read_only:false; response_revision:8369; number_of_response:1; }","duration":"122.463726ms","start":"2026-07-21T21:09:08.364914Z","end":"2026-07-21T21:09:08.487378Z","steps":["trace[1027869482] 'process raft request' (duration: 121.852184ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:09:08.7149Z","caller":"traceutil/trace.go:171","msg":"trace[1942147028] linearizableReadLoop","detail":"{readStateIndex:8670; appliedIndex:8669; }","duration":"174.594599ms","start":"2026-07-21T21:09:08.540277Z","end":"2026-07-21T21:09:08.714872Z","steps":["trace[1942147028] 'read index received' (duration: 160.402489ms)","trace[1942147028] 'applied index is now lower than readState.Index' (duration: 14.1904ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:09:08.715146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.852603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-21T21:09:08.715228Z","caller":"traceutil/trace.go:171","msg":"trace[980329439] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8369; }","duration":"175.009576ms","start":"2026-07-21T21:09:08.540199Z","end":"2026-07-21T21:09:08.715209Z","steps":["trace[980329439] 'agreement among raft nodes before linearized reading' (duration: 174.765131ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:09:09.596126Z","caller":"traceutil/trace.go:171","msg":"trace[495496755] linearizableReadLoop","detail":"{readStateIndex:8673; appliedIndex:8672; }","duration":"255.472587ms","start":"2026-07-21T21:09:09.340632Z","end":"2026-07-21T21:09:09.596105Z","steps":["trace[495496755] 'read index received' (duration: 234.008299ms)","trace[495496755] 'applied index is now lower than readState.Index' (duration: 21.463178ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:09:09.596184Z","caller":"traceutil/trace.go:171","msg":"trace[1559145289] transaction","detail":"{read_only:false; response_revision:8372; number_of_response:1; }","duration":"260.812347ms","start":"2026-07-21T21:09:09.335328Z","end":"2026-07-21T21:09:09.59614Z","steps":["trace[1559145289] 'process raft request' (duration: 239.42931ms)","trace[1559145289] 'compare' (duration: 21.119731ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:09:09.596311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.660352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-21T21:09:09.596361Z","caller":"traceutil/trace.go:171","msg":"trace[308265342] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8372; }","duration":"255.757753ms","start":"2026-07-21T21:09:09.340592Z","end":"2026-07-21T21:09:09.596349Z","steps":["trace[308265342] 'agreement among raft nodes before linearized reading' (duration: 255.60211ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:09:10.371766Z","caller":"traceutil/trace.go:171","msg":"trace[1665166825] linearizableReadLoop","detail":"{readStateIndex:8676; appliedIndex:8675; }","duration":"263.403459ms","start":"2026-07-21T21:09:10.108341Z","end":"2026-07-21T21:09:10.371744Z","steps":["trace[1665166825] 'read index received' (duration: 263.298537ms)","trace[1665166825] 'applied index is now lower than readState.Index' (duration: 103.962µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:09:10.371837Z","caller":"traceutil/trace.go:171","msg":"trace[1990415678] transaction","detail":"{read_only:false; response_revision:8375; number_of_response:1; }","duration":"263.590523ms","start":"2026-07-21T21:09:10.108203Z","end":"2026-07-21T21:09:10.371793Z","steps":["trace[1990415678] 'process raft request' (duration: 263.393279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:09:10.37197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.606123ms","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-21T21:09:10.372015Z","caller":"traceutil/trace.go:171","msg":"trace[1277602816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8375; }","duration":"263.697045ms","start":"2026-07-21T21:09:10.108308Z","end":"2026-07-21T21:09:10.372005Z","steps":["trace[1277602816] 'agreement among raft nodes before linearized reading' (duration: 263.542481ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:09:10.381544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.007746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:09:10.381606Z","caller":"traceutil/trace.go:171","msg":"trace[777318646] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:8377; }","duration":"226.123399ms","start":"2026-07-21T21:09:10.155467Z","end":"2026-07-21T21:09:10.38159Z","steps":["trace[777318646] 'agreement among raft nodes before linearized reading' (duration: 225.997976ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:09:37.790426Z","caller":"traceutil/trace.go:171","msg":"trace[1482612879] transaction","detail":"{read_only:false; response_revision:8579; number_of_response:1; }","duration":"106.989681ms","start":"2026-07-21T21:09:37.683404Z","end":"2026-07-21T21:09:37.790394Z","steps":["trace[1482612879] 'process raft request' (duration: 106.727826ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:09:40.876916Z","caller":"traceutil/trace.go:171","msg":"trace[81941588] transaction","detail":"{read_only:false; response_revision:8597; number_of_response:1; }","duration":"262.674205ms","start":"2026-07-21T21:09:40.614207Z","end":"2026-07-21T21:09:40.876881Z","steps":["trace[81941588] 'process raft request' (duration: 210.792957ms)","trace[81941588] 'compare' (duration: 51.240425ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:09:49.92815Z","caller":"traceutil/trace.go:171","msg":"trace[1658587922] transaction","detail":"{read_only:false; response_revision:8684; number_of_response:1; }","duration":"100.638862ms","start":"2026-07-21T21:09:49.827462Z","end":"2026-07-21T21:09:49.9281Z","steps":["trace[1658587922] 'process raft request' (duration: 100.375126ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:09:50.108523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.640202ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T21:09:50.108661Z","caller":"traceutil/trace.go:171","msg":"trace[916723015] transaction","detail":"{read_only:false; response_revision:8685; number_of_response:1; }","duration":"172.657ms","start":"2026-07-21T21:09:49.935979Z","end":"2026-07-21T21:09:50.108636Z","steps":["trace[916723015] 'process raft request' (duration: 45.814154ms)","trace[916723015] 'compare' (duration: 126.490208ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:09:50.108779Z","caller":"traceutil/trace.go:171","msg":"trace[158262607] transaction","detail":"{read_only:false; response_revision:8686; number_of_response:1; }","duration":"153.886767ms","start":"2026-07-21T21:09:49.954869Z","end":"2026-07-21T21:09:50.108756Z","steps":["trace[158262607] 'process raft request' (duration: 153.779804ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:09:50.108839Z","caller":"traceutil/trace.go:171","msg":"trace[1719494356] linearizableReadLoop","detail":"{readStateIndex:8999; appliedIndex:8998; }","duration":"154.642143ms","start":"2026-07-21T21:09:49.954143Z","end":"2026-07-21T21:09:50.108785Z","steps":["trace[1719494356] 'read index received' (duration: 27.645164ms)","trace[1719494356] 'applied index is now lower than readState.Index' (duration: 126.990679ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:09:50.109118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.956819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-nkw5v\" ","response":"range_response_count:1 size:4647"} {"level":"info","ts":"2026-07-21T21:09:50.109185Z","caller":"traceutil/trace.go:171","msg":"trace[629102647] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-nkw5v; range_end:; response_count:1; response_revision:8686; }","duration":"155.072082ms","start":"2026-07-21T21:09:49.954096Z","end":"2026-07-21T21:09:50.109168Z","steps":["trace[629102647] 'agreement among raft nodes before linearized reading' (duration: 154.806046ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:09:50.226133Z","caller":"traceutil/trace.go:171","msg":"trace[1476350098] transaction","detail":"{read_only:false; response_revision:8687; number_of_response:1; }","duration":"109.281098ms","start":"2026-07-21T21:09:50.116826Z","end":"2026-07-21T21:09:50.226107Z","steps":["trace[1476350098] 'process raft request' (duration: 100.838666ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:09:51.14124Z","caller":"traceutil/trace.go:171","msg":"trace[2120115854] linearizableReadLoop","detail":"{readStateIndex:9008; appliedIndex:9007; }","duration":"151.0641ms","start":"2026-07-21T21:09:50.990151Z","end":"2026-07-21T21:09:51.141215Z","steps":["trace[2120115854] 'read index received' (duration: 150.901326ms)","trace[2120115854] 'applied index is now lower than readState.Index' (duration: 161.234µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:09:51.141321Z","caller":"traceutil/trace.go:171","msg":"trace[1454486480] transaction","detail":"{read_only:false; response_revision:8694; number_of_response:1; }","duration":"151.912757ms","start":"2026-07-21T21:09:50.989363Z","end":"2026-07-21T21:09:51.141276Z","steps":["trace[1454486480] 'process raft request' (duration: 151.701113ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:09:51.141522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.347205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-21T21:09:51.141608Z","caller":"traceutil/trace.go:171","msg":"trace[1068992934] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8694; }","duration":"151.463648ms","start":"2026-07-21T21:09:50.990126Z","end":"2026-07-21T21:09:51.141589Z","steps":["trace[1068992934] 'agreement among raft nodes before linearized reading' (duration: 151.195233ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:09:51.196456Z","caller":"traceutil/trace.go:171","msg":"trace[1795438755] transaction","detail":"{read_only:false; response_revision:8695; number_of_response:1; }","duration":"201.629421ms","start":"2026-07-21T21:09:50.994803Z","end":"2026-07-21T21:09:51.196432Z","steps":["trace[1795438755] 'process raft request' (duration: 201.511338ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:09:51.19664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.030447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-95jvg\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-07-21T21:09:51.196745Z","caller":"traceutil/trace.go:171","msg":"trace[956011182] range","detail":"{range_begin:/registry/pods/openstack/keepalived-95jvg; range_end:; response_count:1; response_revision:8695; }","duration":"198.17654ms","start":"2026-07-21T21:09:50.998551Z","end":"2026-07-21T21:09:51.196728Z","steps":["trace[956011182] 'agreement among raft nodes before linearized reading' (duration: 197.882195ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:10:06.73767Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023cb.wal"} {"level":"info","ts":"2026-07-21T21:11:06.124985Z","caller":"traceutil/trace.go:171","msg":"trace[232721361] linearizableReadLoop","detail":"{readStateIndex:9763; appliedIndex:9762; }","duration":"218.709349ms","start":"2026-07-21T21:11:05.906246Z","end":"2026-07-21T21:11:06.124955Z","steps":["trace[232721361] 'read index received' (duration: 218.510445ms)","trace[232721361] 'applied index is now lower than readState.Index' (duration: 197.884µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:11:06.125084Z","caller":"traceutil/trace.go:171","msg":"trace[1187127318] transaction","detail":"{read_only:false; response_revision:9431; number_of_response:1; }","duration":"233.690643ms","start":"2026-07-21T21:11:05.891374Z","end":"2026-07-21T21:11:06.125064Z","steps":["trace[1187127318] 'process raft request' (duration: 233.392677ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:11:06.125138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.877032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:11:06.125178Z","caller":"traceutil/trace.go:171","msg":"trace[735588586] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:9431; }","duration":"218.969343ms","start":"2026-07-21T21:11:05.906199Z","end":"2026-07-21T21:11:06.125168Z","steps":["trace[735588586] 'agreement among raft nodes before linearized reading' (duration: 218.884742ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:11:06.155026Z","caller":"traceutil/trace.go:171","msg":"trace[681287512] transaction","detail":"{read_only:false; response_revision:9432; number_of_response:1; }","duration":"153.53915ms","start":"2026-07-21T21:11:06.001461Z","end":"2026-07-21T21:11:06.155001Z","steps":["trace[681287512] 'process raft request' (duration: 147.109418ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:11:38.341444Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c47ca1a7b1fe506b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-21T21:11:38.346853Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-21T21:11:38.346953Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-21T21:13:50.155881Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8141} {"level":"info","ts":"2026-07-21T21:13:50.220865Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8141,"took":"62.366866ms","hash":2342844803,"current-db-size-bytes":58830848,"current-db-size":"59 MB","current-db-size-in-use-bytes":28467200,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-21T21:13:50.22093Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2342844803,"revision":8141,"compact-revision":6076} {"level":"info","ts":"2026-07-21T21:16:42.239805Z","caller":"traceutil/trace.go:171","msg":"trace[412050812] transaction","detail":"{read_only:false; response_revision:11856; number_of_response:1; }","duration":"106.992183ms","start":"2026-07-21T21:16:42.132784Z","end":"2026-07-21T21:16:42.239776Z","steps":["trace[412050812] 'process raft request' (duration: 96.018991ms)","trace[412050812] 'compare' (duration: 10.86628ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:18:50.161965Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10500} {"level":"info","ts":"2026-07-21T21:18:50.24219Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10500,"took":"78.013397ms","hash":613679313,"current-db-size-bytes":58830848,"current-db-size":"59 MB","current-db-size-in-use-bytes":26337280,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-21T21:18:50.24226Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":613679313,"revision":10500,"compact-revision":8141} {"level":"info","ts":"2026-07-21T21:23:19.773835Z","caller":"traceutil/trace.go:171","msg":"trace[355060820] linearizableReadLoop","detail":"{readStateIndex:15002; appliedIndex:15001; }","duration":"172.264072ms","start":"2026-07-21T21:23:19.60155Z","end":"2026-07-21T21:23:19.773814Z","steps":["trace[355060820] 'read index received' (duration: 79.142703ms)","trace[355060820] 'applied index is now lower than readState.Index' (duration: 93.120519ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:23:19.773889Z","caller":"traceutil/trace.go:171","msg":"trace[2007319087] transaction","detail":"{read_only:false; response_revision:14498; number_of_response:1; }","duration":"196.165618ms","start":"2026-07-21T21:23:19.577676Z","end":"2026-07-21T21:23:19.773842Z","steps":["trace[2007319087] 'process raft request' (duration: 103.121621ms)","trace[2007319087] 'compare' (duration: 92.914825ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:23:19.774031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.485757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-21T21:23:19.774062Z","caller":"traceutil/trace.go:171","msg":"trace[1540059879] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14498; }","duration":"172.550317ms","start":"2026-07-21T21:23:19.601502Z","end":"2026-07-21T21:23:19.774053Z","steps":["trace[1540059879] 'agreement among raft nodes before linearized reading' (duration: 172.389394ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:23:34.848065Z","caller":"traceutil/trace.go:171","msg":"trace[1534639262] transaction","detail":"{read_only:false; response_revision:14590; number_of_response:1; }","duration":"134.234306ms","start":"2026-07-21T21:23:34.713804Z","end":"2026-07-21T21:23:34.848038Z","steps":["trace[1534639262] 'process raft request' (duration: 108.041752ms)","trace[1534639262] 'compare' (duration: 25.96212ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:23:35.782738Z","caller":"traceutil/trace.go:171","msg":"trace[260314990] transaction","detail":"{read_only:false; response_revision:14596; number_of_response:1; }","duration":"159.281177ms","start":"2026-07-21T21:23:35.623399Z","end":"2026-07-21T21:23:35.78268Z","steps":["trace[260314990] 'process raft request' (duration: 98.679702ms)","trace[260314990] 'compare' (duration: 60.36751ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:23:50.168595Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12723} {"level":"info","ts":"2026-07-21T21:23:50.254726Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12723,"took":"83.747377ms","hash":1934191012,"current-db-size-bytes":58830848,"current-db-size":"59 MB","current-db-size-in-use-bytes":27729920,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-21T21:23:50.254796Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1934191012,"revision":12723,"compact-revision":10500} {"level":"info","ts":"2026-07-21T21:23:58.017117Z","caller":"traceutil/trace.go:171","msg":"trace[1469419584] transaction","detail":"{read_only:false; response_revision:14907; number_of_response:1; }","duration":"158.367787ms","start":"2026-07-21T21:23:57.85872Z","end":"2026-07-21T21:23:58.017088Z","steps":["trace[1469419584] 'process raft request' (duration: 158.212544ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:25:14.025626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.484868ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T21:25:14.025731Z","caller":"traceutil/trace.go:171","msg":"trace[1899935669] linearizableReadLoop","detail":"{readStateIndex:16208; appliedIndex:16207; }","duration":"154.830456ms","start":"2026-07-21T21:25:13.870884Z","end":"2026-07-21T21:25:14.025715Z","steps":["trace[1899935669] 'read index received' (duration: 3.109333ms)","trace[1899935669] 'applied index is now lower than readState.Index' (duration: 151.719882ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:25:14.025777Z","caller":"traceutil/trace.go:171","msg":"trace[1643018454] transaction","detail":"{read_only:false; response_revision:15667; number_of_response:1; }","duration":"188.266997ms","start":"2026-07-21T21:25:13.83747Z","end":"2026-07-21T21:25:14.025737Z","steps":["trace[1643018454] 'process raft request' (duration: 36.585165ms)","trace[1643018454] 'compare' (duration: 151.371096ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:25:14.025862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.975199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:25:14.025917Z","caller":"traceutil/trace.go:171","msg":"trace[522978031] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15667; }","duration":"155.06295ms","start":"2026-07-21T21:25:13.870839Z","end":"2026-07-21T21:25:14.025902Z","steps":["trace[522978031] 'agreement among raft nodes before linearized reading' (duration: 154.971838ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:25:14.026734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.784535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-07-21T21:25:14.026783Z","caller":"traceutil/trace.go:171","msg":"trace[422913962] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15667; }","duration":"155.854167ms","start":"2026-07-21T21:25:13.870916Z","end":"2026-07-21T21:25:14.02677Z","steps":["trace[422913962] 'agreement among raft nodes before linearized reading' (duration: 155.713664ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:25:14.026881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.307342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-07-21T21:25:14.026902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.50797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-07-21T21:25:14.026975Z","caller":"traceutil/trace.go:171","msg":"trace[1896995746] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15667; }","duration":"146.443485ms","start":"2026-07-21T21:25:13.880507Z","end":"2026-07-21T21:25:14.026951Z","steps":["trace[1896995746] 'agreement among raft nodes before linearized reading' (duration: 146.15737ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:25:14.027013Z","caller":"traceutil/trace.go:171","msg":"trace[1521895699] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15667; }","duration":"155.605762ms","start":"2026-07-21T21:25:13.87136Z","end":"2026-07-21T21:25:14.026966Z","steps":["trace[1521895699] 'agreement among raft nodes before linearized reading' (duration: 155.324326ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:28:50.173902Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14745} {"level":"info","ts":"2026-07-21T21:28:50.250662Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14745,"took":"74.331535ms","hash":2703640567,"current-db-size-bytes":58830848,"current-db-size":"59 MB","current-db-size-in-use-bytes":33579008,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-21T21:28:50.250734Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2703640567,"revision":14745,"compact-revision":12723} {"level":"warn","ts":"2026-07-21T21:31:26.348629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.610969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-21T21:31:26.348851Z","caller":"traceutil/trace.go:171","msg":"trace[120097105] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18377; }","duration":"228.839484ms","start":"2026-07-21T21:31:26.119993Z","end":"2026-07-21T21:31:26.348832Z","steps":["trace[120097105] 'range keys from in-memory index tree' (duration: 228.462595ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:31:26.723652Z","caller":"traceutil/trace.go:171","msg":"trace[762949876] linearizableReadLoop","detail":"{readStateIndex:19012; appliedIndex:19011; }","duration":"214.154224ms","start":"2026-07-21T21:31:26.509477Z","end":"2026-07-21T21:31:26.723631Z","steps":["trace[762949876] 'read index received' (duration: 213.95048ms)","trace[762949876] 'applied index is now lower than readState.Index' (duration: 202.724µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:31:26.723757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.260747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:31:26.723724Z","caller":"traceutil/trace.go:171","msg":"trace[1789300943] transaction","detail":"{read_only:false; response_revision:18381; number_of_response:1; }","duration":"221.146687ms","start":"2026-07-21T21:31:26.502528Z","end":"2026-07-21T21:31:26.723675Z","steps":["trace[1789300943] 'process raft request' (duration: 220.970962ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:31:26.723786Z","caller":"traceutil/trace.go:171","msg":"trace[1469223796] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18381; }","duration":"214.336359ms","start":"2026-07-21T21:31:26.50944Z","end":"2026-07-21T21:31:26.723776Z","steps":["trace[1469223796] 'agreement among raft nodes before linearized reading' (duration: 214.271988ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:31:27.31733Z","caller":"traceutil/trace.go:171","msg":"trace[1039168494] transaction","detail":"{read_only:false; response_revision:18383; number_of_response:1; }","duration":"189.619225ms","start":"2026-07-21T21:31:27.127677Z","end":"2026-07-21T21:31:27.317296Z","steps":["trace[1039168494] 'process raft request' (duration: 189.416201ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:31:27.337662Z","caller":"traceutil/trace.go:171","msg":"trace[1455477802] transaction","detail":"{read_only:false; response_revision:18384; number_of_response:1; }","duration":"208.220015ms","start":"2026-07-21T21:31:27.129394Z","end":"2026-07-21T21:31:27.337614Z","steps":["trace[1455477802] 'process raft request' (duration: 208.017101ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:33:02.013516Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c47ca1a7b1fe506b","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-21T21:33:02.018639Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-21T21:33:02.01874Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-21T21:33:50.182119Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17340} {"level":"info","ts":"2026-07-21T21:33:50.288894Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17340,"took":"103.143158ms","hash":87157191,"current-db-size-bytes":58830848,"current-db-size":"59 MB","current-db-size-in-use-bytes":34209792,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-21T21:33:50.288958Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":87157191,"revision":17340,"compact-revision":14745} {"level":"info","ts":"2026-07-21T21:37:29.129417Z","caller":"traceutil/trace.go:171","msg":"trace[112603970] transaction","detail":"{read_only:false; response_revision:21149; number_of_response:1; }","duration":"139.581369ms","start":"2026-07-21T21:37:28.989811Z","end":"2026-07-21T21:37:29.129392Z","steps":["trace[112603970] 'process raft request' (duration: 139.405205ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:37:29.141496Z","caller":"traceutil/trace.go:171","msg":"trace[591208541] transaction","detail":"{read_only:false; response_revision:21150; number_of_response:1; }","duration":"148.540261ms","start":"2026-07-21T21:37:28.992926Z","end":"2026-07-21T21:37:29.141467Z","steps":["trace[591208541] 'process raft request' (duration: 148.204104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:37:29.544404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.803026ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T21:37:29.544627Z","caller":"traceutil/trace.go:171","msg":"trace[1259025381] linearizableReadLoop","detail":"{readStateIndex:21876; appliedIndex:21874; }","duration":"382.953536ms","start":"2026-07-21T21:37:29.161656Z","end":"2026-07-21T21:37:29.54461Z","steps":["trace[1259025381] 'read index received' (duration: 142.571828ms)","trace[1259025381] 'applied index is now lower than readState.Index' (duration: 240.380688ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:37:29.544756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.098759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:37:29.544753Z","caller":"traceutil/trace.go:171","msg":"trace[634592430] transaction","detail":"{read_only:false; response_revision:21151; number_of_response:1; }","duration":"398.274978ms","start":"2026-07-21T21:37:29.146423Z","end":"2026-07-21T21:37:29.544698Z","steps":["trace[634592430] 'process raft request' (duration: 157.786738ms)","trace[634592430] 'compare' (duration: 239.296086ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:37:29.544798Z","caller":"traceutil/trace.go:171","msg":"trace[1486033893] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:21152; }","duration":"383.183751ms","start":"2026-07-21T21:37:29.1616Z","end":"2026-07-21T21:37:29.544783Z","steps":["trace[1486033893] 'agreement among raft nodes before linearized reading' (duration: 383.113429ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:37:29.544849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:37:29.161577Z","time spent":"383.257493ms","remote":"127.0.0.1:42800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true "} {"level":"warn","ts":"2026-07-21T21:37:29.544922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:37:29.146409Z","time spent":"398.419902ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:37:29.54529Z","caller":"traceutil/trace.go:171","msg":"trace[1515851349] transaction","detail":"{read_only:false; response_revision:21152; number_of_response:1; }","duration":"398.708338ms","start":"2026-07-21T21:37:29.146561Z","end":"2026-07-21T21:37:29.545269Z","steps":["trace[1515851349] 'process raft request' (duration: 397.976173ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:37:29.545402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:37:29.146546Z","time spent":"398.816109ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:37:29.85069Z","caller":"traceutil/trace.go:171","msg":"trace[455308960] transaction","detail":"{read_only:false; response_revision:21153; number_of_response:1; }","duration":"232.294692ms","start":"2026-07-21T21:37:29.61837Z","end":"2026-07-21T21:37:29.850664Z","steps":["trace[455308960] 'process raft request' (duration: 232.15972ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:37:30.13207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.912317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T21:37:30.132236Z","caller":"traceutil/trace.go:171","msg":"trace[1000167849] transaction","detail":"{read_only:false; response_revision:21154; number_of_response:1; }","duration":"168.872004ms","start":"2026-07-21T21:37:29.963344Z","end":"2026-07-21T21:37:30.132216Z","steps":["trace[1000167849] 'process raft request' (duration: 54.710252ms)","trace[1000167849] 'compare' (duration: 113.779324ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:37:30.288223Z","caller":"traceutil/trace.go:171","msg":"trace[1225472665] transaction","detail":"{read_only:false; response_revision:21155; number_of_response:1; }","duration":"138.527098ms","start":"2026-07-21T21:37:30.149655Z","end":"2026-07-21T21:37:30.288182Z","steps":["trace[1225472665] 'process raft request' (duration: 116.271605ms)","trace[1225472665] 'compare' (duration: 21.643359ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:37:30.288401Z","caller":"traceutil/trace.go:171","msg":"trace[576570303] transaction","detail":"{read_only:false; response_revision:21156; number_of_response:1; }","duration":"138.556858ms","start":"2026-07-21T21:37:30.149835Z","end":"2026-07-21T21:37:30.288391Z","steps":["trace[576570303] 'process raft request' (duration: 137.869293ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:37:30.566679Z","caller":"traceutil/trace.go:171","msg":"trace[586418583] transaction","detail":"{read_only:false; response_revision:21157; number_of_response:1; }","duration":"198.604789ms","start":"2026-07-21T21:37:30.368048Z","end":"2026-07-21T21:37:30.566653Z","steps":["trace[586418583] 'process raft request' (duration: 176.180633ms)","trace[586418583] 'compare' (duration: 22.300593ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:38:50.188054Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19646} {"level":"info","ts":"2026-07-21T21:38:50.297755Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19646,"took":"106.151229ms","hash":2879557060,"current-db-size-bytes":58830848,"current-db-size":"59 MB","current-db-size-in-use-bytes":29732864,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-21T21:38:50.297821Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2879557060,"revision":19646,"compact-revision":17340} {"level":"info","ts":"2026-07-21T21:41:31.795888Z","caller":"traceutil/trace.go:171","msg":"trace[1494629621] transaction","detail":"{read_only:false; response_revision:23677; number_of_response:1; }","duration":"114.322584ms","start":"2026-07-21T21:41:31.681541Z","end":"2026-07-21T21:41:31.795863Z","steps":["trace[1494629621] 'process raft request' (duration: 114.266713ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:41:31.795974Z","caller":"traceutil/trace.go:171","msg":"trace[973884339] transaction","detail":"{read_only:false; response_revision:23676; number_of_response:1; }","duration":"114.644422ms","start":"2026-07-21T21:41:31.681273Z","end":"2026-07-21T21:41:31.795918Z","steps":["trace[973884339] 'process raft request' (duration: 114.350666ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:41:32.073401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.340924ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T21:41:32.073644Z","caller":"traceutil/trace.go:171","msg":"trace[1983797671] transaction","detail":"{read_only:false; response_revision:23678; number_of_response:1; }","duration":"389.225995ms","start":"2026-07-21T21:41:31.684342Z","end":"2026-07-21T21:41:32.073568Z","steps":["trace[1983797671] 'process raft request' (duration: 218.633167ms)","trace[1983797671] 'compare' (duration: 170.209691ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:41:32.07386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:41:31.684319Z","time spent":"389.436311ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:41:32.117167Z","caller":"traceutil/trace.go:171","msg":"trace[1177948503] linearizableReadLoop","detail":"{readStateIndex:24477; appliedIndex:24476; }","duration":"213.40599ms","start":"2026-07-21T21:41:31.903736Z","end":"2026-07-21T21:41:32.117142Z","steps":["trace[1177948503] 'read index received' (duration: 213.232117ms)","trace[1177948503] 'applied index is now lower than readState.Index' (duration: 172.843µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:41:32.117449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.700257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-manager\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-07-21T21:41:32.117491Z","caller":"traceutil/trace.go:171","msg":"trace[333676494] range","detail":"{range_begin:/registry/serviceaccounts/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-manager; range_end:; response_count:1; response_revision:23679; }","duration":"213.787778ms","start":"2026-07-21T21:41:31.903692Z","end":"2026-07-21T21:41:32.11748Z","steps":["trace[333676494] 'agreement among raft nodes before linearized reading' (duration: 213.602994ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:41:32.117491Z","caller":"traceutil/trace.go:171","msg":"trace[1279668468] transaction","detail":"{read_only:false; response_revision:23679; number_of_response:1; }","duration":"313.218831ms","start":"2026-07-21T21:41:31.804244Z","end":"2026-07-21T21:41:32.117463Z","steps":["trace[1279668468] 'process raft request' (duration: 312.760591ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:41:32.117636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:41:31.804218Z","time spent":"313.342204ms","remote":"127.0.0.1:41804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5252,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:41:32.126527Z","caller":"traceutil/trace.go:171","msg":"trace[890345625] transaction","detail":"{read_only:false; response_revision:23680; number_of_response:1; }","duration":"187.144696ms","start":"2026-07-21T21:41:31.939324Z","end":"2026-07-21T21:41:32.126469Z","steps":["trace[890345625] 'process raft request' (duration: 183.159775ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:41:32.126637Z","caller":"traceutil/trace.go:171","msg":"trace[1625388612] transaction","detail":"{read_only:false; response_revision:23681; number_of_response:1; }","duration":"155.182816ms","start":"2026-07-21T21:41:31.971357Z","end":"2026-07-21T21:41:32.126539Z","steps":["trace[1625388612] 'process raft request' (duration: 151.310908ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:41:32.126639Z","caller":"traceutil/trace.go:171","msg":"trace[1255502892] transaction","detail":"{read_only:false; response_revision:23682; number_of_response:1; }","duration":"148.656313ms","start":"2026-07-21T21:41:31.977847Z","end":"2026-07-21T21:41:32.126503Z","steps":["trace[1255502892] 'process raft request' (duration: 144.883337ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:42:32.915185Z","caller":"traceutil/trace.go:171","msg":"trace[694818467] transaction","detail":"{read_only:false; response_revision:24391; number_of_response:1; }","duration":"148.534921ms","start":"2026-07-21T21:42:32.766615Z","end":"2026-07-21T21:42:32.91515Z","steps":["trace[694818467] 'process raft request' (duration: 148.311997ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:42:32.915394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.105537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-07-21T21:42:32.915472Z","caller":"traceutil/trace.go:171","msg":"trace[462335601] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:24391; }","duration":"135.22451ms","start":"2026-07-21T21:42:32.780205Z","end":"2026-07-21T21:42:32.91543Z","steps":["trace[462335601] 'agreement among raft nodes before linearized reading' (duration: 135.014416ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:42:32.915159Z","caller":"traceutil/trace.go:171","msg":"trace[457873225] linearizableReadLoop","detail":"{readStateIndex:25205; appliedIndex:25204; }","duration":"134.847333ms","start":"2026-07-21T21:42:32.780258Z","end":"2026-07-21T21:42:32.915105Z","steps":["trace[457873225] 'read index received' (duration: 134.640048ms)","trace[457873225] 'applied index is now lower than readState.Index' (duration: 206.545µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:42:41.751699Z","caller":"traceutil/trace.go:171","msg":"trace[1379768173] transaction","detail":"{read_only:false; response_revision:24459; number_of_response:1; }","duration":"110.073928ms","start":"2026-07-21T21:42:41.641597Z","end":"2026-07-21T21:42:41.751671Z","steps":["trace[1379768173] 'process raft request' (duration: 109.846574ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:42:50.113425Z","caller":"traceutil/trace.go:171","msg":"trace[483880159] transaction","detail":"{read_only:false; response_revision:24521; number_of_response:1; }","duration":"117.656643ms","start":"2026-07-21T21:42:49.995727Z","end":"2026-07-21T21:42:50.113384Z","steps":["trace[483880159] 'process raft request' (duration: 104.589408ms)","trace[483880159] 'compare' (duration: 12.842771ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:43:50.194536Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21864} {"level":"info","ts":"2026-07-21T21:43:50.304219Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21864,"took":"106.862343ms","hash":373454768,"current-db-size-bytes":58830848,"current-db-size":"59 MB","current-db-size-in-use-bytes":44961792,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-07-21T21:43:50.304349Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":373454768,"revision":21864,"compact-revision":19646} {"level":"info","ts":"2026-07-21T21:44:43.0964Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006732.wal"} {"level":"warn","ts":"2026-07-21T21:44:46.825994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.447961ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T21:44:46.826414Z","caller":"traceutil/trace.go:171","msg":"trace[711603906] transaction","detail":"{read_only:false; response_revision:25647; number_of_response:1; }","duration":"202.254563ms","start":"2026-07-21T21:44:46.624058Z","end":"2026-07-21T21:44:46.826313Z","steps":["trace[711603906] 'process raft request' (duration: 75.312882ms)","trace[711603906] 'compare' (duration: 119.061601ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:44:47.138991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.665224ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T21:44:47.139068Z","caller":"traceutil/trace.go:171","msg":"trace[1721694749] transaction","detail":"{read_only:false; response_revision:25649; number_of_response:1; }","duration":"244.135246ms","start":"2026-07-21T21:44:46.894918Z","end":"2026-07-21T21:44:47.139053Z","steps":["trace[1721694749] 'process raft request' (duration: 92.350759ms)","trace[1721694749] 'compare' (duration: 151.309857ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:44:47.160242Z","caller":"traceutil/trace.go:171","msg":"trace[1440817436] transaction","detail":"{read_only:false; response_revision:25650; number_of_response:1; }","duration":"253.403244ms","start":"2026-07-21T21:44:46.906814Z","end":"2026-07-21T21:44:47.160217Z","steps":["trace[1440817436] 'process raft request' (duration: 253.275911ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:44:47.237202Z","caller":"traceutil/trace.go:171","msg":"trace[992585843] transaction","detail":"{read_only:false; response_revision:25651; number_of_response:1; }","duration":"116.220305ms","start":"2026-07-21T21:44:47.120952Z","end":"2026-07-21T21:44:47.237173Z","steps":["trace[992585843] 'process raft request' (duration: 115.779926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:44:47.52727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.413847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-07-21T21:44:47.527289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.795818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-21T21:44:47.527328Z","caller":"traceutil/trace.go:171","msg":"trace[622182876] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:25651; }","duration":"174.51814ms","start":"2026-07-21T21:44:47.352796Z","end":"2026-07-21T21:44:47.527315Z","steps":["trace[622182876] 'count revisions from in-memory index tree' (duration: 174.316045ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:44:47.527339Z","caller":"traceutil/trace.go:171","msg":"trace[717285467] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:25651; }","duration":"211.889699ms","start":"2026-07-21T21:44:47.315438Z","end":"2026-07-21T21:44:47.527327Z","steps":["trace[717285467] 'count revisions from in-memory index tree' (duration: 211.678505ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:44:48.516729Z","caller":"traceutil/trace.go:171","msg":"trace[417909356] transaction","detail":"{read_only:false; response_revision:25660; number_of_response:1; }","duration":"121.579653ms","start":"2026-07-21T21:44:48.395118Z","end":"2026-07-21T21:44:48.516697Z","steps":["trace[417909356] 'process raft request' (duration: 121.409849ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:44:48.699926Z","caller":"traceutil/trace.go:171","msg":"trace[367174070] transaction","detail":"{read_only:false; response_revision:25661; number_of_response:1; }","duration":"177.580592ms","start":"2026-07-21T21:44:48.522302Z","end":"2026-07-21T21:44:48.699883Z","steps":["trace[367174070] 'process raft request' (duration: 134.471525ms)","trace[367174070] 'compare' (duration: 42.865802ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:44:48.772112Z","caller":"traceutil/trace.go:171","msg":"trace[817516309] transaction","detail":"{read_only:false; response_revision:25662; number_of_response:1; }","duration":"221.371893ms","start":"2026-07-21T21:44:48.550689Z","end":"2026-07-21T21:44:48.772061Z","steps":["trace[817516309] 'process raft request' (duration: 221.166059ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:44:48.99036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.349612ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-07-21T21:44:48.990569Z","caller":"traceutil/trace.go:171","msg":"trace[1440627007] linearizableReadLoop","detail":"{readStateIndex:26512; appliedIndex:26511; }","duration":"158.82046ms","start":"2026-07-21T21:44:48.831732Z","end":"2026-07-21T21:44:48.990552Z","steps":["trace[1440627007] 'read index received' (duration: 34.43µs)","trace[1440627007] 'applied index is now lower than readState.Index' (duration: 158.78483ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:44:48.990724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.996794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-21T21:44:48.990751Z","caller":"traceutil/trace.go:171","msg":"trace[1147383473] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25662; }","duration":"159.083406ms","start":"2026-07-21T21:44:48.831659Z","end":"2026-07-21T21:44:48.990742Z","steps":["trace[1147383473] 'agreement among raft nodes before linearized reading' (duration: 158.948473ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:44:48.990827Z","caller":"traceutil/trace.go:171","msg":"trace[1707375853] transaction","detail":"{read_only:false; response_revision:25663; number_of_response:1; }","duration":"142.679592ms","start":"2026-07-21T21:44:48.848113Z","end":"2026-07-21T21:44:48.990792Z","steps":["trace[1707375853] 'process raft request' (duration: 142.347075ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:44:49.278004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.849025ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T21:44:49.27849Z","caller":"traceutil/trace.go:171","msg":"trace[979874461] transaction","detail":"{read_only:false; response_revision:25665; number_of_response:1; }","duration":"134.161929ms","start":"2026-07-21T21:44:49.14411Z","end":"2026-07-21T21:44:49.278272Z","steps":["trace[979874461] 'process raft request' (duration: 134.061397ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:44:49.278473Z","caller":"traceutil/trace.go:171","msg":"trace[1998696099] transaction","detail":"{read_only:false; response_revision:25664; number_of_response:1; }","duration":"283.512426ms","start":"2026-07-21T21:44:48.994738Z","end":"2026-07-21T21:44:49.27825Z","steps":["trace[1998696099] 'process raft request' (duration: 140.261893ms)","trace[1998696099] 'compare' (duration: 142.502308ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:44:49.278712Z","caller":"traceutil/trace.go:171","msg":"trace[945477027] linearizableReadLoop","detail":"{readStateIndex:26514; appliedIndex:26513; }","duration":"179.786218ms","start":"2026-07-21T21:44:49.098912Z","end":"2026-07-21T21:44:49.278698Z","steps":["trace[945477027] 'read index received' (duration: 36.073774ms)","trace[945477027] 'applied index is now lower than readState.Index' (duration: 143.710634ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:44:49.278917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.983072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:44:49.278974Z","caller":"traceutil/trace.go:171","msg":"trace[1951013138] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:25665; }","duration":"180.093583ms","start":"2026-07-21T21:44:49.098869Z","end":"2026-07-21T21:44:49.278962Z","steps":["trace[1951013138] 'agreement among raft nodes before linearized reading' (duration: 179.993631ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:44:49.280796Z","caller":"traceutil/trace.go:171","msg":"trace[291639015] transaction","detail":"{read_only:false; response_revision:25666; number_of_response:1; }","duration":"113.906807ms","start":"2026-07-21T21:44:49.166868Z","end":"2026-07-21T21:44:49.280775Z","steps":["trace[291639015] 'process raft request' (duration: 113.790324ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:44:54.713604Z","caller":"traceutil/trace.go:171","msg":"trace[1919084478] transaction","detail":"{read_only:false; response_revision:25718; number_of_response:1; }","duration":"174.474149ms","start":"2026-07-21T21:44:54.53909Z","end":"2026-07-21T21:44:54.713564Z","steps":["trace[1919084478] 'process raft request' (duration: 174.264935ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:44:55.047965Z","caller":"traceutil/trace.go:171","msg":"trace[1209327783] linearizableReadLoop","detail":"{readStateIndex:26570; appliedIndex:26569; }","duration":"324.124353ms","start":"2026-07-21T21:44:54.723817Z","end":"2026-07-21T21:44:55.047941Z","steps":["trace[1209327783] 'read index received' (duration: 288.641841ms)","trace[1209327783] 'applied index is now lower than readState.Index' (duration: 35.481622ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:44:55.047851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.394626ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:44:55.048026Z","caller":"traceutil/trace.go:171","msg":"trace[1483427921] transaction","detail":"{read_only:false; response_revision:25719; number_of_response:1; }","duration":"326.479531ms","start":"2026-07-21T21:44:54.721502Z","end":"2026-07-21T21:44:55.047981Z","steps":["trace[1483427921] 'process raft request' (duration: 290.936407ms)","trace[1483427921] 'compare' (duration: 35.408021ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:44:55.048078Z","caller":"traceutil/trace.go:171","msg":"trace[1183672533] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25718; }","duration":"230.641681ms","start":"2026-07-21T21:44:54.817405Z","end":"2026-07-21T21:44:55.048047Z","steps":["trace[1183672533] 'range keys from in-memory index tree' (duration: 230.335025ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:44:55.048148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.311427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-07-21T21:44:55.04818Z","caller":"traceutil/trace.go:171","msg":"trace[1236634413] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25719; }","duration":"324.371037ms","start":"2026-07-21T21:44:54.723798Z","end":"2026-07-21T21:44:55.048169Z","steps":["trace[1236634413] 'agreement among raft nodes before linearized reading' (duration: 324.234945ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:44:55.04821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:44:54.723791Z","time spent":"324.413359ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1878,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-21T21:44:55.048279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:44:54.721488Z","time spent":"326.601434ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:44:55.36731Z","caller":"traceutil/trace.go:171","msg":"trace[539660289] linearizableReadLoop","detail":"{readStateIndex:26572; appliedIndex:26570; }","duration":"315.962276ms","start":"2026-07-21T21:44:55.051326Z","end":"2026-07-21T21:44:55.367288Z","steps":["trace[539660289] 'read index received' (duration: 295.170034ms)","trace[539660289] 'applied index is now lower than readState.Index' (duration: 20.791212ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:44:55.367348Z","caller":"traceutil/trace.go:171","msg":"trace[1345966697] transaction","detail":"{read_only:false; response_revision:25720; number_of_response:1; }","duration":"575.485965ms","start":"2026-07-21T21:44:54.791833Z","end":"2026-07-21T21:44:55.367319Z","steps":["trace[1345966697] 'process raft request' (duration: 554.727903ms)","trace[1345966697] 'compare' (duration: 20.549668ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:44:55.36743Z","caller":"traceutil/trace.go:171","msg":"trace[905534649] transaction","detail":"{read_only:false; response_revision:25721; number_of_response:1; }","duration":"357.186936ms","start":"2026-07-21T21:44:55.010207Z","end":"2026-07-21T21:44:55.367394Z","steps":["trace[905534649] 'process raft request' (duration: 357.029082ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:44:55.367634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.291564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-a556561f-f186-4fa9-95c3-ea42f5ed7ed0\" ","response":"range_response_count:1 size:1304"} {"level":"info","ts":"2026-07-21T21:44:55.367716Z","caller":"traceutil/trace.go:171","msg":"trace[1412631847] range","detail":"{range_begin:/registry/persistentvolumes/pvc-a556561f-f186-4fa9-95c3-ea42f5ed7ed0; range_end:; response_count:1; response_revision:25721; }","duration":"316.380756ms","start":"2026-07-21T21:44:55.051314Z","end":"2026-07-21T21:44:55.367694Z","steps":["trace[1412631847] 'agreement among raft nodes before linearized reading' (duration: 316.134931ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:44:55.367761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:44:55.05126Z","time spent":"316.491838ms","remote":"127.0.0.1:41506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1328,"request content":"key:\"/registry/persistentvolumes/pvc-a556561f-f186-4fa9-95c3-ea42f5ed7ed0\" "} {"level":"warn","ts":"2026-07-21T21:44:55.367676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:44:54.791798Z","time spent":"575.75645ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:44:55.367823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:44:55.01018Z","time spent":"357.39318ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:44:55.539749Z","caller":"traceutil/trace.go:171","msg":"trace[712023057] linearizableReadLoop","detail":"{readStateIndex:26576; appliedIndex:26572; }","duration":"172.264544ms","start":"2026-07-21T21:44:55.367466Z","end":"2026-07-21T21:44:55.539731Z","steps":["trace[712023057] 'read index received' (duration: 149.574152ms)","trace[712023057] 'applied index is now lower than readState.Index' (duration: 22.689802ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:44:55.539766Z","caller":"traceutil/trace.go:171","msg":"trace[390869182] transaction","detail":"{read_only:false; response_revision:25723; number_of_response:1; }","duration":"243.329409ms","start":"2026-07-21T21:44:55.296406Z","end":"2026-07-21T21:44:55.539735Z","steps":["trace[390869182] 'process raft request' (duration: 243.228196ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:44:55.539857Z","caller":"traceutil/trace.go:171","msg":"trace[45479176] transaction","detail":"{read_only:false; response_revision:25724; number_of_response:1; }","duration":"241.148656ms","start":"2026-07-21T21:44:55.298699Z","end":"2026-07-21T21:44:55.539848Z","steps":["trace[45479176] 'process raft request' (duration: 240.969121ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:44:55.539925Z","caller":"traceutil/trace.go:171","msg":"trace[1313870241] transaction","detail":"{read_only:false; response_revision:25722; number_of_response:1; }","duration":"247.043956ms","start":"2026-07-21T21:44:55.292869Z","end":"2026-07-21T21:44:55.539913Z","steps":["trace[1313870241] 'process raft request' (duration: 224.206181ms)","trace[1313870241] 'compare' (duration: 22.450706ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:44:55.539944Z","caller":"traceutil/trace.go:171","msg":"trace[242323102] transaction","detail":"{read_only:false; response_revision:25725; number_of_response:1; }","duration":"240.770047ms","start":"2026-07-21T21:44:55.299168Z","end":"2026-07-21T21:44:55.539938Z","steps":["trace[242323102] 'process raft request' (duration: 240.528753ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:44:55.540096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.230935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-21T21:44:55.540127Z","caller":"traceutil/trace.go:171","msg":"trace[859749648] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25725; }","duration":"237.311447ms","start":"2026-07-21T21:44:55.302808Z","end":"2026-07-21T21:44:55.540119Z","steps":["trace[859749648] 'agreement among raft nodes before linearized reading' (duration: 237.192615ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:44:55.540148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.546004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/default\" ","response":"range_response_count:1 size:176"} {"level":"info","ts":"2026-07-21T21:44:55.540182Z","caller":"traceutil/trace.go:171","msg":"trace[208674278] range","detail":"{range_begin:/registry/serviceaccounts/openstack/default; range_end:; response_count:1; response_revision:25725; }","duration":"128.625676ms","start":"2026-07-21T21:44:55.411547Z","end":"2026-07-21T21:44:55.540172Z","steps":["trace[208674278] 'agreement among raft nodes before linearized reading' (duration: 128.474543ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:44:55.540292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.966421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:44:55.540383Z","caller":"traceutil/trace.go:171","msg":"trace[238907538] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:25725; }","duration":"267.126454ms","start":"2026-07-21T21:44:55.273238Z","end":"2026-07-21T21:44:55.540364Z","steps":["trace[238907538] 'agreement among raft nodes before linearized reading' (duration: 266.92069ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:44:55.945975Z","caller":"traceutil/trace.go:171","msg":"trace[1917106506] transaction","detail":"{read_only:false; response_revision:25727; number_of_response:1; }","duration":"195.776012ms","start":"2026-07-21T21:44:55.75015Z","end":"2026-07-21T21:44:55.945926Z","steps":["trace[1917106506] 'process raft request' (duration: 195.588768ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:44:55.969901Z","caller":"traceutil/trace.go:171","msg":"trace[222608510] transaction","detail":"{read_only:false; response_revision:25728; number_of_response:1; }","duration":"151.466361ms","start":"2026-07-21T21:44:55.81841Z","end":"2026-07-21T21:44:55.969876Z","steps":["trace[222608510] 'process raft request' (duration: 151.092363ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:44:56.144128Z","caller":"traceutil/trace.go:171","msg":"trace[889222034] transaction","detail":"{read_only:false; response_revision:25730; number_of_response:1; }","duration":"168.027207ms","start":"2026-07-21T21:44:55.976065Z","end":"2026-07-21T21:44:56.144092Z","steps":["trace[889222034] 'process raft request' (duration: 167.853594ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:44:56.488436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.111018ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T21:44:56.488522Z","caller":"traceutil/trace.go:171","msg":"trace[939630685] transaction","detail":"{read_only:false; response_revision:25732; number_of_response:1; }","duration":"198.526998ms","start":"2026-07-21T21:44:56.28998Z","end":"2026-07-21T21:44:56.488507Z","steps":["trace[939630685] 'process raft request' (duration: 61.269377ms)","trace[939630685] 'compare' (duration: 136.930555ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:44:56.753865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.820033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-21T21:44:56.754005Z","caller":"traceutil/trace.go:171","msg":"trace[684075867] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25733; }","duration":"166.040277ms","start":"2026-07-21T21:44:56.587936Z","end":"2026-07-21T21:44:56.753976Z","steps":["trace[684075867] 'range keys from in-memory index tree' (duration: 165.609138ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:02.633467Z","caller":"traceutil/trace.go:171","msg":"trace[715122345] transaction","detail":"{read_only:false; response_revision:25802; number_of_response:1; }","duration":"124.687357ms","start":"2026-07-21T21:45:02.508748Z","end":"2026-07-21T21:45:02.633435Z","steps":["trace[715122345] 'process raft request' (duration: 124.505033ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:02.931413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.642188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/default\" ","response":"range_response_count:1 size:176"} {"level":"info","ts":"2026-07-21T21:45:02.931468Z","caller":"traceutil/trace.go:171","msg":"trace[573981893] range","detail":"{range_begin:/registry/serviceaccounts/openstack/default; range_end:; response_count:1; response_revision:25802; }","duration":"161.734401ms","start":"2026-07-21T21:45:02.769721Z","end":"2026-07-21T21:45:02.931455Z","steps":["trace[573981893] 'range keys from in-memory index tree' (duration: 161.501596ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:03.058894Z","caller":"traceutil/trace.go:171","msg":"trace[1866797499] linearizableReadLoop","detail":"{readStateIndex:26655; appliedIndex:26654; }","duration":"126.431913ms","start":"2026-07-21T21:45:02.932434Z","end":"2026-07-21T21:45:03.058866Z","steps":["trace[1866797499] 'read index received' (duration: 126.106215ms)","trace[1866797499] 'applied index is now lower than readState.Index' (duration: 324.668µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:45:03.058933Z","caller":"traceutil/trace.go:171","msg":"trace[932286941] transaction","detail":"{read_only:false; response_revision:25803; number_of_response:1; }","duration":"287.911437ms","start":"2026-07-21T21:45:02.771006Z","end":"2026-07-21T21:45:03.058917Z","steps":["trace[932286941] 'process raft request' (duration: 287.690052ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:03.059084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.633197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:11488"} {"level":"info","ts":"2026-07-21T21:45:03.059122Z","caller":"traceutil/trace.go:171","msg":"trace[1616026183] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:25803; }","duration":"126.699378ms","start":"2026-07-21T21:45:02.932417Z","end":"2026-07-21T21:45:03.059116Z","steps":["trace[1616026183] 'agreement among raft nodes before linearized reading' (duration: 126.549555ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:03.252864Z","caller":"traceutil/trace.go:171","msg":"trace[211209712] transaction","detail":"{read_only:false; response_revision:25804; number_of_response:1; }","duration":"153.332118ms","start":"2026-07-21T21:45:03.099506Z","end":"2026-07-21T21:45:03.252839Z","steps":["trace[211209712] 'process raft request' (duration: 128.167726ms)","trace[211209712] 'compare' (duration: 25.019609ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:45:03.547508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.163939ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"info","ts":"2026-07-21T21:45:03.54775Z","caller":"traceutil/trace.go:171","msg":"trace[1138053795] transaction","detail":"{read_only:false; response_revision:25805; number_of_response:1; }","duration":"151.051982ms","start":"2026-07-21T21:45:03.39668Z","end":"2026-07-21T21:45:03.547732Z","steps":["trace[1138053795] 'process raft request' (duration: 150.93061ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:03.561452Z","caller":"traceutil/trace.go:171","msg":"trace[1910871812] transaction","detail":"{read_only:false; response_revision:25806; number_of_response:1; }","duration":"164.429755ms","start":"2026-07-21T21:45:03.396992Z","end":"2026-07-21T21:45:03.561422Z","steps":["trace[1910871812] 'process raft request' (duration: 164.241941ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:03.706863Z","caller":"traceutil/trace.go:171","msg":"trace[310255469] linearizableReadLoop","detail":"{readStateIndex:26660; appliedIndex:26659; }","duration":"144.287075ms","start":"2026-07-21T21:45:03.562556Z","end":"2026-07-21T21:45:03.706844Z","steps":["trace[310255469] 'read index received' (duration: 139.519808ms)","trace[310255469] 'applied index is now lower than readState.Index' (duration: 4.766567ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:45:03.706996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.420998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:45:03.70702Z","caller":"traceutil/trace.go:171","msg":"trace[1846509813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25807; }","duration":"144.491519ms","start":"2026-07-21T21:45:03.562521Z","end":"2026-07-21T21:45:03.707012Z","steps":["trace[1846509813] 'agreement among raft nodes before linearized reading' (duration: 144.412997ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:03.706903Z","caller":"traceutil/trace.go:171","msg":"trace[560105825] transaction","detail":"{read_only:false; response_revision:25807; number_of_response:1; }","duration":"157.821499ms","start":"2026-07-21T21:45:03.549051Z","end":"2026-07-21T21:45:03.706873Z","steps":["trace[560105825] 'process raft request' (duration: 153.009771ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:03.998658Z","caller":"traceutil/trace.go:171","msg":"trace[586588429] transaction","detail":"{read_only:false; response_revision:25808; number_of_response:1; }","duration":"425.610026ms","start":"2026-07-21T21:45:03.573031Z","end":"2026-07-21T21:45:03.998641Z","steps":["trace[586588429] 'process raft request' (duration: 378.190412ms)","trace[586588429] 'compare' (duration: 47.265491ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:45:03.998751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:03.573005Z","time spent":"425.697449ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:45:04.000237Z","caller":"traceutil/trace.go:171","msg":"trace[525198016] transaction","detail":"{read_only:false; response_revision:25809; number_of_response:1; }","duration":"422.416602ms","start":"2026-07-21T21:45:03.577796Z","end":"2026-07-21T21:45:04.000213Z","steps":["trace[525198016] 'process raft request' (duration: 420.824529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:04.000347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:03.577781Z","time spent":"422.504563ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:45:04.000562Z","caller":"traceutil/trace.go:171","msg":"trace[1296924869] linearizableReadLoop","detail":"{readStateIndex:26663; appliedIndex:26660; }","duration":"293.641913ms","start":"2026-07-21T21:45:03.706913Z","end":"2026-07-21T21:45:04.000555Z","steps":["trace[1296924869] 'read index received' (duration: 244.286479ms)","trace[1296924869] 'applied index is now lower than readState.Index' (duration: 49.354504ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:45:04.000689Z","caller":"traceutil/trace.go:171","msg":"trace[105959368] transaction","detail":"{read_only:false; response_revision:25810; number_of_response:1; }","duration":"422.81198ms","start":"2026-07-21T21:45:03.577871Z","end":"2026-07-21T21:45:04.000683Z","steps":["trace[105959368] 'process raft request' (duration: 422.28176ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:04.000734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:03.577864Z","time spent":"422.846721ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:45:04.000848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.663222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-21T21:45:04.000877Z","caller":"traceutil/trace.go:171","msg":"trace[896548796] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25810; }","duration":"291.719573ms","start":"2026-07-21T21:45:03.70915Z","end":"2026-07-21T21:45:04.00087Z","steps":["trace[896548796] 'agreement among raft nodes before linearized reading' (duration: 291.608231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:04.00097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.643474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-21T21:45:04.000999Z","caller":"traceutil/trace.go:171","msg":"trace[157724905] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25810; }","duration":"413.696445ms","start":"2026-07-21T21:45:03.587295Z","end":"2026-07-21T21:45:04.000991Z","steps":["trace[157724905] 'agreement among raft nodes before linearized reading' (duration: 413.607283ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:04.00102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:03.587285Z","time spent":"413.728726ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-21T21:45:04.001114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.28165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:45:04.001135Z","caller":"traceutil/trace.go:171","msg":"trace[2084388368] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:25810; }","duration":"396.357102ms","start":"2026-07-21T21:45:03.604773Z","end":"2026-07-21T21:45:04.00113Z","steps":["trace[2084388368] 'agreement among raft nodes before linearized reading' (duration: 396.319112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:04.001153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:03.604753Z","time spent":"396.394794ms","remote":"127.0.0.1:37922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true "} {"level":"warn","ts":"2026-07-21T21:45:04.001938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.383744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-21T21:45:04.001964Z","caller":"traceutil/trace.go:171","msg":"trace[1669353287] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:25810; }","duration":"287.441576ms","start":"2026-07-21T21:45:03.714517Z","end":"2026-07-21T21:45:04.001959Z","steps":["trace[1669353287] 'agreement among raft nodes before linearized reading' (duration: 286.533888ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:04.185573Z","caller":"traceutil/trace.go:171","msg":"trace[2017981594] linearizableReadLoop","detail":"{readStateIndex:26665; appliedIndex:26663; }","duration":"147.788136ms","start":"2026-07-21T21:45:04.037745Z","end":"2026-07-21T21:45:04.185533Z","steps":["trace[2017981594] 'read index received' (duration: 107.361943ms)","trace[2017981594] 'applied index is now lower than readState.Index' (duration: 40.424863ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:45:04.185602Z","caller":"traceutil/trace.go:171","msg":"trace[2036548377] transaction","detail":"{read_only:false; response_revision:25811; number_of_response:1; }","duration":"180.307208ms","start":"2026-07-21T21:45:04.00527Z","end":"2026-07-21T21:45:04.185577Z","steps":["trace[2036548377] 'process raft request' (duration: 139.763183ms)","trace[2036548377] 'compare' (duration: 40.195618ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:45:04.185799Z","caller":"traceutil/trace.go:171","msg":"trace[582541167] transaction","detail":"{read_only:false; response_revision:25812; number_of_response:1; }","duration":"178.080922ms","start":"2026-07-21T21:45:04.00771Z","end":"2026-07-21T21:45:04.185791Z","steps":["trace[582541167] 'process raft request' (duration: 177.728605ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:04.18599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.212335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-21T21:45:04.1861Z","caller":"traceutil/trace.go:171","msg":"trace[1632327135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25812; }","duration":"148.372818ms","start":"2026-07-21T21:45:04.037709Z","end":"2026-07-21T21:45:04.186082Z","steps":["trace[1632327135] 'agreement among raft nodes before linearized reading' (duration: 148.091553ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:04.323791Z","caller":"traceutil/trace.go:171","msg":"trace[1887313171] transaction","detail":"{read_only:false; response_revision:25813; number_of_response:1; }","duration":"134.608838ms","start":"2026-07-21T21:45:04.189153Z","end":"2026-07-21T21:45:04.323762Z","steps":["trace[1887313171] 'process raft request' (duration: 111.291354ms)","trace[1887313171] 'compare' (duration: 23.175701ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:45:04.527242Z","caller":"traceutil/trace.go:171","msg":"trace[2074645188] linearizableReadLoop","detail":"{readStateIndex:26667; appliedIndex:26665; }","duration":"282.614299ms","start":"2026-07-21T21:45:04.244604Z","end":"2026-07-21T21:45:04.527219Z","steps":["trace[2074645188] 'read index received' (duration: 55.851036ms)","trace[2074645188] 'applied index is now lower than readState.Index' (duration: 226.761953ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:45:04.527252Z","caller":"traceutil/trace.go:171","msg":"trace[1108075528] transaction","detail":"{read_only:false; response_revision:25814; number_of_response:1; }","duration":"336.76697ms","start":"2026-07-21T21:45:04.190463Z","end":"2026-07-21T21:45:04.52723Z","steps":["trace[1108075528] 'process raft request' (duration: 260.11318ms)","trace[1108075528] 'compare' (duration: 76.523067ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:45:04.52738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:04.190441Z","time spent":"336.870072ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:45:04.527389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.408184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-21T21:45:04.527426Z","caller":"traceutil/trace.go:171","msg":"trace[1771112138] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:25814; }","duration":"134.488926ms","start":"2026-07-21T21:45:04.392929Z","end":"2026-07-21T21:45:04.527418Z","steps":["trace[1771112138] 'agreement among raft nodes before linearized reading' (duration: 134.420424ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:04.527483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.861753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-21T21:45:04.527558Z","caller":"traceutil/trace.go:171","msg":"trace[215345203] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25814; }","duration":"282.990016ms","start":"2026-07-21T21:45:04.244545Z","end":"2026-07-21T21:45:04.527535Z","steps":["trace[215345203] 'agreement among raft nodes before linearized reading' (duration: 282.748851ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:08.680669Z","caller":"traceutil/trace.go:171","msg":"trace[1657802056] linearizableReadLoop","detail":"{readStateIndex:26705; appliedIndex:26704; }","duration":"130.905072ms","start":"2026-07-21T21:45:08.549741Z","end":"2026-07-21T21:45:08.680646Z","steps":["trace[1657802056] 'read index received' (duration: 130.774719ms)","trace[1657802056] 'applied index is now lower than readState.Index' (duration: 129.453µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:45:08.680867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.107486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-21T21:45:08.680918Z","caller":"traceutil/trace.go:171","msg":"trace[1260948442] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25852; }","duration":"131.210929ms","start":"2026-07-21T21:45:08.549693Z","end":"2026-07-21T21:45:08.680904Z","steps":["trace[1260948442] 'agreement among raft nodes before linearized reading' (duration: 131.043015ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:08.680945Z","caller":"traceutil/trace.go:171","msg":"trace[1865875003] transaction","detail":"{read_only:false; response_revision:25852; number_of_response:1; }","duration":"135.938744ms","start":"2026-07-21T21:45:08.544904Z","end":"2026-07-21T21:45:08.680843Z","steps":["trace[1865875003] 'process raft request' (duration: 135.604007ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:08.978093Z","caller":"traceutil/trace.go:171","msg":"trace[1928141107] transaction","detail":"{read_only:false; response_revision:25853; number_of_response:1; }","duration":"327.915339ms","start":"2026-07-21T21:45:08.650154Z","end":"2026-07-21T21:45:08.978069Z","steps":["trace[1928141107] 'process raft request' (duration: 327.618422ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:08.978223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:08.650137Z","time spent":"328.021482ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:45:09.114607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.570057ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-07-21T21:45:09.114892Z","caller":"traceutil/trace.go:171","msg":"trace[1641581566] transaction","detail":"{read_only:false; response_revision:25854; number_of_response:1; }","duration":"429.643328ms","start":"2026-07-21T21:45:08.685228Z","end":"2026-07-21T21:45:09.114871Z","steps":["trace[1641581566] 'process raft request' (duration: 429.485065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:09.115002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:08.685208Z","time spent":"429.72762ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:45:09.776825Z","caller":"traceutil/trace.go:171","msg":"trace[2140501598] transaction","detail":"{read_only:false; response_revision:25859; number_of_response:1; }","duration":"194.336593ms","start":"2026-07-21T21:45:09.582469Z","end":"2026-07-21T21:45:09.776806Z","steps":["trace[2140501598] 'process raft request' (duration: 175.275815ms)","trace[2140501598] 'compare' (duration: 18.786103ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:45:09.776759Z","caller":"traceutil/trace.go:171","msg":"trace[1895142778] linearizableReadLoop","detail":"{readStateIndex:26713; appliedIndex:26712; }","duration":"146.588252ms","start":"2026-07-21T21:45:09.630132Z","end":"2026-07-21T21:45:09.77672Z","steps":["trace[1895142778] 'read index received' (duration: 127.542045ms)","trace[1895142778] 'applied index is now lower than readState.Index' (duration: 19.045197ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:45:09.777184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.047001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:274716"} {"level":"info","ts":"2026-07-21T21:45:09.777238Z","caller":"traceutil/trace.go:171","msg":"trace[2036306071] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:25859; }","duration":"147.148194ms","start":"2026-07-21T21:45:09.630079Z","end":"2026-07-21T21:45:09.777227Z","steps":["trace[2036306071] 'agreement among raft nodes before linearized reading' (duration: 146.791676ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:10.379074Z","caller":"traceutil/trace.go:171","msg":"trace[2055941230] linearizableReadLoop","detail":"{readStateIndex:26717; appliedIndex:26716; }","duration":"165.024887ms","start":"2026-07-21T21:45:10.214027Z","end":"2026-07-21T21:45:10.379052Z","steps":["trace[2055941230] 'read index received' (duration: 148.114623ms)","trace[2055941230] 'applied index is now lower than readState.Index' (duration: 16.908984ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:45:10.379377Z","caller":"traceutil/trace.go:171","msg":"trace[796087597] transaction","detail":"{read_only:false; response_revision:25863; number_of_response:1; }","duration":"173.672482ms","start":"2026-07-21T21:45:10.20569Z","end":"2026-07-21T21:45:10.379362Z","steps":["trace[796087597] 'process raft request' (duration: 156.509033ms)","trace[796087597] 'compare' (duration: 16.045986ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:45:10.379642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.609048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-21T21:45:10.379685Z","caller":"traceutil/trace.go:171","msg":"trace[776922090] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25863; }","duration":"165.69192ms","start":"2026-07-21T21:45:10.213982Z","end":"2026-07-21T21:45:10.379674Z","steps":["trace[776922090] 'agreement among raft nodes before linearized reading' (duration: 165.563268ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:12.17171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.073291ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T21:45:12.171836Z","caller":"traceutil/trace.go:171","msg":"trace[2117643339] linearizableReadLoop","detail":"{readStateIndex:26737; appliedIndex:26736; }","duration":"119.828057ms","start":"2026-07-21T21:45:12.051984Z","end":"2026-07-21T21:45:12.171812Z","steps":["trace[2117643339] 'read index received' (duration: 43.471µs)","trace[2117643339] 'applied index is now lower than readState.Index' (duration: 119.783096ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:45:12.171929Z","caller":"traceutil/trace.go:171","msg":"trace[840674591] transaction","detail":"{read_only:false; response_revision:25883; number_of_response:1; }","duration":"270.331799ms","start":"2026-07-21T21:45:11.901542Z","end":"2026-07-21T21:45:12.171874Z","steps":["trace[840674591] 'process raft request' (duration: 125.990403ms)","trace[840674591] 'compare' (duration: 143.663652ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:45:12.172123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.133504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-21T21:45:12.172185Z","caller":"traceutil/trace.go:171","msg":"trace[1923682565] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:25883; }","duration":"120.231895ms","start":"2026-07-21T21:45:12.051939Z","end":"2026-07-21T21:45:12.172171Z","steps":["trace[1923682565] 'agreement among raft nodes before linearized reading' (duration: 119.995941ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:12.311999Z","caller":"traceutil/trace.go:171","msg":"trace[1404600820] transaction","detail":"{read_only:false; response_revision:25884; number_of_response:1; }","duration":"228.958107ms","start":"2026-07-21T21:45:12.083016Z","end":"2026-07-21T21:45:12.311974Z","steps":["trace[1404600820] 'process raft request' (duration: 205.0507ms)","trace[1404600820] 'compare' (duration: 23.813105ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:45:12.407915Z","caller":"traceutil/trace.go:171","msg":"trace[105829667] linearizableReadLoop","detail":"{readStateIndex:26740; appliedIndex:26737; }","duration":"235.98577ms","start":"2026-07-21T21:45:12.171903Z","end":"2026-07-21T21:45:12.407889Z","steps":["trace[105829667] 'read index received' (duration: 116.176633ms)","trace[105829667] 'applied index is now lower than readState.Index' (duration: 119.808087ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:45:12.408152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.948392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-21T21:45:12.408207Z","caller":"traceutil/trace.go:171","msg":"trace[1165567811] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25886; }","duration":"303.048714ms","start":"2026-07-21T21:45:12.105141Z","end":"2026-07-21T21:45:12.408189Z","steps":["trace[1165567811] 'agreement among raft nodes before linearized reading' (duration: 302.8504ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:12.408252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:12.105121Z","time spent":"303.119095ms","remote":"127.0.0.1:41548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-21T21:45:12.408639Z","caller":"traceutil/trace.go:171","msg":"trace[1068325740] transaction","detail":"{read_only:false; response_revision:25885; number_of_response:1; }","duration":"310.427065ms","start":"2026-07-21T21:45:12.098198Z","end":"2026-07-21T21:45:12.408625Z","steps":["trace[1068325740] 'process raft request' (duration: 309.520996ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:12.40875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:12.098181Z","time spent":"310.500196ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:45:12.40895Z","caller":"traceutil/trace.go:171","msg":"trace[913950077] transaction","detail":"{read_only:false; response_revision:25886; number_of_response:1; }","duration":"310.003185ms","start":"2026-07-21T21:45:12.098935Z","end":"2026-07-21T21:45:12.408938Z","steps":["trace[913950077] 'process raft request' (duration: 308.908803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:12.409037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:12.098924Z","time spent":"310.065246ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:45:12.780985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.061129ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T21:45:12.781201Z","caller":"traceutil/trace.go:171","msg":"trace[563539187] linearizableReadLoop","detail":"{readStateIndex:26743; appliedIndex:26741; }","duration":"273.232277ms","start":"2026-07-21T21:45:12.507948Z","end":"2026-07-21T21:45:12.78118Z","steps":["trace[563539187] 'read index received' (duration: 69.846741ms)","trace[563539187] 'applied index is now lower than readState.Index' (duration: 203.384376ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:45:12.781336Z","caller":"traceutil/trace.go:171","msg":"trace[1558107540] transaction","detail":"{read_only:false; response_revision:25889; number_of_response:1; }","duration":"287.974158ms","start":"2026-07-21T21:45:12.493347Z","end":"2026-07-21T21:45:12.781321Z","steps":["trace[1558107540] 'process raft request' (duration: 287.758404ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:12.781332Z","caller":"traceutil/trace.go:171","msg":"trace[1092852330] transaction","detail":"{read_only:false; response_revision:25888; number_of_response:1; }","duration":"290.190551ms","start":"2026-07-21T21:45:12.491111Z","end":"2026-07-21T21:45:12.781302Z","steps":["trace[1092852330] 'process raft request' (duration: 86.669253ms)","trace[1092852330] 'compare' (duration: 202.973488ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:45:12.781532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.406446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:45:12.781579Z","caller":"traceutil/trace.go:171","msg":"trace[1561959761] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25889; }","duration":"200.494508ms","start":"2026-07-21T21:45:12.581071Z","end":"2026-07-21T21:45:12.781565Z","steps":["trace[1561959761] 'agreement among raft nodes before linearized reading' (duration: 200.411926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:12.781754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.804868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:45:12.781789Z","caller":"traceutil/trace.go:171","msg":"trace[1645571203] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25889; }","duration":"273.8714ms","start":"2026-07-21T21:45:12.507909Z","end":"2026-07-21T21:45:12.781781Z","steps":["trace[1645571203] 'agreement among raft nodes before linearized reading' (duration: 273.817849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:15.198023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.318866ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-07-21T21:45:15.198121Z","caller":"traceutil/trace.go:171","msg":"trace[944223094] transaction","detail":"{read_only:false; response_revision:25977; number_of_response:1; }","duration":"244.022343ms","start":"2026-07-21T21:45:14.954082Z","end":"2026-07-21T21:45:15.198104Z","steps":["trace[944223094] 'process raft request' (duration: 129.525424ms)","trace[944223094] 'compare' (duration: 113.990869ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:45:15.259736Z","caller":"traceutil/trace.go:171","msg":"trace[721669558] transaction","detail":"{read_only:false; response_revision:25978; number_of_response:1; }","duration":"304.839461ms","start":"2026-07-21T21:45:14.954859Z","end":"2026-07-21T21:45:15.259698Z","steps":["trace[721669558] 'process raft request' (duration: 304.585206ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:15.259907Z","caller":"traceutil/trace.go:171","msg":"trace[1584468844] transaction","detail":"{read_only:false; response_revision:25980; number_of_response:1; }","duration":"264.150513ms","start":"2026-07-21T21:45:14.995729Z","end":"2026-07-21T21:45:15.259879Z","steps":["trace[1584468844] 'process raft request' (duration: 263.972179ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:15.259974Z","caller":"traceutil/trace.go:171","msg":"trace[861688879] transaction","detail":"{read_only:false; response_revision:25979; number_of_response:1; }","duration":"301.432161ms","start":"2026-07-21T21:45:14.958529Z","end":"2026-07-21T21:45:15.259961Z","steps":["trace[861688879] 'process raft request' (duration: 301.078084ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:15.260004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.767754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-21T21:45:15.260045Z","caller":"traceutil/trace.go:171","msg":"trace[2035465315] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25980; }","duration":"112.847505ms","start":"2026-07-21T21:45:15.147188Z","end":"2026-07-21T21:45:15.260035Z","steps":["trace[2035465315] 'agreement among raft nodes before linearized reading' (duration: 112.672871ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:15.260063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:14.958511Z","time spent":"301.501682ms","remote":"127.0.0.1:41592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5401,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:45:15.259801Z","caller":"traceutil/trace.go:171","msg":"trace[197945742] linearizableReadLoop","detail":"{readStateIndex:26835; appliedIndex:26832; }","duration":"112.552479ms","start":"2026-07-21T21:45:15.14722Z","end":"2026-07-21T21:45:15.259772Z","steps":["trace[197945742] 'read index received' (duration: 112.220833ms)","trace[197945742] 'applied index is now lower than readState.Index' (duration: 330.466µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:45:15.260482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:14.954844Z","time spent":"305.068275ms","remote":"127.0.0.1:41446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T21:45:15.399002Z","caller":"traceutil/trace.go:171","msg":"trace[356612738] transaction","detail":"{read_only:false; response_revision:25981; number_of_response:1; }","duration":"220.25722ms","start":"2026-07-21T21:45:15.178712Z","end":"2026-07-21T21:45:15.398969Z","steps":["trace[356612738] 'process raft request' (duration: 147.323457ms)","trace[356612738] 'compare' (duration: 72.693268ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:45:15.480386Z","caller":"traceutil/trace.go:171","msg":"trace[1487990448] transaction","detail":"{read_only:false; response_revision:25982; number_of_response:1; }","duration":"216.205937ms","start":"2026-07-21T21:45:15.26414Z","end":"2026-07-21T21:45:15.480345Z","steps":["trace[1487990448] 'process raft request' (duration: 215.84846ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:15.480567Z","caller":"traceutil/trace.go:171","msg":"trace[678590308] transaction","detail":"{read_only:false; response_revision:25983; number_of_response:1; }","duration":"214.532364ms","start":"2026-07-21T21:45:15.266003Z","end":"2026-07-21T21:45:15.480535Z","steps":["trace[678590308] 'process raft request' (duration: 214.177977ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:22.533209Z","caller":"traceutil/trace.go:171","msg":"trace[1294154998] transaction","detail":"{read_only:false; response_revision:26064; number_of_response:1; }","duration":"188.84332ms","start":"2026-07-21T21:45:22.344344Z","end":"2026-07-21T21:45:22.533187Z","steps":["trace[1294154998] 'process raft request' (duration: 188.710737ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:22.797333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.46821ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T21:45:22.797536Z","caller":"traceutil/trace.go:171","msg":"trace[204771377] transaction","detail":"{read_only:false; response_revision:26065; number_of_response:1; }","duration":"391.432862ms","start":"2026-07-21T21:45:22.406072Z","end":"2026-07-21T21:45:22.797505Z","steps":["trace[204771377] 'process raft request' (duration: 275.709418ms)","trace[204771377] 'compare' (duration: 114.407767ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:45:22.797592Z","caller":"traceutil/trace.go:171","msg":"trace[1731399701] transaction","detail":"{read_only:false; response_revision:26068; number_of_response:1; }","duration":"318.747583ms","start":"2026-07-21T21:45:22.478831Z","end":"2026-07-21T21:45:22.797579Z","steps":["trace[1731399701] 'process raft request' (duration: 318.693762ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:22.797677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:22.406043Z","time spent":"391.567405ms","remote":"127.0.0.1:38730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418457,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:45:22.797671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:22.478809Z","time spent":"318.822175ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:45:22.797684Z","caller":"traceutil/trace.go:171","msg":"trace[1851275382] transaction","detail":"{read_only:false; response_revision:26066; number_of_response:1; }","duration":"357.006172ms","start":"2026-07-21T21:45:22.440648Z","end":"2026-07-21T21:45:22.797655Z","steps":["trace[1851275382] 'process raft request' (duration: 356.778617ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:22.797739Z","caller":"traceutil/trace.go:171","msg":"trace[8012634] transaction","detail":"{read_only:false; response_revision:26067; number_of_response:1; }","duration":"350.436528ms","start":"2026-07-21T21:45:22.447287Z","end":"2026-07-21T21:45:22.797724Z","steps":["trace[8012634] 'process raft request' (duration: 350.202404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:22.797791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:22.447262Z","time spent":"350.502949ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:45:22.797797Z","caller":"traceutil/trace.go:171","msg":"trace[1133937952] transaction","detail":"{read_only:false; response_revision:26069; number_of_response:1; }","duration":"294.357487ms","start":"2026-07-21T21:45:22.503418Z","end":"2026-07-21T21:45:22.797776Z","steps":["trace[1133937952] 'process raft request' (duration: 294.132933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:22.797833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:22.440619Z","time spent":"357.133745ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:45:22.797779Z","caller":"traceutil/trace.go:171","msg":"trace[992050066] linearizableReadLoop","detail":"{readStateIndex:26925; appliedIndex:26920; }","duration":"140.168711ms","start":"2026-07-21T21:45:22.657554Z","end":"2026-07-21T21:45:22.797745Z","steps":["trace[992050066] 'read index received' (duration: 24.080929ms)","trace[992050066] 'applied index is now lower than readState.Index' (duration: 116.081692ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:45:22.798016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.446547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-21T21:45:22.798064Z","caller":"traceutil/trace.go:171","msg":"trace[383174722] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:26069; }","duration":"140.514388ms","start":"2026-07-21T21:45:22.657537Z","end":"2026-07-21T21:45:22.798051Z","steps":["trace[383174722] 'agreement among raft nodes before linearized reading' (duration: 140.303314ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:22.957304Z","caller":"traceutil/trace.go:171","msg":"trace[916883780] linearizableReadLoop","detail":"{readStateIndex:26926; appliedIndex:26925; }","duration":"121.959631ms","start":"2026-07-21T21:45:22.835293Z","end":"2026-07-21T21:45:22.957253Z","steps":["trace[916883780] 'read index received' (duration: 111.735383ms)","trace[916883780] 'applied index is now lower than readState.Index' (duration: 10.223088ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:45:22.957328Z","caller":"traceutil/trace.go:171","msg":"trace[2005426623] transaction","detail":"{read_only:false; response_revision:26070; number_of_response:1; }","duration":"123.477881ms","start":"2026-07-21T21:45:22.833815Z","end":"2026-07-21T21:45:22.957293Z","steps":["trace[2005426623] 'process raft request' (duration: 113.269463ms)","trace[2005426623] 'compare' (duration: 10.019594ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:45:22.957501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.188806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-21T21:45:22.957771Z","caller":"traceutil/trace.go:171","msg":"trace[941656761] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26070; }","duration":"122.499802ms","start":"2026-07-21T21:45:22.835259Z","end":"2026-07-21T21:45:22.957759Z","steps":["trace[941656761] 'agreement among raft nodes before linearized reading' (duration: 122.112974ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:27.854486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.694527ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T21:45:27.854627Z","caller":"traceutil/trace.go:171","msg":"trace[1341815241] transaction","detail":"{read_only:false; response_revision:26111; number_of_response:1; }","duration":"194.996796ms","start":"2026-07-21T21:45:27.659618Z","end":"2026-07-21T21:45:27.854615Z","steps":["trace[1341815241] 'process raft request' (duration: 194.956215ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:27.854854Z","caller":"traceutil/trace.go:171","msg":"trace[31645988] transaction","detail":"{read_only:false; response_revision:26110; number_of_response:1; }","duration":"420.096345ms","start":"2026-07-21T21:45:27.434746Z","end":"2026-07-21T21:45:27.854842Z","steps":["trace[31645988] 'process raft request' (duration: 114.987039ms)","trace[31645988] 'compare' (duration: 304.515384ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:45:27.854894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:27.434731Z","time spent":"420.139955ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:45:27.855019Z","caller":"traceutil/trace.go:171","msg":"trace[1723887057] linearizableReadLoop","detail":"{readStateIndex:26967; appliedIndex:26966; }","duration":"344.14661ms","start":"2026-07-21T21:45:27.510865Z","end":"2026-07-21T21:45:27.855012Z","steps":["trace[1723887057] 'read index received' (duration: 38.906012ms)","trace[1723887057] 'applied index is now lower than readState.Index' (duration: 305.239848ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:45:27.855147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.284023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:45:27.855166Z","caller":"traceutil/trace.go:171","msg":"trace[1982001267] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26111; }","duration":"344.355234ms","start":"2026-07-21T21:45:27.510805Z","end":"2026-07-21T21:45:27.85516Z","steps":["trace[1982001267] 'agreement among raft nodes before linearized reading' (duration: 344.320133ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:27.855184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:27.510776Z","time spent":"344.403955ms","remote":"127.0.0.1:41356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T21:45:28.136863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.187993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T21:45:28.136956Z","caller":"traceutil/trace.go:171","msg":"trace[1930941269] linearizableReadLoop","detail":"{readStateIndex:26969; appliedIndex:26968; }","duration":"281.910134ms","start":"2026-07-21T21:45:27.855032Z","end":"2026-07-21T21:45:28.136943Z","steps":["trace[1930941269] 'read index received' (duration: 139.554288ms)","trace[1930941269] 'applied index is now lower than readState.Index' (duration: 142.354926ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:45:28.13723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.068917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-21T21:45:28.137268Z","caller":"traceutil/trace.go:171","msg":"trace[100546073] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26112; }","duration":"605.141338ms","start":"2026-07-21T21:45:27.532115Z","end":"2026-07-21T21:45:28.137256Z","steps":["trace[100546073] 'agreement among raft nodes before linearized reading' (duration: 605.020816ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:28.137257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.951431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-21T21:45:28.137292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.965218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-21T21:45:28.137357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.632158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:45:28.137356Z","caller":"traceutil/trace.go:171","msg":"trace[1207662593] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:26112; }","duration":"330.112535ms","start":"2026-07-21T21:45:27.807223Z","end":"2026-07-21T21:45:28.137335Z","steps":["trace[1207662593] 'agreement among raft nodes before linearized reading' (duration: 329.911511ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:28.137333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.377932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-07-21T21:45:28.137407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:27.807202Z","time spent":"330.194096ms","remote":"127.0.0.1:56694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":30,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"warn","ts":"2026-07-21T21:45:28.137483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.80524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-21T21:45:28.137553Z","caller":"traceutil/trace.go:171","msg":"trace[614311578] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26112; }","duration":"190.898933ms","start":"2026-07-21T21:45:27.946623Z","end":"2026-07-21T21:45:28.137522Z","steps":["trace[614311578] 'agreement among raft nodes before linearized reading' (duration: 190.811471ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:28.13765Z","caller":"traceutil/trace.go:171","msg":"trace[898851636] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:26112; }","duration":"554.10309ms","start":"2026-07-21T21:45:27.583258Z","end":"2026-07-21T21:45:28.137362Z","steps":["trace[898851636] 'agreement among raft nodes before linearized reading' (duration: 553.917717ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:28.137759Z","caller":"traceutil/trace.go:171","msg":"trace[1552161164] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26112; }","duration":"280.710459ms","start":"2026-07-21T21:45:27.856686Z","end":"2026-07-21T21:45:28.137397Z","steps":["trace[1552161164] 'agreement among raft nodes before linearized reading' (duration: 280.645398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:28.137805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:27.583243Z","time spent":"554.54479ms","remote":"127.0.0.1:49900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-07-21T21:45:28.137845Z","caller":"traceutil/trace.go:171","msg":"trace[237667837] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26112; }","duration":"602.596856ms","start":"2026-07-21T21:45:27.534818Z","end":"2026-07-21T21:45:28.137414Z","steps":["trace[237667837] 'agreement among raft nodes before linearized reading' (duration: 602.26835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:28.137888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:27.534798Z","time spent":"603.078316ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-21T21:45:28.137667Z","caller":"traceutil/trace.go:171","msg":"trace[290885365] transaction","detail":"{read_only:false; response_revision:26112; number_of_response:1; }","duration":"298.639964ms","start":"2026-07-21T21:45:27.839012Z","end":"2026-07-21T21:45:28.137652Z","steps":["trace[290885365] 'process raft request' (duration: 155.591735ms)","trace[290885365] 'compare' (duration: 141.766843ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:45:28.137303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:27.532096Z","time spent":"605.199519ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-21T21:45:28.348911Z","caller":"traceutil/trace.go:171","msg":"trace[421560537] transaction","detail":"{read_only:false; response_revision:26113; number_of_response:1; }","duration":"206.829726ms","start":"2026-07-21T21:45:28.142058Z","end":"2026-07-21T21:45:28.348887Z","steps":["trace[421560537] 'process raft request' (duration: 124.875049ms)","trace[421560537] 'compare' (duration: 81.865026ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:45:28.369531Z","caller":"traceutil/trace.go:171","msg":"trace[1356919765] linearizableReadLoop","detail":"{readStateIndex:26971; appliedIndex:26969; }","duration":"226.780432ms","start":"2026-07-21T21:45:28.142729Z","end":"2026-07-21T21:45:28.369509Z","steps":["trace[1356919765] 'read index received' (duration: 124.151414ms)","trace[1356919765] 'applied index is now lower than readState.Index' (duration: 102.628248ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:45:28.369567Z","caller":"traceutil/trace.go:171","msg":"trace[295475770] transaction","detail":"{read_only:false; response_revision:26114; number_of_response:1; }","duration":"227.248342ms","start":"2026-07-21T21:45:28.142292Z","end":"2026-07-21T21:45:28.36954Z","steps":["trace[295475770] 'process raft request' (duration: 227.100969ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:28.369691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.940886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18227"} {"level":"info","ts":"2026-07-21T21:45:28.369722Z","caller":"traceutil/trace.go:171","msg":"trace[1480003505] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26114; }","duration":"226.997087ms","start":"2026-07-21T21:45:28.142718Z","end":"2026-07-21T21:45:28.369715Z","steps":["trace[1480003505] 'agreement among raft nodes before linearized reading' (duration: 226.905665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:28.369839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.978465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-07-21T21:45:28.369916Z","caller":"traceutil/trace.go:171","msg":"trace[1951508843] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:26114; }","duration":"220.073357ms","start":"2026-07-21T21:45:28.149824Z","end":"2026-07-21T21:45:28.369897Z","steps":["trace[1951508843] 'agreement among raft nodes before linearized reading' (duration: 219.846443ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:32.867948Z","caller":"traceutil/trace.go:171","msg":"trace[1222221788] transaction","detail":"{read_only:false; response_revision:26151; number_of_response:1; }","duration":"162.013995ms","start":"2026-07-21T21:45:32.705897Z","end":"2026-07-21T21:45:32.867911Z","steps":["trace[1222221788] 'process raft request' (duration: 161.82299ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:33.05661Z","caller":"traceutil/trace.go:171","msg":"trace[1838565145] transaction","detail":"{read_only:false; response_revision:26155; number_of_response:1; }","duration":"111.492638ms","start":"2026-07-21T21:45:32.94507Z","end":"2026-07-21T21:45:33.056563Z","steps":["trace[1838565145] 'process raft request' (duration: 111.165771ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:33.056709Z","caller":"traceutil/trace.go:171","msg":"trace[221439308] transaction","detail":"{read_only:false; response_revision:26153; number_of_response:1; }","duration":"223.760471ms","start":"2026-07-21T21:45:32.832909Z","end":"2026-07-21T21:45:33.05667Z","steps":["trace[221439308] 'process raft request' (duration: 223.22778ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:33.056797Z","caller":"traceutil/trace.go:171","msg":"trace[1670219658] transaction","detail":"{read_only:false; response_revision:26154; number_of_response:1; }","duration":"223.544457ms","start":"2026-07-21T21:45:32.833211Z","end":"2026-07-21T21:45:33.056756Z","steps":["trace[1670219658] 'process raft request' (duration: 222.980376ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:33.056799Z","caller":"traceutil/trace.go:171","msg":"trace[1732036941] transaction","detail":"{read_only:false; response_revision:26152; number_of_response:1; }","duration":"225.783833ms","start":"2026-07-21T21:45:32.830974Z","end":"2026-07-21T21:45:33.056758Z","steps":["trace[1732036941] 'process raft request' (duration: 190.7794ms)","trace[1732036941] 'compare' (duration: 34.111455ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:45:35.417746Z","caller":"traceutil/trace.go:171","msg":"trace[532540670] transaction","detail":"{read_only:false; response_revision:26176; number_of_response:1; }","duration":"107.61531ms","start":"2026-07-21T21:45:35.31009Z","end":"2026-07-21T21:45:35.417706Z","steps":["trace[532540670] 'process raft request' (duration: 107.356854ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:35.6237Z","caller":"traceutil/trace.go:171","msg":"trace[1113098254] linearizableReadLoop","detail":"{readStateIndex:27037; appliedIndex:27036; }","duration":"133.826642ms","start":"2026-07-21T21:45:35.489846Z","end":"2026-07-21T21:45:35.623672Z","steps":["trace[1113098254] 'read index received' (duration: 133.662299ms)","trace[1113098254] 'applied index is now lower than readState.Index' (duration: 163.673µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:45:35.623782Z","caller":"traceutil/trace.go:171","msg":"trace[138670227] transaction","detail":"{read_only:false; response_revision:26178; number_of_response:1; }","duration":"195.123458ms","start":"2026-07-21T21:45:35.428629Z","end":"2026-07-21T21:45:35.623753Z","steps":["trace[138670227] 'process raft request' (duration: 194.925124ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:35.62384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.978535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:45:35.623873Z","caller":"traceutil/trace.go:171","msg":"trace[1807367823] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26178; }","duration":"134.072687ms","start":"2026-07-21T21:45:35.489791Z","end":"2026-07-21T21:45:35.623864Z","steps":["trace[1807367823] 'agreement among raft nodes before linearized reading' (duration: 133.990436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:35.624043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.558499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:45:35.624471Z","caller":"traceutil/trace.go:171","msg":"trace[1777715813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26178; }","duration":"114.01342ms","start":"2026-07-21T21:45:35.510424Z","end":"2026-07-21T21:45:35.624438Z","steps":["trace[1777715813] 'agreement among raft nodes before linearized reading' (duration: 113.491458ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:44.738222Z","caller":"traceutil/trace.go:171","msg":"trace[1602703280] transaction","detail":"{read_only:false; response_revision:26264; number_of_response:1; }","duration":"189.724669ms","start":"2026-07-21T21:45:44.548472Z","end":"2026-07-21T21:45:44.738197Z","steps":["trace[1602703280] 'process raft request' (duration: 189.587077ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:44.754197Z","caller":"traceutil/trace.go:171","msg":"trace[738210002] linearizableReadLoop","detail":"{readStateIndex:27127; appliedIndex:27125; }","duration":"126.025963ms","start":"2026-07-21T21:45:44.628155Z","end":"2026-07-21T21:45:44.754181Z","steps":["trace[738210002] 'read index received' (duration: 109.960536ms)","trace[738210002] 'applied index is now lower than readState.Index' (duration: 16.064577ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:45:44.75424Z","caller":"traceutil/trace.go:171","msg":"trace[1542085127] transaction","detail":"{read_only:false; response_revision:26265; number_of_response:1; }","duration":"155.83677ms","start":"2026-07-21T21:45:44.598368Z","end":"2026-07-21T21:45:44.754204Z","steps":["trace[1542085127] 'process raft request' (duration: 155.731088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:44.754359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.191097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"info","ts":"2026-07-21T21:45:44.7544Z","caller":"traceutil/trace.go:171","msg":"trace[101516730] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:26265; }","duration":"126.272638ms","start":"2026-07-21T21:45:44.628115Z","end":"2026-07-21T21:45:44.754388Z","steps":["trace[101516730] 'agreement among raft nodes before linearized reading' (duration: 126.129355ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:44.923712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.757981ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:45:44.923874Z","caller":"traceutil/trace.go:171","msg":"trace[356300133] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26265; }","duration":"106.937454ms","start":"2026-07-21T21:45:44.816919Z","end":"2026-07-21T21:45:44.923856Z","steps":["trace[356300133] 'range keys from in-memory index tree' (duration: 106.74124ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:46.3876Z","caller":"traceutil/trace.go:171","msg":"trace[270263646] transaction","detail":"{read_only:false; response_revision:26279; number_of_response:1; }","duration":"120.259506ms","start":"2026-07-21T21:45:46.267321Z","end":"2026-07-21T21:45:46.38758Z","steps":["trace[270263646] 'process raft request' (duration: 120.139014ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:50.50968Z","caller":"traceutil/trace.go:171","msg":"trace[1596533879] transaction","detail":"{read_only:false; response_revision:26310; number_of_response:1; }","duration":"105.541898ms","start":"2026-07-21T21:45:50.404107Z","end":"2026-07-21T21:45:50.509649Z","steps":["trace[1596533879] 'process raft request' (duration: 105.349144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:50.634033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.681792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:45:50.634108Z","caller":"traceutil/trace.go:171","msg":"trace[934900705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26310; }","duration":"115.814035ms","start":"2026-07-21T21:45:50.518276Z","end":"2026-07-21T21:45:50.63409Z","steps":["trace[934900705] 'range keys from in-memory index tree' (duration: 115.521399ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:53.435838Z","caller":"traceutil/trace.go:171","msg":"trace[1756442737] transaction","detail":"{read_only:false; response_revision:26332; number_of_response:1; }","duration":"320.402598ms","start":"2026-07-21T21:45:53.115416Z","end":"2026-07-21T21:45:53.435819Z","steps":["trace[1756442737] 'process raft request' (duration: 320.275605ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:53.435959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:53.115393Z","time spent":"320.502689ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:45:53.49128Z","caller":"traceutil/trace.go:171","msg":"trace[100444622] linearizableReadLoop","detail":"{readStateIndex:27197; appliedIndex:27194; }","duration":"338.149298ms","start":"2026-07-21T21:45:53.153111Z","end":"2026-07-21T21:45:53.49126Z","steps":["trace[100444622] 'read index received' (duration: 282.556398ms)","trace[100444622] 'applied index is now lower than readState.Index' (duration: 55.592179ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:45:53.491334Z","caller":"traceutil/trace.go:171","msg":"trace[936035161] transaction","detail":"{read_only:false; response_revision:26333; number_of_response:1; }","duration":"363.86359ms","start":"2026-07-21T21:45:53.127453Z","end":"2026-07-21T21:45:53.491316Z","steps":["trace[936035161] 'process raft request' (duration: 363.668386ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:53.49136Z","caller":"traceutil/trace.go:171","msg":"trace[517815259] transaction","detail":"{read_only:false; response_revision:26334; number_of_response:1; }","duration":"362.454901ms","start":"2026-07-21T21:45:53.128899Z","end":"2026-07-21T21:45:53.491354Z","steps":["trace[517815259] 'process raft request' (duration: 362.332219ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:53.491432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:53.128882Z","time spent":"362.490863ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:45:53.491751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.997732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-21T21:45:53.491776Z","caller":"traceutil/trace.go:171","msg":"trace[354456789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26334; }","duration":"330.046893ms","start":"2026-07-21T21:45:53.161724Z","end":"2026-07-21T21:45:53.491771Z","steps":["trace[354456789] 'agreement among raft nodes before linearized reading' (duration: 329.960251ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:53.491792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:53.161714Z","time spent":"330.074243ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T21:45:53.491782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.270541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:137"} {"level":"warn","ts":"2026-07-21T21:45:53.491819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.688529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-21T21:45:53.49186Z","caller":"traceutil/trace.go:171","msg":"trace[606969686] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:26334; }","duration":"250.410904ms","start":"2026-07-21T21:45:53.241431Z","end":"2026-07-21T21:45:53.491842Z","steps":["trace[606969686] 'agreement among raft nodes before linearized reading' (duration: 250.147679ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:53.491903Z","caller":"traceutil/trace.go:171","msg":"trace[126254947] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26334; }","duration":"338.826892ms","start":"2026-07-21T21:45:53.153057Z","end":"2026-07-21T21:45:53.491884Z","steps":["trace[126254947] 'agreement among raft nodes before linearized reading' (duration: 338.576227ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:53.491957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:53.153033Z","time spent":"338.915013ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-21T21:45:53.492119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.246641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-21T21:45:53.492152Z","caller":"traceutil/trace.go:171","msg":"trace[151015333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26334; }","duration":"338.305251ms","start":"2026-07-21T21:45:53.153837Z","end":"2026-07-21T21:45:53.492143Z","steps":["trace[151015333] 'agreement among raft nodes before linearized reading' (duration: 337.716589ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:53.492173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:53.153825Z","time spent":"338.342832ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-21T21:45:53.492323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:53.127427Z","time spent":"363.944702ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:45:53.949241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.594637ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T21:45:53.949373Z","caller":"traceutil/trace.go:171","msg":"trace[1729878206] transaction","detail":"{read_only:false; response_revision:26335; number_of_response:1; }","duration":"451.571475ms","start":"2026-07-21T21:45:53.497787Z","end":"2026-07-21T21:45:53.949359Z","steps":["trace[1729878206] 'process raft request' (duration: 318.788164ms)","trace[1729878206] 'compare' (duration: 132.479745ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:45:53.949424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:53.497745Z","time spent":"451.659977ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:45:53.949687Z","caller":"traceutil/trace.go:171","msg":"trace[1929898831] transaction","detail":"{read_only:false; response_revision:26336; number_of_response:1; }","duration":"449.633785ms","start":"2026-07-21T21:45:53.500033Z","end":"2026-07-21T21:45:53.949667Z","steps":["trace[1929898831] 'process raft request' (duration: 449.281768ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:53.949857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:53.50002Z","time spent":"449.733707ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:45:53.949878Z","caller":"traceutil/trace.go:171","msg":"trace[1960039846] transaction","detail":"{read_only:false; response_revision:26337; number_of_response:1; }","duration":"449.504472ms","start":"2026-07-21T21:45:53.500365Z","end":"2026-07-21T21:45:53.94987Z","steps":["trace[1960039846] 'process raft request' (duration: 449.236047ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:53.94992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:53.500359Z","time spent":"449.538944ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:45:53.950166Z","caller":"traceutil/trace.go:171","msg":"trace[957120597] transaction","detail":"{read_only:false; response_revision:26338; number_of_response:1; }","duration":"356.778577ms","start":"2026-07-21T21:45:53.59338Z","end":"2026-07-21T21:45:53.950159Z","steps":["trace[957120597] 'process raft request' (duration: 356.45462ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:53.950228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:53.593368Z","time spent":"356.832938ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:45:53.95042Z","caller":"traceutil/trace.go:171","msg":"trace[534787622] linearizableReadLoop","detail":"{readStateIndex:27202; appliedIndex:27197; }","duration":"339.033776ms","start":"2026-07-21T21:45:53.611379Z","end":"2026-07-21T21:45:53.950413Z","steps":["trace[534787622] 'read index received' (duration: 31.015471ms)","trace[534787622] 'applied index is now lower than readState.Index' (duration: 308.017285ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:45:53.950489Z","caller":"traceutil/trace.go:171","msg":"trace[615123163] transaction","detail":"{read_only:false; response_revision:26339; number_of_response:1; }","duration":"326.035681ms","start":"2026-07-21T21:45:53.624444Z","end":"2026-07-21T21:45:53.95048Z","steps":["trace[615123163] 'process raft request' (duration: 325.676504ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:53.950529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:53.624423Z","time spent":"326.083952ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:45:53.950595Z","caller":"traceutil/trace.go:171","msg":"trace[1084268313] transaction","detail":"{read_only:false; response_revision:26340; number_of_response:1; }","duration":"293.56357ms","start":"2026-07-21T21:45:53.65697Z","end":"2026-07-21T21:45:53.950534Z","steps":["trace[1084268313] 'process raft request' (duration: 293.409157ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:53.950659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.276981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:45:53.950683Z","caller":"traceutil/trace.go:171","msg":"trace[489603485] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:26340; }","duration":"339.303551ms","start":"2026-07-21T21:45:53.611372Z","end":"2026-07-21T21:45:53.950676Z","steps":["trace[489603485] 'agreement among raft nodes before linearized reading' (duration: 339.24251ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:53.950711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:53.611361Z","time spent":"339.342963ms","remote":"127.0.0.1:54846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true "} {"level":"warn","ts":"2026-07-21T21:45:53.950821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.238674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:45:53.950844Z","caller":"traceutil/trace.go:171","msg":"trace[490461543] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/roles/; range_end:/registry/openstack.k-orc.cloud/roles0; response_count:0; response_revision:26340; }","duration":"262.291894ms","start":"2026-07-21T21:45:53.688546Z","end":"2026-07-21T21:45:53.950838Z","steps":["trace[490461543] 'agreement among raft nodes before linearized reading' (duration: 262.252964ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:53.951457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.212911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-21T21:45:53.951486Z","caller":"traceutil/trace.go:171","msg":"trace[443609935] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26340; }","duration":"109.303853ms","start":"2026-07-21T21:45:53.842176Z","end":"2026-07-21T21:45:53.95148Z","steps":["trace[443609935] 'agreement among raft nodes before linearized reading' (duration: 108.780062ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:54.352364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.737097ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T21:45:54.352484Z","caller":"traceutil/trace.go:171","msg":"trace[27010584] transaction","detail":"{read_only:false; response_revision:26341; number_of_response:1; }","duration":"327.839318ms","start":"2026-07-21T21:45:54.024622Z","end":"2026-07-21T21:45:54.352461Z","steps":["trace[27010584] 'process raft request' (duration: 56.737724ms)","trace[27010584] 'compare' (duration: 270.271667ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:45:54.352474Z","caller":"traceutil/trace.go:171","msg":"trace[78630186] linearizableReadLoop","detail":"{readStateIndex:27205; appliedIndex:27204; }","duration":"324.239525ms","start":"2026-07-21T21:45:54.028218Z","end":"2026-07-21T21:45:54.352458Z","steps":["trace[78630186] 'read index received' (duration: 53.071439ms)","trace[78630186] 'applied index is now lower than readState.Index' (duration: 271.165896ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:45:54.352593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:54.024607Z","time spent":"327.940421ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:45:54.352702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.46878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-07-21T21:45:54.352789Z","caller":"traceutil/trace.go:171","msg":"trace[613185928] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:26341; }","duration":"324.592102ms","start":"2026-07-21T21:45:54.028174Z","end":"2026-07-21T21:45:54.352766Z","steps":["trace[613185928] 'agreement among raft nodes before linearized reading' (duration: 324.377958ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:54.352853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:45:54.028159Z","time spent":"324.682575ms","remote":"127.0.0.1:56912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5676,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-21T21:45:54.35428Z","caller":"traceutil/trace.go:171","msg":"trace[1178320540] transaction","detail":"{read_only:false; response_revision:26342; number_of_response:1; }","duration":"222.331592ms","start":"2026-07-21T21:45:54.131934Z","end":"2026-07-21T21:45:54.354266Z","steps":["trace[1178320540] 'process raft request' (duration: 222.22483ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:45:54.37842Z","caller":"traceutil/trace.go:171","msg":"trace[1395915867] transaction","detail":"{read_only:false; response_revision:26343; number_of_response:1; }","duration":"143.260754ms","start":"2026-07-21T21:45:54.23513Z","end":"2026-07-21T21:45:54.378391Z","steps":["trace[1395915867] 'process raft request' (duration: 142.848926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:45:54.378435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.110205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-21T21:45:54.37852Z","caller":"traceutil/trace.go:171","msg":"trace[1646977101] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:26343; }","duration":"155.225977ms","start":"2026-07-21T21:45:54.223273Z","end":"2026-07-21T21:45:54.378499Z","steps":["trace[1646977101] 'agreement among raft nodes before linearized reading' (duration: 155.058424ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:46:09.600822Z","caller":"traceutil/trace.go:171","msg":"trace[1655097977] transaction","detail":"{read_only:false; response_revision:26467; number_of_response:1; }","duration":"117.289406ms","start":"2026-07-21T21:46:09.483517Z","end":"2026-07-21T21:46:09.600807Z","steps":["trace[1655097977] 'process raft request' (duration: 116.919168ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:46:09.811319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.89275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:46:09.811385Z","caller":"traceutil/trace.go:171","msg":"trace[105162448] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26469; }","duration":"123.003862ms","start":"2026-07-21T21:46:09.688365Z","end":"2026-07-21T21:46:09.811369Z","steps":["trace[105162448] 'range keys from in-memory index tree' (duration: 122.812389ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:46:09.811475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.617722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-api-7d5475fd68-jf4pv.18c46c2e12b4feca\" ","response":"range_response_count:1 size:855"} {"level":"info","ts":"2026-07-21T21:46:09.81155Z","caller":"traceutil/trace.go:171","msg":"trace[614676026] range","detail":"{range_begin:/registry/events/openstack/magnum-api-7d5475fd68-jf4pv.18c46c2e12b4feca; range_end:; response_count:1; response_revision:26469; }","duration":"114.724894ms","start":"2026-07-21T21:46:09.696807Z","end":"2026-07-21T21:46:09.811532Z","steps":["trace[614676026] 'range keys from in-memory index tree' (duration: 114.392767ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:46:09.811642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.748304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-api-7d5475fd68-jf4pv\" ","response":"range_response_count:1 size:8862"} {"level":"info","ts":"2026-07-21T21:46:09.811701Z","caller":"traceutil/trace.go:171","msg":"trace[1052737174] range","detail":"{range_begin:/registry/pods/openstack/magnum-api-7d5475fd68-jf4pv; range_end:; response_count:1; response_revision:26469; }","duration":"115.865817ms","start":"2026-07-21T21:46:09.69582Z","end":"2026-07-21T21:46:09.811686Z","steps":["trace[1052737174] 'range keys from in-memory index tree' (duration: 115.506409ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:46:15.057243Z","caller":"traceutil/trace.go:171","msg":"trace[1126285136] transaction","detail":"{read_only:false; response_revision:26512; number_of_response:1; }","duration":"248.682848ms","start":"2026-07-21T21:46:14.808524Z","end":"2026-07-21T21:46:15.057207Z","steps":["trace[1126285136] 'process raft request' (duration: 248.28298ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:46:15.057258Z","caller":"traceutil/trace.go:171","msg":"trace[399528100] linearizableReadLoop","detail":"{readStateIndex:27380; appliedIndex:27379; }","duration":"217.636117ms","start":"2026-07-21T21:46:14.839536Z","end":"2026-07-21T21:46:15.057172Z","steps":["trace[399528100] 'read index received' (duration: 217.352902ms)","trace[399528100] 'applied index is now lower than readState.Index' (duration: 282.115µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:46:15.057448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.902272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-21T21:46:15.057527Z","caller":"traceutil/trace.go:171","msg":"trace[2044921621] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26512; }","duration":"218.012744ms","start":"2026-07-21T21:46:14.839494Z","end":"2026-07-21T21:46:15.057507Z","steps":["trace[2044921621] 'agreement among raft nodes before linearized reading' (duration: 217.80865ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:46:15.20475Z","caller":"traceutil/trace.go:171","msg":"trace[637405754] linearizableReadLoop","detail":"{readStateIndex:27381; appliedIndex:27380; }","duration":"147.410648ms","start":"2026-07-21T21:46:15.057318Z","end":"2026-07-21T21:46:15.204728Z","steps":["trace[637405754] 'read index received' (duration: 51.65356ms)","trace[637405754] 'applied index is now lower than readState.Index' (duration: 95.756408ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:46:15.204783Z","caller":"traceutil/trace.go:171","msg":"trace[443599610] transaction","detail":"{read_only:false; response_revision:26513; number_of_response:1; }","duration":"339.565956ms","start":"2026-07-21T21:46:14.865189Z","end":"2026-07-21T21:46:15.204755Z","steps":["trace[443599610] 'process raft request' (duration: 243.86035ms)","trace[443599610] 'compare' (duration: 95.575174ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:46:15.204881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:46:14.865173Z","time spent":"339.646408ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:46:15.204927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.709257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-21T21:46:15.204953Z","caller":"traceutil/trace.go:171","msg":"trace[1388754509] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26513; }","duration":"330.777019ms","start":"2026-07-21T21:46:14.874166Z","end":"2026-07-21T21:46:15.204943Z","steps":["trace[1388754509] 'agreement among raft nodes before linearized reading' (duration: 330.675276ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:46:15.204938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.636282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-21T21:46:15.205015Z","caller":"traceutil/trace.go:171","msg":"trace[616126677] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:26513; }","duration":"296.761036ms","start":"2026-07-21T21:46:14.908235Z","end":"2026-07-21T21:46:15.204996Z","steps":["trace[616126677] 'agreement among raft nodes before linearized reading' (duration: 296.545891ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:46:15.204974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:46:14.874146Z","time spent":"330.82213ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-21T21:46:15.205076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.888324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-21T21:46:15.205084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.223053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-21T21:46:15.205116Z","caller":"traceutil/trace.go:171","msg":"trace[1354555983] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:26513; }","duration":"108.965726ms","start":"2026-07-21T21:46:15.096141Z","end":"2026-07-21T21:46:15.205107Z","steps":["trace[1354555983] 'agreement among raft nodes before linearized reading' (duration: 108.882875ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:46:15.205208Z","caller":"traceutil/trace.go:171","msg":"trace[1768279601] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26513; }","duration":"144.325386ms","start":"2026-07-21T21:46:15.06081Z","end":"2026-07-21T21:46:15.205135Z","steps":["trace[1768279601] 'agreement among raft nodes before linearized reading' (duration: 144.128502ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:46:15.741405Z","caller":"traceutil/trace.go:171","msg":"trace[1207538454] transaction","detail":"{read_only:false; response_revision:26515; number_of_response:1; }","duration":"121.220626ms","start":"2026-07-21T21:46:15.620148Z","end":"2026-07-21T21:46:15.741369Z","steps":["trace[1207538454] 'process raft request' (duration: 120.94593ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:46:16.078486Z","caller":"traceutil/trace.go:171","msg":"trace[1742970549] linearizableReadLoop","detail":"{readStateIndex:27385; appliedIndex:27383; }","duration":"277.074176ms","start":"2026-07-21T21:46:15.80139Z","end":"2026-07-21T21:46:16.078464Z","steps":["trace[1742970549] 'read index received' (duration: 276.897892ms)","trace[1742970549] 'applied index is now lower than readState.Index' (duration: 175.234µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:46:16.078561Z","caller":"traceutil/trace.go:171","msg":"trace[825334135] transaction","detail":"{read_only:false; response_revision:26516; number_of_response:1; }","duration":"434.692822ms","start":"2026-07-21T21:46:15.643821Z","end":"2026-07-21T21:46:16.078513Z","steps":["trace[825334135] 'process raft request' (duration: 434.442027ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:46:16.078542Z","caller":"traceutil/trace.go:171","msg":"trace[1275074985] transaction","detail":"{read_only:false; response_revision:26517; number_of_response:1; }","duration":"432.801843ms","start":"2026-07-21T21:46:15.645718Z","end":"2026-07-21T21:46:16.07852Z","steps":["trace[1275074985] 'process raft request' (duration: 432.698421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:46:16.078718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.30811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-21T21:46:16.080303Z","caller":"traceutil/trace.go:171","msg":"trace[737347523] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:26517; }","duration":"279.001675ms","start":"2026-07-21T21:46:15.801286Z","end":"2026-07-21T21:46:16.080288Z","steps":["trace[737347523] 'agreement among raft nodes before linearized reading' (duration: 277.31444ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:46:16.07887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:46:15.643798Z","time spent":"434.909176ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:46:16.080374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:46:15.645704Z","time spent":"434.518228ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:46:16.316328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.817967ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-21T21:46:16.316598Z","caller":"traceutil/trace.go:171","msg":"trace[601603806] linearizableReadLoop","detail":"{readStateIndex:27387; appliedIndex:27385; }","duration":"238.022712ms","start":"2026-07-21T21:46:16.07855Z","end":"2026-07-21T21:46:16.316573Z","steps":["trace[601603806] 'read index received' (duration: 89.849848ms)","trace[601603806] 'applied index is now lower than readState.Index' (duration: 148.171394ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:46:16.316635Z","caller":"traceutil/trace.go:171","msg":"trace[854432461] transaction","detail":"{read_only:false; response_revision:26519; number_of_response:1; }","duration":"248.019254ms","start":"2026-07-21T21:46:16.068576Z","end":"2026-07-21T21:46:16.316595Z","steps":["trace[854432461] 'process raft request' (duration: 247.915402ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:46:16.316777Z","caller":"traceutil/trace.go:171","msg":"trace[1569016285] transaction","detail":"{read_only:false; response_revision:26518; number_of_response:1; }","duration":"301.172366ms","start":"2026-07-21T21:46:16.015589Z","end":"2026-07-21T21:46:16.316762Z","steps":["trace[1569016285] 'process raft request' (duration: 152.788458ms)","trace[1569016285] 'compare' (duration: 147.619162ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:46:16.316869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:46:16.015565Z","time spent":"301.264157ms","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:46:16.316855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.94084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-21T21:46:16.316937Z","caller":"traceutil/trace.go:171","msg":"trace[1353614482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26519; }","duration":"269.075153ms","start":"2026-07-21T21:46:16.047844Z","end":"2026-07-21T21:46:16.316919Z","steps":["trace[1353614482] 'agreement among raft nodes before linearized reading' (duration: 268.796207ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:46:16.316852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.667548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-21T21:46:16.317045Z","caller":"traceutil/trace.go:171","msg":"trace[948888310] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26519; }","duration":"250.913123ms","start":"2026-07-21T21:46:16.066115Z","end":"2026-07-21T21:46:16.317028Z","steps":["trace[948888310] 'agreement among raft nodes before linearized reading' (duration: 250.508475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:46:16.3172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.310649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-21T21:46:16.31731Z","caller":"traceutil/trace.go:171","msg":"trace[1019088492] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26519; }","duration":"269.457761ms","start":"2026-07-21T21:46:16.047832Z","end":"2026-07-21T21:46:16.31729Z","steps":["trace[1019088492] 'agreement among raft nodes before linearized reading' (duration: 268.814487ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:46:16.460957Z","caller":"traceutil/trace.go:171","msg":"trace[1324868355] transaction","detail":"{read_only:false; response_revision:26520; number_of_response:1; }","duration":"137.837263ms","start":"2026-07-21T21:46:16.323084Z","end":"2026-07-21T21:46:16.460921Z","steps":["trace[1324868355] 'process raft request' (duration: 135.981245ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:46:16.603825Z","caller":"traceutil/trace.go:171","msg":"trace[1490529262] transaction","detail":"{read_only:false; response_revision:26522; number_of_response:1; }","duration":"280.565716ms","start":"2026-07-21T21:46:16.323234Z","end":"2026-07-21T21:46:16.6038Z","steps":["trace[1490529262] 'process raft request' (duration: 280.442994ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:46:16.604071Z","caller":"traceutil/trace.go:171","msg":"trace[2058238688] transaction","detail":"{read_only:false; response_revision:26523; number_of_response:1; }","duration":"176.272435ms","start":"2026-07-21T21:46:16.427777Z","end":"2026-07-21T21:46:16.60405Z","steps":["trace[2058238688] 'process raft request' (duration: 175.98332ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:46:16.604207Z","caller":"traceutil/trace.go:171","msg":"trace[1354349434] transaction","detail":"{read_only:false; response_revision:26521; number_of_response:1; }","duration":"280.971565ms","start":"2026-07-21T21:46:16.323139Z","end":"2026-07-21T21:46:16.604111Z","steps":["trace[1354349434] 'process raft request' (duration: 280.312981ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:46:16.605072Z","caller":"traceutil/trace.go:171","msg":"trace[1728906942] transaction","detail":"{read_only:false; response_revision:26524; number_of_response:1; }","duration":"175.55043ms","start":"2026-07-21T21:46:16.429487Z","end":"2026-07-21T21:46:16.605037Z","steps":["trace[1728906942] 'process raft request' (duration: 174.475179ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:48:50.200197Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25076} {"level":"info","ts":"2026-07-21T21:48:50.342779Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25076,"took":"138.532649ms","hash":3684883618,"current-db-size-bytes":58830848,"current-db-size":"59 MB","current-db-size-in-use-bytes":37543936,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-07-21T21:48:50.342837Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3684883618,"revision":25076,"compact-revision":21864} {"level":"info","ts":"2026-07-21T21:49:39.477732Z","caller":"traceutil/trace.go:171","msg":"trace[118424626] transaction","detail":"{read_only:false; response_revision:28562; number_of_response:1; }","duration":"118.115543ms","start":"2026-07-21T21:49:39.359499Z","end":"2026-07-21T21:49:39.477615Z","steps":["trace[118424626] 'process raft request' (duration: 117.509961ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:39.492057Z","caller":"traceutil/trace.go:171","msg":"trace[1251417575] transaction","detail":"{read_only:false; response_revision:28563; number_of_response:1; }","duration":"132.495197ms","start":"2026-07-21T21:49:39.359541Z","end":"2026-07-21T21:49:39.492036Z","steps":["trace[1251417575] 'process raft request' (duration: 131.984055ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:49:39.492315Z","caller":"traceutil/trace.go:171","msg":"trace[1081041043] transaction","detail":"{read_only:false; response_revision:28564; number_of_response:1; }","duration":"132.750842ms","start":"2026-07-21T21:49:39.359553Z","end":"2026-07-21T21:49:39.492304Z","steps":["trace[1081041043] 'process raft request' (duration: 132.454226ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:50:27.680895Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c47ca1a7b1fe506b","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-21T21:50:27.686541Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-21T21:50:27.686635Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-21T21:53:50.206737Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28060} {"level":"info","ts":"2026-07-21T21:53:50.345113Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28060,"took":"134.082308ms","hash":4276625915,"current-db-size-bytes":58830848,"current-db-size":"59 MB","current-db-size-in-use-bytes":33017856,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-21T21:53:50.34517Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4276625915,"revision":28060,"compact-revision":25076} {"level":"info","ts":"2026-07-21T21:58:50.213871Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30578} {"level":"info","ts":"2026-07-21T21:58:50.358081Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30578,"took":"140.745775ms","hash":2817333570,"current-db-size-bytes":58830848,"current-db-size":"59 MB","current-db-size-in-use-bytes":30818304,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-21T21:58:50.358163Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2817333570,"revision":30578,"compact-revision":28060} {"level":"info","ts":"2026-07-21T22:03:50.219486Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32852} {"level":"info","ts":"2026-07-21T22:03:50.372481Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32852,"took":"148.661846ms","hash":3450589094,"current-db-size-bytes":59871232,"current-db-size":"60 MB","current-db-size-in-use-bytes":31637504,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-21T22:03:50.372555Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3450589094,"revision":32852,"compact-revision":30578} {"level":"info","ts":"2026-07-21T22:08:50.226299Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35166} {"level":"info","ts":"2026-07-21T22:08:50.368054Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35166,"took":"138.352845ms","hash":3794280063,"current-db-size-bytes":59871232,"current-db-size":"60 MB","current-db-size-in-use-bytes":31170560,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-21T22:08:50.368129Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3794280063,"revision":35166,"compact-revision":32852} {"level":"info","ts":"2026-07-21T22:11:32.663582Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c47ca1a7b1fe506b","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-21T22:11:32.668494Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-21T22:11:32.66857Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}