{"level":"warn","ts":"2026-08-08T05:53:03.801451Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-08T05:53:03.801619Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113: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.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113: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-08-08T05:53:03.801726Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-08T05:53:03.801746Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-08-08T05:53:03.80178Z","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-08-08T05:53:03.803348Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-08-08T05:53:03.803466Z","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.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113: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-08-08T05:53:03.827267Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"23.41599ms"} {"level":"info","ts":"2026-08-08T05:53:03.841465Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-08-08T05:53:03.841573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-08-08T05:53:03.841644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-08-08T05:53:03.841686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-08T05:53:03.841719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-08-08T05:53:03.841804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-08-08T05:53:03.846611Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-08T05:53:03.848235Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-08T05:53:03.849833Z","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-08-08T05:53:03.852898Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-08T05:53:03.8532Z","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-08-08T05:53:03.853364Z","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-08-08T05:53:03.853412Z","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-08-08T05:53:03.853181Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-08T05:53:03.853928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-08-08T05:53:03.854745Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-08-08T05:53:03.858111Z","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-08-08T05:53:03.85826Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-08-08T05:53:03.858348Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-08-08T05:53:03.858608Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-08T05:53:03.858686Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-08T05:53:04.642085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-08-08T05:53:04.642225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-08-08T05:53:04.642281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-08-08T05:53:04.642302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-08-08T05:53:04.642314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-08-08T05:53:04.64233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-08-08T05:53:04.642349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-08-08T05:53:04.643209Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-08T05:53:04.643985Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-08T05:53:04.643985Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-08-08T05:53:04.644038Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-08T05:53:04.644356Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-08T05:53:04.644417Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-08T05:53:04.644626Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-08-08T05:53:04.644742Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-08T05:53:04.644785Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-08T05:53:04.647907Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-08T05:53:04.651262Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"warn","ts":"2026-08-08T05:53:14.612623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.162055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-08T05:53:14.612753Z","caller":"traceutil/trace.go:171","msg":"trace[169574092] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:383; }","duration":"294.339029ms","start":"2026-08-08T05:53:14.318391Z","end":"2026-08-08T05:53:14.61273Z","steps":["trace[169574092] 'range keys from in-memory index tree' (duration: 294.060913ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T05:53:14.612651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.403951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4373"} {"level":"info","ts":"2026-08-08T05:53:14.612896Z","caller":"traceutil/trace.go:171","msg":"trace[233323788] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:383; }","duration":"332.715747ms","start":"2026-08-08T05:53:14.280158Z","end":"2026-08-08T05:53:14.612874Z","steps":["trace[233323788] 'range keys from in-memory index tree' (duration: 332.099984ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T05:53:14.612986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T05:53:14.280132Z","time spent":"332.84023ms","remote":"127.0.0.1:40298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4396,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-08-08T05:53:14.615043Z","caller":"traceutil/trace.go:171","msg":"trace[752327003] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"138.348896ms","start":"2026-08-08T05:53:14.476671Z","end":"2026-08-08T05:53:14.61502Z","steps":["trace[752327003] 'process raft request' (duration: 138.196072ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T05:53:14.952054Z","caller":"traceutil/trace.go:171","msg":"trace[1419356444] linearizableReadLoop","detail":"{readStateIndex:397; appliedIndex:396; }","duration":"290.760515ms","start":"2026-08-08T05:53:14.661271Z","end":"2026-08-08T05:53:14.952032Z","steps":["trace[1419356444] 'read index received' (duration: 290.728164ms)","trace[1419356444] 'applied index is now lower than readState.Index' (duration: 31.371µs)"],"step_count":2} {"level":"info","ts":"2026-08-08T05:53:14.952202Z","caller":"traceutil/trace.go:171","msg":"trace[985441740] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"328.222503ms","start":"2026-08-08T05:53:14.623949Z","end":"2026-08-08T05:53:14.952172Z","steps":["trace[985441740] 'process raft request' (duration: 327.974278ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T05:53:14.952387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.082391ms","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-08-08T05:53:14.952482Z","caller":"traceutil/trace.go:171","msg":"trace[979504025] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:386; }","duration":"291.238335ms","start":"2026-08-08T05:53:14.661219Z","end":"2026-08-08T05:53:14.952457Z","steps":["trace[979504025] 'agreement among raft nodes before linearized reading' (duration: 290.98932ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T05:53:14.953028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T05:53:14.623931Z","time spent":"328.52384ms","remote":"127.0.0.1:40312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4467,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T05:53:14.953821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.344897ms","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-08-08T05:53:14.953925Z","caller":"traceutil/trace.go:171","msg":"trace[1008649165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:386; }","duration":"203.50644ms","start":"2026-08-08T05:53:14.750396Z","end":"2026-08-08T05:53:14.953903Z","steps":["trace[1008649165] 'agreement among raft nodes before linearized reading' (duration: 203.335557ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T05:53:49.244419Z","caller":"traceutil/trace.go:171","msg":"trace[98995574] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"623.592614ms","start":"2026-08-08T05:53:48.620769Z","end":"2026-08-08T05:53:49.244362Z","steps":["trace[98995574] 'process raft request' (duration: 622.964622ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T05:53:49.244587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T05:53:48.620732Z","time spent":"623.769417ms","remote":"127.0.0.1:40204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T05:53:49.295315Z","caller":"traceutil/trace.go:171","msg":"trace[907571679] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:651; }","duration":"438.809311ms","start":"2026-08-08T05:53:48.856485Z","end":"2026-08-08T05:53:49.295295Z","steps":["trace[907571679] 'read index received' (duration: 387.136127ms)","trace[907571679] 'applied index is now lower than readState.Index' (duration: 51.672154ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T05:53:49.295453Z","caller":"traceutil/trace.go:171","msg":"trace[706664131] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"666.654038ms","start":"2026-08-08T05:53:48.628759Z","end":"2026-08-08T05:53:49.295413Z","steps":["trace[706664131] 'process raft request' (duration: 666.415354ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T05:53:49.29552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.029136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-08T05:53:49.295546Z","caller":"traceutil/trace.go:171","msg":"trace[325266525] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:629; }","duration":"439.113418ms","start":"2026-08-08T05:53:48.856424Z","end":"2026-08-08T05:53:49.295537Z","steps":["trace[325266525] 'agreement among raft nodes before linearized reading' (duration: 438.973235ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T05:53:49.29552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.745116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-08T05:53:49.295571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T05:53:48.856399Z","time spent":"439.166589ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-08T05:53:49.295593Z","caller":"traceutil/trace.go:171","msg":"trace[127268999] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"279.875649ms","start":"2026-08-08T05:53:49.0157Z","end":"2026-08-08T05:53:49.295575Z","steps":["trace[127268999] 'agreement among raft nodes before linearized reading' (duration: 279.743926ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T05:53:49.295634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T05:53:48.628725Z","time spent":"666.816342ms","remote":"127.0.0.1:40312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T05:53:49.295688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.728974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-08T05:53:49.295706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.731624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-08T05:53:49.295748Z","caller":"traceutil/trace.go:171","msg":"trace[1233040585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:629; }","duration":"151.802136ms","start":"2026-08-08T05:53:49.143927Z","end":"2026-08-08T05:53:49.295729Z","steps":["trace[1233040585] 'agreement among raft nodes before linearized reading' (duration: 151.572691ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T05:53:49.295796Z","caller":"traceutil/trace.go:171","msg":"trace[2062876646] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:629; }","duration":"164.855036ms","start":"2026-08-08T05:53:49.130898Z","end":"2026-08-08T05:53:49.295753Z","steps":["trace[2062876646] 'agreement among raft nodes before linearized reading' (duration: 164.598591ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T05:53:49.432837Z","caller":"traceutil/trace.go:171","msg":"trace[1260306021] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"132.31725ms","start":"2026-08-08T05:53:49.30047Z","end":"2026-08-08T05:53:49.432788Z","steps":["trace[1260306021] 'process raft request' (duration: 67.35014ms)","trace[1260306021] 'compare' (duration: 64.664744ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T05:53:49.433002Z","caller":"traceutil/trace.go:171","msg":"trace[2077592855] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"131.934433ms","start":"2026-08-08T05:53:49.301021Z","end":"2026-08-08T05:53:49.432956Z","steps":["trace[2077592855] 'process raft request' (duration: 131.679458ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T05:53:49.43356Z","caller":"traceutil/trace.go:171","msg":"trace[1716248877] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"128.942061ms","start":"2026-08-08T05:53:49.3046Z","end":"2026-08-08T05:53:49.433542Z","steps":["trace[1716248877] 'process raft request' (duration: 128.167394ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T05:54:04.94131Z","caller":"traceutil/trace.go:171","msg":"trace[1286805616] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"219.516793ms","start":"2026-08-08T05:54:04.721769Z","end":"2026-08-08T05:54:04.941286Z","steps":["trace[1286805616] 'process raft request' (duration: 219.36135ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T05:55:34.042491Z","caller":"traceutil/trace.go:171","msg":"trace[1378117314] linearizableReadLoop","detail":"{readStateIndex:2039; appliedIndex:2038; }","duration":"129.01676ms","start":"2026-08-08T05:55:33.913449Z","end":"2026-08-08T05:55:34.042466Z","steps":["trace[1378117314] 'read index received' (duration: 128.816056ms)","trace[1378117314] 'applied index is now lower than readState.Index' (duration: 199.354µs)"],"step_count":2} {"level":"warn","ts":"2026-08-08T05:55:34.042693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.235995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-08T05:55:34.042762Z","caller":"traceutil/trace.go:171","msg":"trace[1238962238] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1963; }","duration":"129.354988ms","start":"2026-08-08T05:55:33.913395Z","end":"2026-08-08T05:55:34.04275Z","steps":["trace[1238962238] 'agreement among raft nodes before linearized reading' (duration: 129.181184ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T05:55:34.042542Z","caller":"traceutil/trace.go:171","msg":"trace[1813411747] transaction","detail":"{read_only:false; response_revision:1963; number_of_response:1; }","duration":"133.457992ms","start":"2026-08-08T05:55:33.909058Z","end":"2026-08-08T05:55:34.042516Z","steps":["trace[1813411747] 'process raft request' (duration: 133.221697ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T05:55:59.792092Z","caller":"traceutil/trace.go:171","msg":"trace[1197229146] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"142.314271ms","start":"2026-08-08T05:55:59.649737Z","end":"2026-08-08T05:55:59.792051Z","steps":["trace[1197229146] 'process raft request' (duration: 141.980165ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T05:56:11.192223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.224481ms","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-08-08T05:56:11.192386Z","caller":"traceutil/trace.go:171","msg":"trace[132946949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2178; }","duration":"121.462326ms","start":"2026-08-08T05:56:11.070896Z","end":"2026-08-08T05:56:11.192358Z","steps":["trace[132946949] 'range keys from in-memory index tree' (duration: 118.333792ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:00:03.240567Z","caller":"traceutil/trace.go:171","msg":"trace[1676383933] transaction","detail":"{read_only:false; response_revision:3807; number_of_response:1; }","duration":"206.877735ms","start":"2026-08-08T06:00:03.033667Z","end":"2026-08-08T06:00:03.240544Z","steps":["trace[1676383933] 'process raft request' (duration: 206.710671ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:00:03.275119Z","caller":"traceutil/trace.go:171","msg":"trace[217528575] transaction","detail":"{read_only:false; response_revision:3809; number_of_response:1; }","duration":"146.508987ms","start":"2026-08-08T06:00:03.128578Z","end":"2026-08-08T06:00:03.275087Z","steps":["trace[217528575] 'process raft request' (duration: 146.454456ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:00:03.275189Z","caller":"traceutil/trace.go:171","msg":"trace[940038648] transaction","detail":"{read_only:false; response_revision:3808; number_of_response:1; }","duration":"239.656892ms","start":"2026-08-08T06:00:03.035498Z","end":"2026-08-08T06:00:03.275155Z","steps":["trace[940038648] 'process raft request' (duration: 239.366676ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:01:22.162107Z","caller":"traceutil/trace.go:171","msg":"trace[1800157987] transaction","detail":"{read_only:false; response_revision:4758; number_of_response:1; }","duration":"105.834948ms","start":"2026-08-08T06:01:22.056238Z","end":"2026-08-08T06:01:22.162073Z","steps":["trace[1800157987] 'process raft request' (duration: 59.106392ms)","trace[1800157987] 'compare' (duration: 46.394459ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:01:25.476422Z","caller":"traceutil/trace.go:171","msg":"trace[502234129] transaction","detail":"{read_only:false; response_revision:4854; number_of_response:1; }","duration":"115.962349ms","start":"2026-08-08T06:01:25.360441Z","end":"2026-08-08T06:01:25.476404Z","steps":["trace[502234129] 'process raft request' (duration: 115.836897ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:01:28.27136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.104796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-08T06:01:28.271456Z","caller":"traceutil/trace.go:171","msg":"trace[1349640483] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4978; }","duration":"255.245819ms","start":"2026-08-08T06:01:28.01619Z","end":"2026-08-08T06:01:28.271436Z","steps":["trace[1349640483] 'range keys from in-memory index tree' (duration: 254.964402ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:01:28.27147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.582225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-58c04c68-b026-437e-b3df-56cb641a77ca\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-08-08T06:01:28.271559Z","caller":"traceutil/trace.go:171","msg":"trace[1602177972] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-58c04c68-b026-437e-b3df-56cb641a77ca; range_end:; response_count:1; response_revision:4978; }","duration":"210.721128ms","start":"2026-08-08T06:01:28.060815Z","end":"2026-08-08T06:01:28.271536Z","steps":["trace[1602177972] 'range keys from in-memory index tree' (duration: 210.384541ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:01:28.271483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.971516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-08T06:01:28.271802Z","caller":"traceutil/trace.go:171","msg":"trace[1564139514] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4978; }","duration":"231.330193ms","start":"2026-08-08T06:01:28.040459Z","end":"2026-08-08T06:01:28.27179Z","steps":["trace[1564139514] 'range keys from in-memory index tree' (duration: 230.815192ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:01:28.271974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.350499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-wgb9c.18c9bf23692690fc\" ","response":"range_response_count:1 size:962"} {"level":"info","ts":"2026-08-08T06:01:28.272111Z","caller":"traceutil/trace.go:171","msg":"trace[832178141] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-wgb9c.18c9bf23692690fc; range_end:; response_count:1; response_revision:4978; }","duration":"208.528723ms","start":"2026-08-08T06:01:28.063549Z","end":"2026-08-08T06:01:28.272078Z","steps":["trace[832178141] 'range keys from in-memory index tree' (duration: 208.104763ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:01:41.836186Z","caller":"traceutil/trace.go:171","msg":"trace[1419908380] transaction","detail":"{read_only:false; response_revision:5242; number_of_response:1; }","duration":"140.555567ms","start":"2026-08-08T06:01:41.695602Z","end":"2026-08-08T06:01:41.836157Z","steps":["trace[1419908380] 'process raft request' (duration: 140.388893ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:01:41.836175Z","caller":"traceutil/trace.go:171","msg":"trace[42738999] linearizableReadLoop","detail":"{readStateIndex:5441; appliedIndex:5440; }","duration":"129.371905ms","start":"2026-08-08T06:01:41.706762Z","end":"2026-08-08T06:01:41.836133Z","steps":["trace[42738999] 'read index received' (duration: 129.211312ms)","trace[42738999] 'applied index is now lower than readState.Index' (duration: 159.593µs)"],"step_count":2} {"level":"warn","ts":"2026-08-08T06:01:41.836495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.707883ms","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-08-08T06:01:41.836589Z","caller":"traceutil/trace.go:171","msg":"trace[222093682] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5242; }","duration":"129.837376ms","start":"2026-08-08T06:01:41.706726Z","end":"2026-08-08T06:01:41.836563Z","steps":["trace[222093682] 'agreement among raft nodes before linearized reading' (duration: 129.55264ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:03:04.707536Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2986} {"level":"info","ts":"2026-08-08T06:03:04.783458Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2986,"took":"73.997974ms","hash":2336558842,"current-db-size-bytes":58441728,"current-db-size":"58 MB","current-db-size-in-use-bytes":33865728,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-08T06:03:04.78353Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2336558842,"revision":2986,"compact-revision":-1} {"level":"info","ts":"2026-08-08T06:07:47.845954Z","caller":"traceutil/trace.go:171","msg":"trace[493076428] transaction","detail":"{read_only:false; response_revision:8196; number_of_response:1; }","duration":"233.639377ms","start":"2026-08-08T06:07:47.612285Z","end":"2026-08-08T06:07:47.845924Z","steps":["trace[493076428] 'process raft request' (duration: 233.460473ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:07:48.007967Z","caller":"traceutil/trace.go:171","msg":"trace[1631135971] transaction","detail":"{read_only:false; response_revision:8197; number_of_response:1; }","duration":"393.800385ms","start":"2026-08-08T06:07:47.614107Z","end":"2026-08-08T06:07:48.007907Z","steps":["trace[1631135971] 'process raft request' (duration: 393.613161ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:07:48.008023Z","caller":"traceutil/trace.go:171","msg":"trace[240744386] transaction","detail":"{read_only:false; response_revision:8198; number_of_response:1; }","duration":"333.050478ms","start":"2026-08-08T06:07:47.674946Z","end":"2026-08-08T06:07:48.007996Z","steps":["trace[240744386] 'process raft request' (duration: 332.881164ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:07:48.008276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:07:47.674924Z","time spent":"333.223182ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T06:07:48.008297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:07:47.614078Z","time spent":"394.03568ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T06:07:48.00881Z","caller":"traceutil/trace.go:171","msg":"trace[1381512126] linearizableReadLoop","detail":"{readStateIndex:8485; appliedIndex:8482; }","duration":"312.131249ms","start":"2026-08-08T06:07:47.696664Z","end":"2026-08-08T06:07:48.008795Z","steps":["trace[1381512126] 'read index received' (duration: 149.137691ms)","trace[1381512126] 'applied index is now lower than readState.Index' (duration: 162.991788ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:07:48.008912Z","caller":"traceutil/trace.go:171","msg":"trace[1983586418] transaction","detail":"{read_only:false; response_revision:8199; number_of_response:1; }","duration":"235.067046ms","start":"2026-08-08T06:07:47.773833Z","end":"2026-08-08T06:07:48.0089Z","steps":["trace[1983586418] 'process raft request' (duration: 234.042454ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:07:48.009118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.451926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-08T06:07:48.009158Z","caller":"traceutil/trace.go:171","msg":"trace[1344709803] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:8199; }","duration":"312.518667ms","start":"2026-08-08T06:07:47.696629Z","end":"2026-08-08T06:07:48.009148Z","steps":["trace[1344709803] 'agreement among raft nodes before linearized reading' (duration: 312.371004ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:07:48.009501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.361431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-08T06:07:48.009734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:07:47.696613Z","time spent":"313.105358ms","remote":"127.0.0.1:40132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.113\" "} {"level":"info","ts":"2026-08-08T06:07:48.009601Z","caller":"traceutil/trace.go:171","msg":"trace[557478218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8199; }","duration":"270.524755ms","start":"2026-08-08T06:07:47.739052Z","end":"2026-08-08T06:07:48.009577Z","steps":["trace[557478218] 'agreement among raft nodes before linearized reading' (duration: 270.102906ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:07:48.009773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.484894ms","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-08-08T06:07:48.009891Z","caller":"traceutil/trace.go:171","msg":"trace[419880599] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8199; }","duration":"291.663958ms","start":"2026-08-08T06:07:47.718204Z","end":"2026-08-08T06:07:48.009868Z","steps":["trace[419880599] 'agreement among raft nodes before linearized reading' (duration: 290.72574ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:08:00.123752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.558808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-08T06:08:00.123836Z","caller":"traceutil/trace.go:171","msg":"trace[545786108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8260; }","duration":"107.713871ms","start":"2026-08-08T06:08:00.016102Z","end":"2026-08-08T06:08:00.123815Z","steps":["trace[545786108] 'range keys from in-memory index tree' (duration: 107.386604ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:08:04.715649Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6098} {"level":"info","ts":"2026-08-08T06:08:04.810728Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6098,"took":"92.180773ms","hash":191518351,"current-db-size-bytes":58441728,"current-db-size":"58 MB","current-db-size-in-use-bytes":23453696,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-08-08T06:08:04.810809Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":191518351,"revision":6098,"compact-revision":2986} {"level":"info","ts":"2026-08-08T06:08:29.16597Z","caller":"traceutil/trace.go:171","msg":"trace[431293093] transaction","detail":"{read_only:false; response_revision:8474; number_of_response:1; }","duration":"138.101459ms","start":"2026-08-08T06:08:29.027844Z","end":"2026-08-08T06:08:29.165946Z","steps":["trace[431293093] 'process raft request' (duration: 137.947646ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:08:41.537133Z","caller":"traceutil/trace.go:171","msg":"trace[1875714972] linearizableReadLoop","detail":"{readStateIndex:8884; appliedIndex:8883; }","duration":"141.143232ms","start":"2026-08-08T06:08:41.395966Z","end":"2026-08-08T06:08:41.537109Z","steps":["trace[1875714972] 'read index received' (duration: 53.719614ms)","trace[1875714972] 'applied index is now lower than readState.Index' (duration: 87.422198ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:08:41.53716Z","caller":"traceutil/trace.go:171","msg":"trace[1496688700] transaction","detail":"{read_only:false; response_revision:8579; number_of_response:1; }","duration":"143.624404ms","start":"2026-08-08T06:08:41.393502Z","end":"2026-08-08T06:08:41.537127Z","steps":["trace[1496688700] 'process raft request' (duration: 56.296917ms)","trace[1496688700] 'compare' (duration: 87.098811ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T06:08:41.53744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.440188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-6kscq\" ","response":"range_response_count:1 size:2804"} {"level":"info","ts":"2026-08-08T06:08:41.537501Z","caller":"traceutil/trace.go:171","msg":"trace[780152559] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-6kscq; range_end:; response_count:1; response_revision:8580; }","duration":"141.535191ms","start":"2026-08-08T06:08:41.395948Z","end":"2026-08-08T06:08:41.537483Z","steps":["trace[780152559] 'agreement among raft nodes before linearized reading' (duration: 141.315977ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:08:43.267124Z","caller":"traceutil/trace.go:171","msg":"trace[487541689] transaction","detail":"{read_only:false; response_revision:8594; number_of_response:1; }","duration":"111.608475ms","start":"2026-08-08T06:08:43.155481Z","end":"2026-08-08T06:08:43.267089Z","steps":["trace[487541689] 'process raft request' (duration: 87.340476ms)","trace[487541689] 'compare' (duration: 24.003244ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:09:30.698135Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002487.wal"} {"level":"info","ts":"2026-08-08T06:10:43.931151Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a31e123290fcf0df","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-08T06:10:43.936293Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-08T06:10:43.93638Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-08T06:13:04.722019Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8296} {"level":"info","ts":"2026-08-08T06:13:04.802607Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8296,"took":"78.278094ms","hash":1809500613,"current-db-size-bytes":58441728,"current-db-size":"58 MB","current-db-size-in-use-bytes":25423872,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-08-08T06:13:04.802664Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1809500613,"revision":8296,"compact-revision":6098} {"level":"info","ts":"2026-08-08T06:18:04.72936Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10790} {"level":"info","ts":"2026-08-08T06:18:04.81689Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10790,"took":"82.518677ms","hash":2559234558,"current-db-size-bytes":58441728,"current-db-size":"58 MB","current-db-size-in-use-bytes":27402240,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-08T06:18:04.816949Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2559234558,"revision":10790,"compact-revision":8296} {"level":"info","ts":"2026-08-08T06:19:43.671984Z","caller":"traceutil/trace.go:171","msg":"trace[557084470] linearizableReadLoop","detail":"{readStateIndex:14102; appliedIndex:14101; }","duration":"124.855937ms","start":"2026-08-08T06:19:43.547102Z","end":"2026-08-08T06:19:43.671958Z","steps":["trace[557084470] 'read index received' (duration: 124.685233ms)","trace[557084470] 'applied index is now lower than readState.Index' (duration: 168.834µs)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:19:43.672092Z","caller":"traceutil/trace.go:171","msg":"trace[924266670] transaction","detail":"{read_only:false; response_revision:13637; number_of_response:1; }","duration":"166.616886ms","start":"2026-08-08T06:19:43.505443Z","end":"2026-08-08T06:19:43.67206Z","steps":["trace[924266670] 'process raft request' (duration: 166.33887ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:19:43.672188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.063921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-08T06:19:43.672273Z","caller":"traceutil/trace.go:171","msg":"trace[134085833] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:13637; }","duration":"125.199304ms","start":"2026-08-08T06:19:43.547043Z","end":"2026-08-08T06:19:43.672242Z","steps":["trace[134085833] 'agreement among raft nodes before linearized reading' (duration: 125.048571ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:19:59.406449Z","caller":"traceutil/trace.go:171","msg":"trace[309624227] transaction","detail":"{read_only:false; response_revision:13732; number_of_response:1; }","duration":"105.55157ms","start":"2026-08-08T06:19:59.300873Z","end":"2026-08-08T06:19:59.406425Z","steps":["trace[309624227] 'process raft request' (duration: 105.408167ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:19:59.897549Z","caller":"traceutil/trace.go:171","msg":"trace[1607774136] linearizableReadLoop","detail":"{readStateIndex:14203; appliedIndex:14202; }","duration":"143.861967ms","start":"2026-08-08T06:19:59.753661Z","end":"2026-08-08T06:19:59.897523Z","steps":["trace[1607774136] 'read index received' (duration: 143.659273ms)","trace[1607774136] 'applied index is now lower than readState.Index' (duration: 201.484µs)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:19:59.897623Z","caller":"traceutil/trace.go:171","msg":"trace[568080216] transaction","detail":"{read_only:false; response_revision:13734; number_of_response:1; }","duration":"146.378219ms","start":"2026-08-08T06:19:59.751208Z","end":"2026-08-08T06:19:59.897586Z","steps":["trace[568080216] 'process raft request' (duration: 146.114933ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:19:59.897847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.132813ms","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-08-08T06:19:59.897926Z","caller":"traceutil/trace.go:171","msg":"trace[1830949939] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13734; }","duration":"144.260995ms","start":"2026-08-08T06:19:59.753646Z","end":"2026-08-08T06:19:59.897907Z","steps":["trace[1830949939] 'agreement among raft nodes before linearized reading' (duration: 144.00896ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:19:59.931532Z","caller":"traceutil/trace.go:171","msg":"trace[1817163450] transaction","detail":"{read_only:false; response_revision:13735; number_of_response:1; }","duration":"177.54952ms","start":"2026-08-08T06:19:59.753958Z","end":"2026-08-08T06:19:59.931508Z","steps":["trace[1817163450] 'process raft request' (duration: 177.366346ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:19:59.931871Z","caller":"traceutil/trace.go:171","msg":"trace[420920548] transaction","detail":"{read_only:false; response_revision:13736; number_of_response:1; }","duration":"176.315255ms","start":"2026-08-08T06:19:59.755533Z","end":"2026-08-08T06:19:59.931848Z","steps":["trace[420920548] 'process raft request' (duration: 175.932407ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:19:59.932437Z","caller":"traceutil/trace.go:171","msg":"trace[1350140340] transaction","detail":"{read_only:false; response_revision:13737; number_of_response:1; }","duration":"146.632014ms","start":"2026-08-08T06:19:59.785783Z","end":"2026-08-08T06:19:59.932415Z","steps":["trace[1350140340] 'process raft request' (duration: 145.95502ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:19:59.932663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.320887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-08T06:19:59.932741Z","caller":"traceutil/trace.go:171","msg":"trace[341011052] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:13739; }","duration":"163.454429ms","start":"2026-08-08T06:19:59.769267Z","end":"2026-08-08T06:19:59.932722Z","steps":["trace[341011052] 'agreement among raft nodes before linearized reading' (duration: 163.320717ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:20:20.82794Z","caller":"traceutil/trace.go:171","msg":"trace[608031466] linearizableReadLoop","detail":"{readStateIndex:14522; appliedIndex:14521; }","duration":"268.499288ms","start":"2026-08-08T06:20:20.559416Z","end":"2026-08-08T06:20:20.827915Z","steps":["trace[608031466] 'read index received' (duration: 268.373545ms)","trace[608031466] 'applied index is now lower than readState.Index' (duration: 124.653µs)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:20:20.827992Z","caller":"traceutil/trace.go:171","msg":"trace[2093831176] transaction","detail":"{read_only:false; response_revision:14041; number_of_response:1; }","duration":"294.591925ms","start":"2026-08-08T06:20:20.533368Z","end":"2026-08-08T06:20:20.82796Z","steps":["trace[2093831176] 'process raft request' (duration: 294.408821ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:20:20.82809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.649071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-86f06014-193e-4c02-a9f9-2e17f489169b\" ","response":"range_response_count:1 size:3754"} {"level":"info","ts":"2026-08-08T06:20:20.828128Z","caller":"traceutil/trace.go:171","msg":"trace[1753963030] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-86f06014-193e-4c02-a9f9-2e17f489169b; range_end:; response_count:1; response_revision:14041; }","duration":"268.726553ms","start":"2026-08-08T06:20:20.55939Z","end":"2026-08-08T06:20:20.828116Z","steps":["trace[1753963030] 'agreement among raft nodes before linearized reading' (duration: 268.643991ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:20:20.833002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.539713ms","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-08-08T06:20:20.833094Z","caller":"traceutil/trace.go:171","msg":"trace[1330329264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14042; }","duration":"128.681465ms","start":"2026-08-08T06:20:20.704392Z","end":"2026-08-08T06:20:20.833073Z","steps":["trace[1330329264] 'agreement among raft nodes before linearized reading' (duration: 128.42772ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:23:04.739621Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12984} {"level":"info","ts":"2026-08-08T06:23:04.825595Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12984,"took":"81.796699ms","hash":3635256306,"current-db-size-bytes":58441728,"current-db-size":"58 MB","current-db-size-in-use-bytes":31125504,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-08T06:23:04.825678Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3635256306,"revision":12984,"compact-revision":10790} {"level":"info","ts":"2026-08-08T06:27:27.806215Z","caller":"traceutil/trace.go:171","msg":"trace[1238523293] transaction","detail":"{read_only:false; response_revision:17399; number_of_response:1; }","duration":"144.782606ms","start":"2026-08-08T06:27:27.661331Z","end":"2026-08-08T06:27:27.806114Z","steps":["trace[1238523293] 'process raft request' (duration: 144.49954ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:27:28.095147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.825013ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-08T06:27:28.095232Z","caller":"traceutil/trace.go:171","msg":"trace[1357619587] linearizableReadLoop","detail":"{readStateIndex:17989; appliedIndex:17988; }","duration":"112.472565ms","start":"2026-08-08T06:27:27.982746Z","end":"2026-08-08T06:27:28.095219Z","steps":["trace[1357619587] 'read index received' (duration: 39.361µs)","trace[1357619587] 'applied index is now lower than readState.Index' (duration: 112.432334ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T06:27:28.09535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.618558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-08T06:27:28.095386Z","caller":"traceutil/trace.go:171","msg":"trace[1592057314] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17400; }","duration":"112.688959ms","start":"2026-08-08T06:27:27.982681Z","end":"2026-08-08T06:27:28.09537Z","steps":["trace[1592057314] 'agreement among raft nodes before linearized reading' (duration: 112.571967ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:27:28.095466Z","caller":"traceutil/trace.go:171","msg":"trace[1976004262] transaction","detail":"{read_only:false; response_revision:17400; number_of_response:1; }","duration":"263.415278ms","start":"2026-08-08T06:27:27.832017Z","end":"2026-08-08T06:27:28.095433Z","steps":["trace[1976004262] 'process raft request' (duration: 108.142727ms)","trace[1976004262] 'compare' (duration: 154.72293ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T06:27:28.411928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.591373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-08T06:27:28.411949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.609924ms","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-08-08T06:27:28.412015Z","caller":"traceutil/trace.go:171","msg":"trace[774668652] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:17401; }","duration":"204.739215ms","start":"2026-08-08T06:27:28.207256Z","end":"2026-08-08T06:27:28.411995Z","steps":["trace[774668652] 'count revisions from in-memory index tree' (duration: 204.492361ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:27:28.412103Z","caller":"traceutil/trace.go:171","msg":"trace[2057387992] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17401; }","duration":"146.751207ms","start":"2026-08-08T06:27:28.265282Z","end":"2026-08-08T06:27:28.412033Z","steps":["trace[2057387992] 'range keys from in-memory index tree' (duration: 146.466121ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:27:28.412145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.376664ms","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-08-08T06:27:28.412284Z","caller":"traceutil/trace.go:171","msg":"trace[2129874957] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17401; }","duration":"194.540377ms","start":"2026-08-08T06:27:28.217694Z","end":"2026-08-08T06:27:28.412235Z","steps":["trace[2129874957] 'range keys from in-memory index tree' (duration: 194.151129ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:27:28.412455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.707769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-wkntk\" ","response":"range_response_count:1 size:22132"} {"level":"warn","ts":"2026-08-08T06:27:28.412489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.104025ms","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-08-08T06:27:28.412509Z","caller":"traceutil/trace.go:171","msg":"trace[1903325685] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-wkntk; range_end:; response_count:1; response_revision:17401; }","duration":"131.808631ms","start":"2026-08-08T06:27:28.280688Z","end":"2026-08-08T06:27:28.412497Z","steps":["trace[1903325685] 'range keys from in-memory index tree' (duration: 131.537916ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:27:28.412524Z","caller":"traceutil/trace.go:171","msg":"trace[882752398] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17401; }","duration":"147.140276ms","start":"2026-08-08T06:27:28.265374Z","end":"2026-08-08T06:27:28.412514Z","steps":["trace[882752398] 'range keys from in-memory index tree' (duration: 146.960013ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:27:36.303549Z","caller":"traceutil/trace.go:171","msg":"trace[593083782] transaction","detail":"{read_only:false; response_revision:17549; number_of_response:1; }","duration":"113.301603ms","start":"2026-08-08T06:27:36.190215Z","end":"2026-08-08T06:27:36.303516Z","steps":["trace[593083782] 'process raft request' (duration: 112.895864ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:27:36.30357Z","caller":"traceutil/trace.go:171","msg":"trace[264173759] transaction","detail":"{read_only:false; response_revision:17550; number_of_response:1; }","duration":"112.587038ms","start":"2026-08-08T06:27:36.190954Z","end":"2026-08-08T06:27:36.303541Z","steps":["trace[264173759] 'process raft request' (duration: 112.481625ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:27:36.44247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.579022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-08T06:27:36.442529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.043742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:651"} {"level":"info","ts":"2026-08-08T06:27:36.442583Z","caller":"traceutil/trace.go:171","msg":"trace[1119204783] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:17558; }","duration":"103.711136ms","start":"2026-08-08T06:27:36.338854Z","end":"2026-08-08T06:27:36.442565Z","steps":["trace[1119204783] 'agreement among raft nodes before linearized reading' (duration: 103.556353ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:27:36.442588Z","caller":"traceutil/trace.go:171","msg":"trace[1696531820] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:17558; }","duration":"103.113133ms","start":"2026-08-08T06:27:36.33946Z","end":"2026-08-08T06:27:36.442573Z","steps":["trace[1696531820] 'agreement among raft nodes before linearized reading' (duration: 102.938149ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:27:36.739266Z","caller":"traceutil/trace.go:171","msg":"trace[1321534993] linearizableReadLoop","detail":"{readStateIndex:18167; appliedIndex:18166; }","duration":"139.322376ms","start":"2026-08-08T06:27:36.599913Z","end":"2026-08-08T06:27:36.739236Z","steps":["trace[1321534993] 'read index received' (duration: 128.501924ms)","trace[1321534993] 'applied index is now lower than readState.Index' (duration: 10.819022ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T06:27:36.73947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.761842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/openstack/libvirt-libvirt-default-6f5h9-vnc\" ","response":"range_response_count:1 size:2197"} {"level":"info","ts":"2026-08-08T06:27:36.739444Z","caller":"traceutil/trace.go:171","msg":"trace[827602721] transaction","detail":"{read_only:false; number_of_response:1; response_revision:17571; }","duration":"141.845077ms","start":"2026-08-08T06:27:36.597556Z","end":"2026-08-08T06:27:36.739401Z","steps":["trace[827602721] 'process raft request' (duration: 130.992724ms)","trace[827602721] 'compare' (duration: 10.620958ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:27:36.739534Z","caller":"traceutil/trace.go:171","msg":"trace[705906181] range","detail":"{range_begin:/registry/cert-manager.io/certificates/openstack/libvirt-libvirt-default-6f5h9-vnc; range_end:; response_count:1; response_revision:17571; }","duration":"154.852323ms","start":"2026-08-08T06:27:36.584661Z","end":"2026-08-08T06:27:36.739513Z","steps":["trace[705906181] 'agreement among raft nodes before linearized reading' (duration: 154.741671ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:27:36.739543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.415377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1125"} {"level":"warn","ts":"2026-08-08T06:27:36.739564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.29895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-wkntk\" ","response":"range_response_count:1 size:22132"} {"level":"info","ts":"2026-08-08T06:27:36.739624Z","caller":"traceutil/trace.go:171","msg":"trace[1826886566] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:17571; }","duration":"140.53122ms","start":"2026-08-08T06:27:36.59907Z","end":"2026-08-08T06:27:36.739601Z","steps":["trace[1826886566] 'agreement among raft nodes before linearized reading' (duration: 140.268635ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:27:36.739633Z","caller":"traceutil/trace.go:171","msg":"trace[2064753529] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-wkntk; range_end:; response_count:1; response_revision:17571; }","duration":"150.381222ms","start":"2026-08-08T06:27:36.58923Z","end":"2026-08-08T06:27:36.739611Z","steps":["trace[2064753529] 'agreement among raft nodes before linearized reading' (duration: 150.163908ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:27:36.73966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.448418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:111958"} {"level":"info","ts":"2026-08-08T06:27:36.739742Z","caller":"traceutil/trace.go:171","msg":"trace[30821062] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:17571; }","duration":"140.57748ms","start":"2026-08-08T06:27:36.599147Z","end":"2026-08-08T06:27:36.739724Z","steps":["trace[30821062] 'agreement among raft nodes before linearized reading' (duration: 140.173262ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:27:36.739791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.884914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:651"} {"level":"info","ts":"2026-08-08T06:27:36.739854Z","caller":"traceutil/trace.go:171","msg":"trace[398353395] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:17571; }","duration":"135.973406ms","start":"2026-08-08T06:27:36.603866Z","end":"2026-08-08T06:27:36.739839Z","steps":["trace[398353395] 'agreement among raft nodes before linearized reading' (duration: 135.808182ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:28:04.746746Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15371} {"level":"info","ts":"2026-08-08T06:28:04.842603Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15371,"took":"91.657588ms","hash":3888533918,"current-db-size-bytes":58441728,"current-db-size":"58 MB","current-db-size-in-use-bytes":32669696,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-08T06:28:04.842665Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3888533918,"revision":15371,"compact-revision":12984} {"level":"info","ts":"2026-08-08T06:31:26.768951Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a31e123290fcf0df","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-08T06:31:26.78505Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-08T06:31:26.78519Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-08T06:33:04.754137Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17767} {"level":"info","ts":"2026-08-08T06:33:04.857001Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17767,"took":"99.381188ms","hash":2811640635,"current-db-size-bytes":58441728,"current-db-size":"58 MB","current-db-size-in-use-bytes":33263616,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-08T06:33:04.857085Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2811640635,"revision":17767,"compact-revision":15371} {"level":"info","ts":"2026-08-08T06:33:12.018163Z","caller":"traceutil/trace.go:171","msg":"trace[574370550] transaction","detail":"{read_only:false; response_revision:20071; number_of_response:1; }","duration":"112.586499ms","start":"2026-08-08T06:33:11.905541Z","end":"2026-08-08T06:33:12.018128Z","steps":["trace[574370550] 'process raft request' (duration: 112.328433ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:33:17.991093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.15284ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-08-08T06:33:18.290128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.04714ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-08T06:33:18.290211Z","caller":"traceutil/trace.go:171","msg":"trace[1222762703] linearizableReadLoop","detail":"{readStateIndex:20779; appliedIndex:20778; }","duration":"252.15896ms","start":"2026-08-08T06:33:18.038039Z","end":"2026-08-08T06:33:18.290198Z","steps":["trace[1222762703] 'read index received' (duration: 114.971507ms)","trace[1222762703] 'applied index is now lower than readState.Index' (duration: 137.186603ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:33:18.290278Z","caller":"traceutil/trace.go:171","msg":"trace[22774179] transaction","detail":"{read_only:false; response_revision:20101; number_of_response:1; }","duration":"297.339045ms","start":"2026-08-08T06:33:17.99293Z","end":"2026-08-08T06:33:18.290269Z","steps":["trace[22774179] 'process raft request' (duration: 160.080061ms)","trace[22774179] 'compare' (duration: 136.941398ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T06:33:18.290723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.6547ms","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-08-08T06:33:18.290801Z","caller":"traceutil/trace.go:171","msg":"trace[946698028] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20101; }","duration":"252.798413ms","start":"2026-08-08T06:33:18.037981Z","end":"2026-08-08T06:33:18.290779Z","steps":["trace[946698028] 'agreement among raft nodes before linearized reading' (duration: 252.523227ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:33:18.524565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.73489ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-08T06:33:18.52483Z","caller":"traceutil/trace.go:171","msg":"trace[960526693] linearizableReadLoop","detail":"{readStateIndex:20782; appliedIndex:20779; }","duration":"229.409723ms","start":"2026-08-08T06:33:18.295401Z","end":"2026-08-08T06:33:18.52481Z","steps":["trace[960526693] 'read index received' (duration: 102.238275ms)","trace[960526693] 'applied index is now lower than readState.Index' (duration: 127.170138ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:33:18.525101Z","caller":"traceutil/trace.go:171","msg":"trace[1921280878] transaction","detail":"{read_only:false; response_revision:20102; number_of_response:1; }","duration":"230.596107ms","start":"2026-08-08T06:33:18.294489Z","end":"2026-08-08T06:33:18.525085Z","steps":["trace[1921280878] 'process raft request' (duration: 103.229616ms)","trace[1921280878] 'compare' (duration: 126.496604ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:33:18.525314Z","caller":"traceutil/trace.go:171","msg":"trace[151874210] transaction","detail":"{read_only:false; response_revision:20103; number_of_response:1; }","duration":"230.600707ms","start":"2026-08-08T06:33:18.294701Z","end":"2026-08-08T06:33:18.525302Z","steps":["trace[151874210] 'process raft request' (duration: 229.991045ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:33:18.525472Z","caller":"traceutil/trace.go:171","msg":"trace[1235584070] transaction","detail":"{read_only:false; response_revision:20104; number_of_response:1; }","duration":"230.73381ms","start":"2026-08-08T06:33:18.294729Z","end":"2026-08-08T06:33:18.525463Z","steps":["trace[1235584070] 'process raft request' (duration: 230.041646ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:33:18.525708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.299611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-08T06:33:18.525755Z","caller":"traceutil/trace.go:171","msg":"trace[848491923] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:20104; }","duration":"230.367033ms","start":"2026-08-08T06:33:18.295376Z","end":"2026-08-08T06:33:18.525743Z","steps":["trace[848491923] 'agreement among raft nodes before linearized reading' (duration: 230.206399ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:33:18.525832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.477848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-08T06:33:18.525914Z","caller":"traceutil/trace.go:171","msg":"trace[500915313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20105; }","duration":"195.610051ms","start":"2026-08-08T06:33:18.330285Z","end":"2026-08-08T06:33:18.525895Z","steps":["trace[500915313] 'agreement among raft nodes before linearized reading' (duration: 195.410307ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:33:18.52627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.26718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-08T06:33:18.526333Z","caller":"traceutil/trace.go:171","msg":"trace[1301130840] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:20105; }","duration":"129.377402ms","start":"2026-08-08T06:33:18.396941Z","end":"2026-08-08T06:33:18.526318Z","steps":["trace[1301130840] 'agreement among raft nodes before linearized reading' (duration: 129.2722ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:33:18.526549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.681689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-08T06:33:18.526714Z","caller":"traceutil/trace.go:171","msg":"trace[194848579] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20105; }","duration":"191.877784ms","start":"2026-08-08T06:33:18.334804Z","end":"2026-08-08T06:33:18.526681Z","steps":["trace[194848579] 'agreement among raft nodes before linearized reading' (duration: 191.446595ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:33:18.861483Z","caller":"traceutil/trace.go:171","msg":"trace[971625167] linearizableReadLoop","detail":"{readStateIndex:20785; appliedIndex:20784; }","duration":"179.710514ms","start":"2026-08-08T06:33:18.681742Z","end":"2026-08-08T06:33:18.861452Z","steps":["trace[971625167] 'read index received' (duration: 179.51257ms)","trace[971625167] 'applied index is now lower than readState.Index' (duration: 196.714µs)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:33:18.861584Z","caller":"traceutil/trace.go:171","msg":"trace[1943637753] transaction","detail":"{read_only:false; response_revision:20107; number_of_response:1; }","duration":"330.74864ms","start":"2026-08-08T06:33:18.530803Z","end":"2026-08-08T06:33:18.861552Z","steps":["trace[1943637753] 'process raft request' (duration: 330.460425ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:33:18.861681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.902848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T06:33:18.861723Z","caller":"traceutil/trace.go:171","msg":"trace[1404996557] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:20107; }","duration":"180.01109ms","start":"2026-08-08T06:33:18.681698Z","end":"2026-08-08T06:33:18.861709Z","steps":["trace[1404996557] 'agreement among raft nodes before linearized reading' (duration: 179.901277ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:33:18.861737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:33:18.530785Z","time spent":"330.867503ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T06:33:18.891789Z","caller":"traceutil/trace.go:171","msg":"trace[651059112] transaction","detail":"{read_only:false; response_revision:20108; number_of_response:1; }","duration":"194.950398ms","start":"2026-08-08T06:33:18.696788Z","end":"2026-08-08T06:33:18.891739Z","steps":["trace[651059112] 'process raft request' (duration: 194.701203ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:33:18.891851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.003266ms","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-08-08T06:33:18.891947Z","caller":"traceutil/trace.go:171","msg":"trace[773145407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20108; }","duration":"150.317762ms","start":"2026-08-08T06:33:18.741604Z","end":"2026-08-08T06:33:18.891921Z","steps":["trace[773145407] 'agreement among raft nodes before linearized reading' (duration: 150.063867ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:37:03.926737Z","caller":"traceutil/trace.go:171","msg":"trace[2085857839] transaction","detail":"{read_only:false; response_revision:22516; number_of_response:1; }","duration":"112.605089ms","start":"2026-08-08T06:37:03.814107Z","end":"2026-08-08T06:37:03.926712Z","steps":["trace[2085857839] 'process raft request' (duration: 112.473726ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:37:04.208455Z","caller":"traceutil/trace.go:171","msg":"trace[801052741] linearizableReadLoop","detail":"{readStateIndex:23264; appliedIndex:23263; }","duration":"194.007188ms","start":"2026-08-08T06:37:04.014423Z","end":"2026-08-08T06:37:04.20843Z","steps":["trace[801052741] 'read index received' (duration: 193.248142ms)","trace[801052741] 'applied index is now lower than readState.Index' (duration: 757.936µs)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:37:04.208526Z","caller":"traceutil/trace.go:171","msg":"trace[1716620030] transaction","detail":"{read_only:false; response_revision:22518; number_of_response:1; }","duration":"254.64098ms","start":"2026-08-08T06:37:03.953851Z","end":"2026-08-08T06:37:04.208492Z","steps":["trace[1716620030] 'process raft request' (duration: 253.572438ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:37:04.208643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.162821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T06:37:04.208828Z","caller":"traceutil/trace.go:171","msg":"trace[1497183556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22518; }","duration":"194.383976ms","start":"2026-08-08T06:37:04.014381Z","end":"2026-08-08T06:37:04.208765Z","steps":["trace[1497183556] 'agreement among raft nodes before linearized reading' (duration: 194.155601ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:37:04.210197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.317042ms","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-08-08T06:37:04.210282Z","caller":"traceutil/trace.go:171","msg":"trace[635747012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22519; }","duration":"191.451195ms","start":"2026-08-08T06:37:04.018813Z","end":"2026-08-08T06:37:04.210264Z","steps":["trace[635747012] 'agreement among raft nodes before linearized reading' (duration: 191.22009ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:37:04.210422Z","caller":"traceutil/trace.go:171","msg":"trace[1188015510] transaction","detail":"{read_only:false; response_revision:22519; number_of_response:1; }","duration":"128.960773ms","start":"2026-08-08T06:37:04.081439Z","end":"2026-08-08T06:37:04.210399Z","steps":["trace[1188015510] 'process raft request' (duration: 128.460263ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:38:04.766388Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20036} {"level":"info","ts":"2026-08-08T06:38:04.874504Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20036,"took":"101.498112ms","hash":1114032900,"current-db-size-bytes":58441728,"current-db-size":"58 MB","current-db-size-in-use-bytes":42971136,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-08T06:38:04.874607Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1114032900,"revision":20036,"compact-revision":17767} {"level":"info","ts":"2026-08-08T06:40:15.001855Z","caller":"traceutil/trace.go:171","msg":"trace[439667193] transaction","detail":"{read_only:false; response_revision:24492; number_of_response:1; }","duration":"118.925666ms","start":"2026-08-08T06:40:14.882909Z","end":"2026-08-08T06:40:15.001835Z","steps":["trace[439667193] 'process raft request' (duration: 118.774943ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:20.495884Z","caller":"traceutil/trace.go:171","msg":"trace[1867002021] linearizableReadLoop","detail":"{readStateIndex:25328; appliedIndex:25327; }","duration":"297.506528ms","start":"2026-08-08T06:40:20.198342Z","end":"2026-08-08T06:40:20.495848Z","steps":["trace[1867002021] 'read index received' (duration: 297.349744ms)","trace[1867002021] 'applied index is now lower than readState.Index' (duration: 155.384µs)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:40:20.495935Z","caller":"traceutil/trace.go:171","msg":"trace[1294559894] transaction","detail":"{read_only:false; response_revision:24531; number_of_response:1; }","duration":"311.716148ms","start":"2026-08-08T06:40:20.184176Z","end":"2026-08-08T06:40:20.495892Z","steps":["trace[1294559894] 'process raft request' (duration: 311.451973ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:20.496036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.671531ms","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-08-08T06:40:20.496087Z","caller":"traceutil/trace.go:171","msg":"trace[1175328659] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24531; }","duration":"297.760542ms","start":"2026-08-08T06:40:20.198313Z","end":"2026-08-08T06:40:20.496074Z","steps":["trace[1175328659] 'agreement among raft nodes before linearized reading' (duration: 297.6632ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:20.496117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:20.184159Z","time spent":"311.874261ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T06:40:20.889206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.202655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-08-08T06:40:20.889273Z","caller":"traceutil/trace.go:171","msg":"trace[46502913] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:24532; }","duration":"323.300758ms","start":"2026-08-08T06:40:20.565956Z","end":"2026-08-08T06:40:20.889256Z","steps":["trace[46502913] 'range keys from in-memory index tree' (duration: 323.059102ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:20.889301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:20.565939Z","time spent":"323.355078ms","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1657,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"warn","ts":"2026-08-08T06:40:20.890059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.887105ms","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-08-08T06:40:20.89005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.243376ms","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-08-08T06:40:20.890092Z","caller":"traceutil/trace.go:171","msg":"trace[1666733509] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24532; }","duration":"255.956276ms","start":"2026-08-08T06:40:20.634127Z","end":"2026-08-08T06:40:20.890084Z","steps":["trace[1666733509] 'range keys from in-memory index tree' (duration: 255.778112ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:20.890102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.985554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-08T06:40:20.890137Z","caller":"traceutil/trace.go:171","msg":"trace[687078028] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24532; }","duration":"223.383529ms","start":"2026-08-08T06:40:20.666732Z","end":"2026-08-08T06:40:20.890116Z","steps":["trace[687078028] 'range keys from in-memory index tree' (duration: 223.031752ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:20.890195Z","caller":"traceutil/trace.go:171","msg":"trace[399305014] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24532; }","duration":"150.085077ms","start":"2026-08-08T06:40:20.740084Z","end":"2026-08-08T06:40:20.890169Z","steps":["trace[399305014] 'range keys from in-memory index tree' (duration: 149.781721ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:20.890688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.015427ms","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-08-08T06:40:20.890842Z","caller":"traceutil/trace.go:171","msg":"trace[1465305579] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24532; }","duration":"217.169151ms","start":"2026-08-08T06:40:20.673588Z","end":"2026-08-08T06:40:20.890757Z","steps":["trace[1465305579] 'range keys from in-memory index tree' (duration: 216.356164ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:21.027137Z","caller":"traceutil/trace.go:171","msg":"trace[1774658384] transaction","detail":"{read_only:false; response_revision:24533; number_of_response:1; }","duration":"132.507405ms","start":"2026-08-08T06:40:20.8946Z","end":"2026-08-08T06:40:21.027108Z","steps":["trace[1774658384] 'process raft request' (duration: 132.291441ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:21.174012Z","caller":"traceutil/trace.go:171","msg":"trace[2021207391] transaction","detail":"{read_only:false; response_revision:24534; number_of_response:1; }","duration":"279.351884ms","start":"2026-08-08T06:40:20.894631Z","end":"2026-08-08T06:40:21.173983Z","steps":["trace[2021207391] 'process raft request' (duration: 278.363405ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:21.174036Z","caller":"traceutil/trace.go:171","msg":"trace[1823428613] transaction","detail":"{read_only:false; response_revision:24535; number_of_response:1; }","duration":"279.355315ms","start":"2026-08-08T06:40:20.89465Z","end":"2026-08-08T06:40:21.174005Z","steps":["trace[1823428613] 'process raft request' (duration: 279.273493ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:21.174259Z","caller":"traceutil/trace.go:171","msg":"trace[587195050] transaction","detail":"{read_only:false; response_revision:24536; number_of_response:1; }","duration":"278.754593ms","start":"2026-08-08T06:40:20.895491Z","end":"2026-08-08T06:40:21.174246Z","steps":["trace[587195050] 'process raft request' (duration: 278.475907ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:21.174253Z","caller":"traceutil/trace.go:171","msg":"trace[1930145639] linearizableReadLoop","detail":"{readStateIndex:25333; appliedIndex:25330; }","duration":"182.48649ms","start":"2026-08-08T06:40:20.99175Z","end":"2026-08-08T06:40:21.174237Z","steps":["trace[1930145639] 'read index received' (duration: 35.380275ms)","trace[1930145639] 'applied index is now lower than readState.Index' (duration: 147.105075ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:40:21.174269Z","caller":"traceutil/trace.go:171","msg":"trace[1508491692] transaction","detail":"{read_only:false; response_revision:24537; number_of_response:1; }","duration":"153.469095ms","start":"2026-08-08T06:40:21.020779Z","end":"2026-08-08T06:40:21.174248Z","steps":["trace[1508491692] 'process raft request' (duration: 153.329112ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:21.174369Z","caller":"traceutil/trace.go:171","msg":"trace[1719052829] transaction","detail":"{read_only:false; response_revision:24538; number_of_response:1; }","duration":"150.80568ms","start":"2026-08-08T06:40:21.023551Z","end":"2026-08-08T06:40:21.174357Z","steps":["trace[1719052829] 'process raft request' (duration: 150.625776ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:21.174426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.667864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-08T06:40:21.174463Z","caller":"traceutil/trace.go:171","msg":"trace[473223139] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:24538; }","duration":"182.766286ms","start":"2026-08-08T06:40:20.991684Z","end":"2026-08-08T06:40:21.17445Z","steps":["trace[473223139] 'agreement among raft nodes before linearized reading' (duration: 182.684394ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:21.174616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.075559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T06:40:21.174685Z","caller":"traceutil/trace.go:171","msg":"trace[1459390045] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24539; }","duration":"157.178942ms","start":"2026-08-08T06:40:21.017489Z","end":"2026-08-08T06:40:21.174668Z","steps":["trace[1459390045] 'agreement among raft nodes before linearized reading' (duration: 157.051589ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:21.174693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.556587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-08-08T06:40:21.174729Z","caller":"traceutil/trace.go:171","msg":"trace[2000882511] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24539; }","duration":"129.639487ms","start":"2026-08-08T06:40:21.045079Z","end":"2026-08-08T06:40:21.174718Z","steps":["trace[2000882511] 'agreement among raft nodes before linearized reading' (duration: 129.508804ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:25.151601Z","caller":"traceutil/trace.go:171","msg":"trace[443596015] transaction","detail":"{read_only:false; response_revision:24566; number_of_response:1; }","duration":"105.518543ms","start":"2026-08-08T06:40:25.04606Z","end":"2026-08-08T06:40:25.151579Z","steps":["trace[443596015] 'process raft request' (duration: 105.35592ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:25.491495Z","caller":"traceutil/trace.go:171","msg":"trace[932044275] transaction","detail":"{read_only:false; response_revision:24567; number_of_response:1; }","duration":"301.298015ms","start":"2026-08-08T06:40:25.190166Z","end":"2026-08-08T06:40:25.491464Z","steps":["trace[932044275] 'process raft request' (duration: 299.277065ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:25.491662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:25.190132Z","time spent":"301.437768ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T06:40:25.693596Z","caller":"traceutil/trace.go:171","msg":"trace[781161875] transaction","detail":"{read_only:false; response_revision:24569; number_of_response:1; }","duration":"503.294496ms","start":"2026-08-08T06:40:25.190243Z","end":"2026-08-08T06:40:25.693537Z","steps":["trace[781161875] 'process raft request' (duration: 503.201444ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:25.693525Z","caller":"traceutil/trace.go:171","msg":"trace[17135546] linearizableReadLoop","detail":"{readStateIndex:25367; appliedIndex:25364; }","duration":"503.188214ms","start":"2026-08-08T06:40:25.190319Z","end":"2026-08-08T06:40:25.693507Z","steps":["trace[17135546] 'read index received' (duration: 299.142552ms)","trace[17135546] 'applied index is now lower than readState.Index' (duration: 204.044772ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T06:40:25.693787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:25.190202Z","time spent":"503.48817ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T06:40:25.693856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.890737ms","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-08-08T06:40:25.693884Z","caller":"traceutil/trace.go:171","msg":"trace[1472115975] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24570; }","duration":"501.959839ms","start":"2026-08-08T06:40:25.191914Z","end":"2026-08-08T06:40:25.693874Z","steps":["trace[1472115975] 'agreement among raft nodes before linearized reading' (duration: 501.852956ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:25.693908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:25.191899Z","time spent":"502.003929ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-08T06:40:25.694069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.676984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-08-08T06:40:25.694171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.991067ms","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-08-08T06:40:25.694191Z","caller":"traceutil/trace.go:171","msg":"trace[1960942279] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24570; }","duration":"175.051829ms","start":"2026-08-08T06:40:25.519134Z","end":"2026-08-08T06:40:25.694186Z","steps":["trace[1960942279] 'agreement among raft nodes before linearized reading' (duration: 174.979317ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:25.694233Z","caller":"traceutil/trace.go:171","msg":"trace[30588053] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24570; }","duration":"503.886158ms","start":"2026-08-08T06:40:25.190267Z","end":"2026-08-08T06:40:25.694153Z","steps":["trace[30588053] 'agreement among raft nodes before linearized reading' (duration: 503.48906ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:25.694269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:25.190242Z","time spent":"504.01998ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-08T06:40:25.694327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.565555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-08-08T06:40:25.694349Z","caller":"traceutil/trace.go:171","msg":"trace[1797566251] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24570; }","duration":"306.616815ms","start":"2026-08-08T06:40:25.387727Z","end":"2026-08-08T06:40:25.694344Z","steps":["trace[1797566251] 'agreement among raft nodes before linearized reading' (duration: 306.558144ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:25.694366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:25.387713Z","time spent":"306.648975ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":585,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-08-08T06:40:25.694444Z","caller":"traceutil/trace.go:171","msg":"trace[824249948] transaction","detail":"{read_only:false; response_revision:24570; number_of_response:1; }","duration":"336.218251ms","start":"2026-08-08T06:40:25.358118Z","end":"2026-08-08T06:40:25.694336Z","steps":["trace[824249948] 'process raft request' (duration: 335.353953ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:25.694451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.214265ms","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-08-08T06:40:25.694466Z","caller":"traceutil/trace.go:171","msg":"trace[258673460] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24570; }","duration":"364.253065ms","start":"2026-08-08T06:40:25.330208Z","end":"2026-08-08T06:40:25.694461Z","steps":["trace[258673460] 'agreement among raft nodes before linearized reading' (duration: 364.208185ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:25.694481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:25.330197Z","time spent":"364.280407ms","remote":"127.0.0.1:40386","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-08-08T06:40:25.694502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:25.358099Z","time spent":"336.363874ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T06:40:25.69456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.326517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-08T06:40:25.694567Z","caller":"traceutil/trace.go:171","msg":"trace[1224207733] transaction","detail":"{read_only:false; response_revision:24568; number_of_response:1; }","duration":"504.349517ms","start":"2026-08-08T06:40:25.190209Z","end":"2026-08-08T06:40:25.694558Z","steps":["trace[1224207733] 'process raft request' (duration: 503.082082ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:25.694581Z","caller":"traceutil/trace.go:171","msg":"trace[2120337938] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24570; }","duration":"364.347288ms","start":"2026-08-08T06:40:25.330226Z","end":"2026-08-08T06:40:25.694574Z","steps":["trace[2120337938] 'agreement among raft nodes before linearized reading' (duration: 364.291687ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:25.694591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:25.190176Z","time spent":"504.400739ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T06:40:25.694864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.861156ms","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-08-08T06:40:25.694989Z","caller":"traceutil/trace.go:171","msg":"trace[79349545] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24570; }","duration":"503.02384ms","start":"2026-08-08T06:40:25.191945Z","end":"2026-08-08T06:40:25.694969Z","steps":["trace[79349545] 'agreement among raft nodes before linearized reading' (duration: 502.792915ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:25.695077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:25.191929Z","time spent":"503.135162ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-08T06:40:25.694599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:25.330152Z","time spent":"364.44447ms","remote":"127.0.0.1:40386","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-08-08T06:40:25.880789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.521452ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-08T06:40:25.880944Z","caller":"traceutil/trace.go:171","msg":"trace[853284800] transaction","detail":"{read_only:false; response_revision:24571; number_of_response:1; }","duration":"180.864046ms","start":"2026-08-08T06:40:25.700055Z","end":"2026-08-08T06:40:25.880919Z","steps":["trace[853284800] 'process raft request' (duration: 56.11097ms)","trace[853284800] 'compare' (duration: 124.331298ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T06:40:26.20743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.857426ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-08T06:40:26.207801Z","caller":"traceutil/trace.go:171","msg":"trace[1124374490] transaction","detail":"{read_only:false; response_revision:24577; number_of_response:1; }","duration":"101.375618ms","start":"2026-08-08T06:40:26.106402Z","end":"2026-08-08T06:40:26.207777Z","steps":["trace[1124374490] 'process raft request' (duration: 101.331667ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:26.208062Z","caller":"traceutil/trace.go:171","msg":"trace[1634028680] transaction","detail":"{read_only:false; response_revision:24572; number_of_response:1; }","duration":"506.644994ms","start":"2026-08-08T06:40:25.701397Z","end":"2026-08-08T06:40:26.208042Z","steps":["trace[1634028680] 'process raft request' (duration: 308.081914ms)","trace[1634028680] 'compare' (duration: 197.667252ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:40:26.208091Z","caller":"traceutil/trace.go:171","msg":"trace[408258331] transaction","detail":"{read_only:false; response_revision:24575; number_of_response:1; }","duration":"505.664314ms","start":"2026-08-08T06:40:25.7024Z","end":"2026-08-08T06:40:26.208065Z","steps":["trace[408258331] 'process raft request' (duration: 505.265866ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:26.208101Z","caller":"traceutil/trace.go:171","msg":"trace[2018575847] transaction","detail":"{read_only:false; response_revision:24573; number_of_response:1; }","duration":"506.604244ms","start":"2026-08-08T06:40:25.701467Z","end":"2026-08-08T06:40:26.208071Z","steps":["trace[2018575847] 'process raft request' (duration: 506.065103ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:26.20812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:25.701381Z","time spent":"506.704336ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T06:40:26.208126Z","caller":"traceutil/trace.go:171","msg":"trace[679227947] transaction","detail":"{read_only:false; response_revision:24574; number_of_response:1; }","duration":"506.616334ms","start":"2026-08-08T06:40:25.701494Z","end":"2026-08-08T06:40:26.20811Z","steps":["trace[679227947] 'process raft request' (duration: 506.136364ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:26.208191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:25.702395Z","time spent":"505.743485ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T06:40:26.208209Z","caller":"traceutil/trace.go:171","msg":"trace[564775495] transaction","detail":"{read_only:false; response_revision:24576; number_of_response:1; }","duration":"505.197094ms","start":"2026-08-08T06:40:25.703004Z","end":"2026-08-08T06:40:26.208201Z","steps":["trace[564775495] 'process raft request' (duration: 504.690574ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:26.208222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:25.701491Z","time spent":"506.666745ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T06:40:26.208226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:25.701463Z","time spent":"506.690595ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T06:40:26.208266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.044551ms","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-08-08T06:40:26.208296Z","caller":"traceutil/trace.go:171","msg":"trace[1949175871] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24577; }","duration":"505.122463ms","start":"2026-08-08T06:40:25.703167Z","end":"2026-08-08T06:40:26.208289Z","steps":["trace[1949175871] 'agreement among raft nodes before linearized reading' (duration: 504.97209ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:26.208288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:25.702999Z","time spent":"505.234825ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T06:40:26.208317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:25.703151Z","time spent":"505.160494ms","remote":"127.0.0.1:40312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-08T06:40:26.208101Z","caller":"traceutil/trace.go:171","msg":"trace[1559836504] linearizableReadLoop","detail":"{readStateIndex:25374; appliedIndex:25368; }","duration":"504.881698ms","start":"2026-08-08T06:40:25.703201Z","end":"2026-08-08T06:40:26.208083Z","steps":["trace[1559836504] 'read index received' (duration: 53.045018ms)","trace[1559836504] 'applied index is now lower than readState.Index' (duration: 451.83495ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T06:40:26.208484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.054162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T06:40:26.208516Z","caller":"traceutil/trace.go:171","msg":"trace[1527624972] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:24577; }","duration":"366.118424ms","start":"2026-08-08T06:40:25.842387Z","end":"2026-08-08T06:40:26.208506Z","steps":["trace[1527624972] 'agreement among raft nodes before linearized reading' (duration: 366.058892ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:26.208517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.874356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-08T06:40:26.208549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:25.842369Z","time spent":"366.169134ms","remote":"127.0.0.1:41538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true "} {"level":"warn","ts":"2026-08-08T06:40:26.208525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.875693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T06:40:26.208572Z","caller":"traceutil/trace.go:171","msg":"trace[1237710064] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:24577; }","duration":"198.970899ms","start":"2026-08-08T06:40:26.009584Z","end":"2026-08-08T06:40:26.208555Z","steps":["trace[1237710064] 'agreement among raft nodes before linearized reading' (duration: 198.881497ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:26.20859Z","caller":"traceutil/trace.go:171","msg":"trace[1276691463] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24577; }","duration":"192.970774ms","start":"2026-08-08T06:40:26.015606Z","end":"2026-08-08T06:40:26.208577Z","steps":["trace[1276691463] 'agreement among raft nodes before linearized reading' (duration: 192.846661ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:26.478592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.817785ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-08T06:40:26.478787Z","caller":"traceutil/trace.go:171","msg":"trace[977131302] linearizableReadLoop","detail":"{readStateIndex:25377; appliedIndex:25375; }","duration":"177.589029ms","start":"2026-08-08T06:40:26.301181Z","end":"2026-08-08T06:40:26.47877Z","steps":["trace[977131302] 'read index received' (duration: 61.52779ms)","trace[977131302] 'applied index is now lower than readState.Index' (duration: 116.059779ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:40:26.478866Z","caller":"traceutil/trace.go:171","msg":"trace[1439632686] transaction","detail":"{read_only:false; response_revision:24579; number_of_response:1; }","duration":"201.211915ms","start":"2026-08-08T06:40:26.277618Z","end":"2026-08-08T06:40:26.47883Z","steps":["trace[1439632686] 'process raft request' (duration: 201.095482ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:26.478919Z","caller":"traceutil/trace.go:171","msg":"trace[165455486] transaction","detail":"{read_only:false; response_revision:24578; number_of_response:1; }","duration":"243.755326ms","start":"2026-08-08T06:40:26.235138Z","end":"2026-08-08T06:40:26.478894Z","steps":["trace[165455486] 'process raft request' (duration: 127.550823ms)","trace[165455486] 'compare' (duration: 115.682902ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T06:40:26.478909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.730303ms","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-08-08T06:40:26.479094Z","caller":"traceutil/trace.go:171","msg":"trace[1212943900] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24579; }","duration":"177.911487ms","start":"2026-08-08T06:40:26.301168Z","end":"2026-08-08T06:40:26.479079Z","steps":["trace[1212943900] 'agreement among raft nodes before linearized reading' (duration: 177.704132ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:26.697591Z","caller":"traceutil/trace.go:171","msg":"trace[1812230159] transaction","detail":"{read_only:false; response_revision:24581; number_of_response:1; }","duration":"180.444607ms","start":"2026-08-08T06:40:26.517123Z","end":"2026-08-08T06:40:26.697568Z","steps":["trace[1812230159] 'process raft request' (duration: 180.306085ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:27.011645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.20164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T06:40:27.011715Z","caller":"traceutil/trace.go:171","msg":"trace[1188799971] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:24581; }","duration":"213.288462ms","start":"2026-08-08T06:40:26.79841Z","end":"2026-08-08T06:40:27.011699Z","steps":["trace[1188799971] 'count revisions from in-memory index tree' (duration: 213.109248ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:27.011959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.833346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T06:40:27.011997Z","caller":"traceutil/trace.go:171","msg":"trace[1869421061] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:24581; }","duration":"178.887867ms","start":"2026-08-08T06:40:26.833099Z","end":"2026-08-08T06:40:27.011987Z","steps":["trace[1869421061] 'count revisions from in-memory index tree' (duration: 178.775815ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:27.012167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.320788ms","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-08-08T06:40:27.012195Z","caller":"traceutil/trace.go:171","msg":"trace[1167655250] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24581; }","duration":"205.38804ms","start":"2026-08-08T06:40:26.8068Z","end":"2026-08-08T06:40:27.012188Z","steps":["trace[1167655250] 'count revisions from in-memory index tree' (duration: 205.250666ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:27.360025Z","caller":"traceutil/trace.go:171","msg":"trace[1990682121] transaction","detail":"{read_only:false; response_revision:24583; number_of_response:1; }","duration":"152.155738ms","start":"2026-08-08T06:40:27.207847Z","end":"2026-08-08T06:40:27.360002Z","steps":["trace[1990682121] 'process raft request' (duration: 151.939834ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:27.662686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.702533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T06:40:27.662765Z","caller":"traceutil/trace.go:171","msg":"trace[1771804935] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24583; }","duration":"238.838156ms","start":"2026-08-08T06:40:27.423907Z","end":"2026-08-08T06:40:27.662745Z","steps":["trace[1771804935] 'range keys from in-memory index tree' (duration: 238.632002ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:27.663653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.884269ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-08T06:40:27.66377Z","caller":"traceutil/trace.go:171","msg":"trace[667920849] transaction","detail":"{read_only:false; response_revision:24584; number_of_response:1; }","duration":"166.774439ms","start":"2026-08-08T06:40:27.496977Z","end":"2026-08-08T06:40:27.663752Z","steps":["trace[667920849] 'process raft request' (duration: 24.720358ms)","trace[667920849] 'compare' (duration: 141.679404ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T06:40:27.956271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.599701ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-08T06:40:27.956385Z","caller":"traceutil/trace.go:171","msg":"trace[1831273892] transaction","detail":"{read_only:false; response_revision:24585; number_of_response:1; }","duration":"256.779252ms","start":"2026-08-08T06:40:27.699587Z","end":"2026-08-08T06:40:27.956366Z","steps":["trace[1831273892] 'process raft request' (duration: 95.986777ms)","trace[1831273892] 'compare' (duration: 160.412727ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T06:40:28.246871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.011326ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-08T06:40:28.247137Z","caller":"traceutil/trace.go:171","msg":"trace[710217990] transaction","detail":"{read_only:false; response_revision:24586; number_of_response:1; }","duration":"546.077972ms","start":"2026-08-08T06:40:27.701022Z","end":"2026-08-08T06:40:28.2471Z","steps":["trace[710217990] 'process raft request' (duration: 371.738249ms)","trace[710217990] 'compare' (duration: 173.817473ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T06:40:28.247293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:27.701015Z","time spent":"546.197014ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T06:40:28.249273Z","caller":"traceutil/trace.go:171","msg":"trace[424967932] transaction","detail":"{read_only:false; response_revision:24587; number_of_response:1; }","duration":"545.783017ms","start":"2026-08-08T06:40:27.703476Z","end":"2026-08-08T06:40:28.249259Z","steps":["trace[424967932] 'process raft request' (duration: 543.50208ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:28.249335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:27.703455Z","time spent":"545.846008ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T06:40:28.249416Z","caller":"traceutil/trace.go:171","msg":"trace[925629405] linearizableReadLoop","detail":"{readStateIndex:25385; appliedIndex:25383; }","duration":"443.50385ms","start":"2026-08-08T06:40:27.805904Z","end":"2026-08-08T06:40:28.249408Z","steps":["trace[925629405] 'read index received' (duration: 266.803348ms)","trace[925629405] 'applied index is now lower than readState.Index' (duration: 176.699472ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T06:40:28.249749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.850666ms","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-08-08T06:40:28.249766Z","caller":"traceutil/trace.go:171","msg":"trace[1787756682] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:24588; }","duration":"443.895618ms","start":"2026-08-08T06:40:27.805865Z","end":"2026-08-08T06:40:28.249761Z","steps":["trace[1787756682] 'agreement among raft nodes before linearized reading' (duration: 443.860927ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:28.249781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:27.805845Z","time spent":"443.932148ms","remote":"127.0.0.1:59982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true "} {"level":"warn","ts":"2026-08-08T06:40:28.341033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.616955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-08T06:40:28.341088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.714412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-08T06:40:28.341135Z","caller":"traceutil/trace.go:171","msg":"trace[1451867956] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24589; }","duration":"326.768997ms","start":"2026-08-08T06:40:28.014348Z","end":"2026-08-08T06:40:28.341117Z","steps":["trace[1451867956] 'agreement among raft nodes before linearized reading' (duration: 326.565203ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:28.341175Z","caller":"traceutil/trace.go:171","msg":"trace[1019343548] range","detail":"{range_begin:/registry/clusterctl.cluster.x-k8s.io/providers/; range_end:/registry/clusterctl.cluster.x-k8s.io/providers0; response_count:0; response_revision:24589; }","duration":"397.823884ms","start":"2026-08-08T06:40:27.943333Z","end":"2026-08-08T06:40:28.341156Z","steps":["trace[1019343548] 'agreement among raft nodes before linearized reading' (duration: 397.680831ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:28.341187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:28.014322Z","time spent":"326.85743ms","remote":"127.0.0.1:40102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-08T06:40:28.341216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:27.943278Z","time spent":"397.928536ms","remote":"127.0.0.1:59974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":4,"response size":31,"request content":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true "} {"level":"warn","ts":"2026-08-08T06:40:28.341211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.83441ms","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-08-08T06:40:28.341295Z","caller":"traceutil/trace.go:171","msg":"trace[139064158] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24589; }","duration":"125.942792ms","start":"2026-08-08T06:40:28.215333Z","end":"2026-08-08T06:40:28.341276Z","steps":["trace[139064158] 'agreement among raft nodes before linearized reading' (duration: 125.656866ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:28.341521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.88251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-08T06:40:28.341577Z","caller":"traceutil/trace.go:171","msg":"trace[822889779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24589; }","duration":"126.956431ms","start":"2026-08-08T06:40:28.214611Z","end":"2026-08-08T06:40:28.341568Z","steps":["trace[822889779] 'agreement among raft nodes before linearized reading' (duration: 126.795058ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:28.34164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.060884ms","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-08-08T06:40:28.341682Z","caller":"traceutil/trace.go:171","msg":"trace[1733646494] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24589; }","duration":"126.126255ms","start":"2026-08-08T06:40:28.215545Z","end":"2026-08-08T06:40:28.341672Z","steps":["trace[1733646494] 'agreement among raft nodes before linearized reading' (duration: 126.016123ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:28.34179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.588126ms","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-08-08T06:40:28.341828Z","caller":"traceutil/trace.go:171","msg":"trace[1988654246] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24589; }","duration":"127.635466ms","start":"2026-08-08T06:40:28.214183Z","end":"2026-08-08T06:40:28.341818Z","steps":["trace[1988654246] 'agreement among raft nodes before linearized reading' (duration: 127.537514ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:28.34188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.158554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T06:40:28.34192Z","caller":"traceutil/trace.go:171","msg":"trace[454107062] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:24589; }","duration":"145.241996ms","start":"2026-08-08T06:40:28.196666Z","end":"2026-08-08T06:40:28.341908Z","steps":["trace[454107062] 'agreement among raft nodes before linearized reading' (duration: 145.174475ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:28.342025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.641797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-08-08T06:40:28.342058Z","caller":"traceutil/trace.go:171","msg":"trace[1938318175] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:24589; }","duration":"513.704418ms","start":"2026-08-08T06:40:27.828345Z","end":"2026-08-08T06:40:28.34205Z","steps":["trace[1938318175] 'agreement among raft nodes before linearized reading' (duration: 513.622406ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:28.342039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.376544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-08T06:40:28.342083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:27.828326Z","time spent":"513.75012ms","remote":"127.0.0.1:40132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":163,"request content":"key:\"/registry/masterleases/199.204.45.113\" "} {"level":"info","ts":"2026-08-08T06:40:28.342169Z","caller":"traceutil/trace.go:171","msg":"trace[582968793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24589; }","duration":"456.514867ms","start":"2026-08-08T06:40:27.885578Z","end":"2026-08-08T06:40:28.342093Z","steps":["trace[582968793] 'agreement among raft nodes before linearized reading' (duration: 455.958606ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:28.342235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:27.885559Z","time spent":"456.66548ms","remote":"127.0.0.1:40386","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-08-08T06:40:28.556273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.110536ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-08-08T06:40:28.649319Z","caller":"traceutil/trace.go:171","msg":"trace[473133016] transaction","detail":"{read_only:false; response_revision:24594; number_of_response:1; }","duration":"302.206734ms","start":"2026-08-08T06:40:28.347093Z","end":"2026-08-08T06:40:28.6493Z","steps":["trace[473133016] 'process raft request' (duration: 302.176033ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:28.649436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:28.347068Z","time spent":"302.307317ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T06:40:28.649713Z","caller":"traceutil/trace.go:171","msg":"trace[1560930790] transaction","detail":"{read_only:false; response_revision:24590; number_of_response:1; }","duration":"303.585963ms","start":"2026-08-08T06:40:28.346119Z","end":"2026-08-08T06:40:28.649705Z","steps":["trace[1560930790] 'process raft request' (duration: 302.92894ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:28.649755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:28.346101Z","time spent":"303.631744ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T06:40:28.649843Z","caller":"traceutil/trace.go:171","msg":"trace[642012353] transaction","detail":"{read_only:false; response_revision:24591; number_of_response:1; }","duration":"303.45269ms","start":"2026-08-08T06:40:28.346384Z","end":"2026-08-08T06:40:28.649837Z","steps":["trace[642012353] 'process raft request' (duration: 302.802477ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:28.649876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:28.346341Z","time spent":"303.51575ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T06:40:28.649957Z","caller":"traceutil/trace.go:171","msg":"trace[1530885105] transaction","detail":"{read_only:false; response_revision:24592; number_of_response:1; }","duration":"303.171834ms","start":"2026-08-08T06:40:28.346779Z","end":"2026-08-08T06:40:28.649951Z","steps":["trace[1530885105] 'process raft request' (duration: 302.433549ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:28.64999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:28.346772Z","time spent":"303.199545ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T06:40:28.650042Z","caller":"traceutil/trace.go:171","msg":"trace[40003635] transaction","detail":"{read_only:false; response_revision:24593; number_of_response:1; }","duration":"303.210505ms","start":"2026-08-08T06:40:28.346826Z","end":"2026-08-08T06:40:28.650036Z","steps":["trace[40003635] 'process raft request' (duration: 302.415669ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:28.650072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:28.346821Z","time spent":"303.235285ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T06:40:28.756161Z","caller":"traceutil/trace.go:171","msg":"trace[1641367694] transaction","detail":"{read_only:false; response_revision:24595; number_of_response:1; }","duration":"225.507613ms","start":"2026-08-08T06:40:28.530634Z","end":"2026-08-08T06:40:28.756142Z","steps":["trace[1641367694] 'process raft request' (duration: 166.256898ms)","trace[1641367694] 'compare' (duration: 58.924068ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:40:28.756279Z","caller":"traceutil/trace.go:171","msg":"trace[1301662504] transaction","detail":"{read_only:false; response_revision:24596; number_of_response:1; }","duration":"199.10332ms","start":"2026-08-08T06:40:28.557131Z","end":"2026-08-08T06:40:28.756234Z","steps":["trace[1301662504] 'process raft request' (duration: 198.893316ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:28.993124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.054568ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-08T06:40:28.993278Z","caller":"traceutil/trace.go:171","msg":"trace[890557660] linearizableReadLoop","detail":"{readStateIndex:25397; appliedIndex:25395; }","duration":"235.16572ms","start":"2026-08-08T06:40:28.758098Z","end":"2026-08-08T06:40:28.993264Z","steps":["trace[890557660] 'read index received' (duration: 99.872426ms)","trace[890557660] 'applied index is now lower than readState.Index' (duration: 135.292204ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T06:40:28.993392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.300162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-08-08T06:40:28.993409Z","caller":"traceutil/trace.go:171","msg":"trace[732054732] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24598; }","duration":"235.344483ms","start":"2026-08-08T06:40:28.758059Z","end":"2026-08-08T06:40:28.993403Z","steps":["trace[732054732] 'agreement among raft nodes before linearized reading' (duration: 235.239071ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:28.993421Z","caller":"traceutil/trace.go:171","msg":"trace[1220262213] transaction","detail":"{read_only:false; response_revision:24597; number_of_response:1; }","duration":"288.900182ms","start":"2026-08-08T06:40:28.704492Z","end":"2026-08-08T06:40:28.993392Z","steps":["trace[1220262213] 'process raft request' (duration: 153.521057ms)","trace[1220262213] 'compare' (duration: 134.791872ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:40:28.993465Z","caller":"traceutil/trace.go:171","msg":"trace[1937416139] transaction","detail":"{read_only:false; response_revision:24598; number_of_response:1; }","duration":"285.205495ms","start":"2026-08-08T06:40:28.70822Z","end":"2026-08-08T06:40:28.993425Z","steps":["trace[1937416139] 'process raft request' (duration: 284.99826ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:29.490403Z","caller":"traceutil/trace.go:171","msg":"trace[1837261450] transaction","detail":"{read_only:false; response_revision:24599; number_of_response:1; }","duration":"122.748356ms","start":"2026-08-08T06:40:29.367634Z","end":"2026-08-08T06:40:29.490382Z","steps":["trace[1837261450] 'process raft request' (duration: 122.594573ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:30.618827Z","caller":"traceutil/trace.go:171","msg":"trace[315895581] transaction","detail":"{read_only:false; response_revision:24602; number_of_response:1; }","duration":"365.945251ms","start":"2026-08-08T06:40:30.252856Z","end":"2026-08-08T06:40:30.618801Z","steps":["trace[315895581] 'process raft request' (duration: 365.805458ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:30.61899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:30.252832Z","time spent":"366.066193ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T06:40:30.631274Z","caller":"traceutil/trace.go:171","msg":"trace[60840641] linearizableReadLoop","detail":"{readStateIndex:25403; appliedIndex:25400; }","duration":"200.123831ms","start":"2026-08-08T06:40:30.431134Z","end":"2026-08-08T06:40:30.631257Z","steps":["trace[60840641] 'read index received' (duration: 187.566404ms)","trace[60840641] 'applied index is now lower than readState.Index' (duration: 12.556277ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:40:30.631365Z","caller":"traceutil/trace.go:171","msg":"trace[209640026] transaction","detail":"{read_only:false; response_revision:24604; number_of_response:1; }","duration":"376.929315ms","start":"2026-08-08T06:40:30.254425Z","end":"2026-08-08T06:40:30.631355Z","steps":["trace[209640026] 'process raft request' (duration: 376.792762ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:30.631355Z","caller":"traceutil/trace.go:171","msg":"trace[1842237980] transaction","detail":"{read_only:false; response_revision:24603; number_of_response:1; }","duration":"378.050839ms","start":"2026-08-08T06:40:30.253245Z","end":"2026-08-08T06:40:30.631296Z","steps":["trace[1842237980] 'process raft request' (duration: 377.881996ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:30.631442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:30.254411Z","time spent":"376.983426ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T06:40:30.63144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.271064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T06:40:30.631498Z","caller":"traceutil/trace.go:171","msg":"trace[1864973724] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/floatingips/; range_end:/registry/openstack.k-orc.cloud/floatingips0; response_count:0; response_revision:24604; }","duration":"181.371887ms","start":"2026-08-08T06:40:30.450111Z","end":"2026-08-08T06:40:30.631483Z","steps":["trace[1864973724] 'agreement among raft nodes before linearized reading' (duration: 181.271225ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:30.631497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:30.253217Z","time spent":"378.200981ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T06:40:30.631507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.357536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T06:40:30.631582Z","caller":"traceutil/trace.go:171","msg":"trace[918411913] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:24604; }","duration":"200.490498ms","start":"2026-08-08T06:40:30.431074Z","end":"2026-08-08T06:40:30.631565Z","steps":["trace[918411913] 'agreement among raft nodes before linearized reading' (duration: 200.339125ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:30.793092Z","caller":"traceutil/trace.go:171","msg":"trace[1970805071] transaction","detail":"{read_only:false; response_revision:24605; number_of_response:1; }","duration":"136.897256ms","start":"2026-08-08T06:40:30.656172Z","end":"2026-08-08T06:40:30.793069Z","steps":["trace[1970805071] 'process raft request' (duration: 121.98649ms)","trace[1970805071] 'compare' (duration: 14.782173ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T06:40:31.116145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.292675ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-08T06:40:31.116581Z","caller":"traceutil/trace.go:171","msg":"trace[1414980487] transaction","detail":"{read_only:false; response_revision:24606; number_of_response:1; }","duration":"458.525287ms","start":"2026-08-08T06:40:30.658016Z","end":"2026-08-08T06:40:31.116541Z","steps":["trace[1414980487] 'process raft request' (duration: 277.758853ms)","trace[1414980487] 'compare' (duration: 180.155172ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T06:40:31.116744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:30.658007Z","time spent":"458.653719ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T06:40:31.116745Z","caller":"traceutil/trace.go:171","msg":"trace[1195905840] linearizableReadLoop","detail":"{readStateIndex:25408; appliedIndex:25403; }","duration":"408.642676ms","start":"2026-08-08T06:40:30.708082Z","end":"2026-08-08T06:40:31.116725Z","steps":["trace[1195905840] 'read index received' (duration: 70.161128ms)","trace[1195905840] 'applied index is now lower than readState.Index' (duration: 338.480588ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:40:31.116914Z","caller":"traceutil/trace.go:171","msg":"trace[1989633191] transaction","detail":"{read_only:false; response_revision:24610; number_of_response:1; }","duration":"355.960395ms","start":"2026-08-08T06:40:30.760933Z","end":"2026-08-08T06:40:31.116893Z","steps":["trace[1989633191] 'process raft request' (duration: 355.452744ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:31.116915Z","caller":"traceutil/trace.go:171","msg":"trace[2071558826] transaction","detail":"{read_only:false; response_revision:24608; number_of_response:1; }","duration":"458.523608ms","start":"2026-08-08T06:40:30.658363Z","end":"2026-08-08T06:40:31.116887Z","steps":["trace[2071558826] 'process raft request' (duration: 457.954235ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:31.116933Z","caller":"traceutil/trace.go:171","msg":"trace[879712261] transaction","detail":"{read_only:false; response_revision:24609; number_of_response:1; }","duration":"458.436787ms","start":"2026-08-08T06:40:30.658447Z","end":"2026-08-08T06:40:31.116884Z","steps":["trace[879712261] 'process raft request' (duration: 457.903465ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:31.116986Z","caller":"traceutil/trace.go:171","msg":"trace[167520997] transaction","detail":"{read_only:false; response_revision:24607; number_of_response:1; }","duration":"458.880285ms","start":"2026-08-08T06:40:30.658072Z","end":"2026-08-08T06:40:31.116952Z","steps":["trace[167520997] 'process raft request' (duration: 458.19137ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:31.117031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:30.760912Z","time spent":"356.060409ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T06:40:31.117033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:30.658352Z","time spent":"458.62695ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T06:40:31.117047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.962792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-08T06:40:31.117097Z","caller":"traceutil/trace.go:171","msg":"trace[167310421] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:24610; }","duration":"409.038704ms","start":"2026-08-08T06:40:30.708038Z","end":"2026-08-08T06:40:31.117077Z","steps":["trace[167310421] 'agreement among raft nodes before linearized reading' (duration: 408.743158ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:31.11712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:30.658069Z","time spent":"458.968187ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T06:40:31.117125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:30.65844Z","time spent":"458.59926ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T06:40:31.117159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:30.708022Z","time spent":"409.126525ms","remote":"127.0.0.1:54266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":37,"response size":31,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"info","ts":"2026-08-08T06:40:31.12794Z","caller":"traceutil/trace.go:171","msg":"trace[696604397] transaction","detail":"{read_only:false; response_revision:24611; number_of_response:1; }","duration":"129.452254ms","start":"2026-08-08T06:40:30.998462Z","end":"2026-08-08T06:40:31.127914Z","steps":["trace[696604397] 'process raft request' (duration: 129.264729ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:31.128021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.125015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T06:40:31.128478Z","caller":"traceutil/trace.go:171","msg":"trace[1465321306] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:24611; }","duration":"266.621754ms","start":"2026-08-08T06:40:30.861836Z","end":"2026-08-08T06:40:31.128458Z","steps":["trace[1465321306] 'agreement among raft nodes before linearized reading' (duration: 266.059263ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:31.128071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.271991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-08T06:40:31.128095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.28818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T06:40:31.128594Z","caller":"traceutil/trace.go:171","msg":"trace[923089276] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24611; }","duration":"111.811402ms","start":"2026-08-08T06:40:31.016753Z","end":"2026-08-08T06:40:31.128564Z","steps":["trace[923089276] 'agreement among raft nodes before linearized reading' (duration: 111.246071ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:31.128739Z","caller":"traceutil/trace.go:171","msg":"trace[1372376555] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:24611; }","duration":"252.957125ms","start":"2026-08-08T06:40:30.875762Z","end":"2026-08-08T06:40:31.128719Z","steps":["trace[1372376555] 'agreement among raft nodes before linearized reading' (duration: 252.292052ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:31.626928Z","caller":"traceutil/trace.go:171","msg":"trace[1568190455] linearizableReadLoop","detail":"{readStateIndex:25411; appliedIndex:25410; }","duration":"121.616513ms","start":"2026-08-08T06:40:31.505287Z","end":"2026-08-08T06:40:31.626904Z","steps":["trace[1568190455] 'read index received' (duration: 121.46629ms)","trace[1568190455] 'applied index is now lower than readState.Index' (duration: 148.913µs)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:40:31.626981Z","caller":"traceutil/trace.go:171","msg":"trace[906452843] transaction","detail":"{read_only:false; response_revision:24612; number_of_response:1; }","duration":"127.163497ms","start":"2026-08-08T06:40:31.499783Z","end":"2026-08-08T06:40:31.626947Z","steps":["trace[906452843] 'process raft request' (duration: 126.965203ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:31.627217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.91826ms","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-08-08T06:40:31.627285Z","caller":"traceutil/trace.go:171","msg":"trace[1244513869] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24612; }","duration":"122.029541ms","start":"2026-08-08T06:40:31.505244Z","end":"2026-08-08T06:40:31.627274Z","steps":["trace[1244513869] 'agreement among raft nodes before linearized reading' (duration: 121.750396ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:31.807297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.60368ms","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-08-08T06:40:31.807362Z","caller":"traceutil/trace.go:171","msg":"trace[2070995359] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24612; }","duration":"118.725353ms","start":"2026-08-08T06:40:31.688623Z","end":"2026-08-08T06:40:31.807349Z","steps":["trace[2070995359] 'range keys from in-memory index tree' (duration: 118.475958ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:31.821241Z","caller":"traceutil/trace.go:171","msg":"trace[1722743795] transaction","detail":"{read_only:false; response_revision:24613; number_of_response:1; }","duration":"102.678695ms","start":"2026-08-08T06:40:31.718531Z","end":"2026-08-08T06:40:31.82121Z","steps":["trace[1722743795] 'process raft request' (duration: 102.466261ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:32.159145Z","caller":"traceutil/trace.go:171","msg":"trace[373511108] linearizableReadLoop","detail":"{readStateIndex:25413; appliedIndex:25412; }","duration":"173.259081ms","start":"2026-08-08T06:40:31.985861Z","end":"2026-08-08T06:40:32.15912Z","steps":["trace[373511108] 'read index received' (duration: 164.056722ms)","trace[373511108] 'applied index is now lower than readState.Index' (duration: 9.201069ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:40:32.159182Z","caller":"traceutil/trace.go:171","msg":"trace[246899610] transaction","detail":"{read_only:false; response_revision:24614; number_of_response:1; }","duration":"182.94822ms","start":"2026-08-08T06:40:31.976213Z","end":"2026-08-08T06:40:32.159161Z","steps":["trace[246899610] 'process raft request' (duration: 173.67864ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:32.159324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.447835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-08T06:40:32.159466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.72884ms","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-08-08T06:40:32.159547Z","caller":"traceutil/trace.go:171","msg":"trace[912978022] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:24614; }","duration":"111.827752ms","start":"2026-08-08T06:40:32.047702Z","end":"2026-08-08T06:40:32.15953Z","steps":["trace[912978022] 'agreement among raft nodes before linearized reading' (duration: 111.688599ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:32.159792Z","caller":"traceutil/trace.go:171","msg":"trace[499487645] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:24614; }","duration":"173.958755ms","start":"2026-08-08T06:40:31.985816Z","end":"2026-08-08T06:40:32.159775Z","steps":["trace[499487645] 'agreement among raft nodes before linearized reading' (duration: 173.450035ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:36.823198Z","caller":"traceutil/trace.go:171","msg":"trace[1786246875] transaction","detail":"{read_only:false; response_revision:24644; number_of_response:1; }","duration":"163.694565ms","start":"2026-08-08T06:40:36.659477Z","end":"2026-08-08T06:40:36.823172Z","steps":["trace[1786246875] 'process raft request' (duration: 163.534661ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:37.222229Z","caller":"traceutil/trace.go:171","msg":"trace[388041897] transaction","detail":"{read_only:false; response_revision:24645; number_of_response:1; }","duration":"561.959458ms","start":"2026-08-08T06:40:36.660227Z","end":"2026-08-08T06:40:37.222187Z","steps":["trace[388041897] 'process raft request' (duration: 559.075849ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:37.222387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:36.660201Z","time spent":"562.100091ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T06:40:37.222393Z","caller":"traceutil/trace.go:171","msg":"trace[1407440221] transaction","detail":"{read_only:false; response_revision:24646; number_of_response:1; }","duration":"561.790855ms","start":"2026-08-08T06:40:36.660545Z","end":"2026-08-08T06:40:37.222335Z","steps":["trace[1407440221] 'process raft request' (duration: 561.54141ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:37.222632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:36.660536Z","time spent":"561.950818ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T06:40:37.223075Z","caller":"traceutil/trace.go:171","msg":"trace[821549577] transaction","detail":"{read_only:false; response_revision:24647; number_of_response:1; }","duration":"520.029328ms","start":"2026-08-08T06:40:36.703024Z","end":"2026-08-08T06:40:37.223053Z","steps":["trace[821549577] 'process raft request' (duration: 519.241433ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:37.223172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:36.703005Z","time spent":"520.11683ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T06:40:37.223095Z","caller":"traceutil/trace.go:171","msg":"trace[1337649699] linearizableReadLoop","detail":"{readStateIndex:25447; appliedIndex:25444; }","duration":"390.369701ms","start":"2026-08-08T06:40:36.832697Z","end":"2026-08-08T06:40:37.223067Z","steps":["trace[1337649699] 'read index received' (duration: 386.623364ms)","trace[1337649699] 'applied index is now lower than readState.Index' (duration: 3.744127ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T06:40:37.223327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.605935ms","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-08-08T06:40:37.223426Z","caller":"traceutil/trace.go:171","msg":"trace[113896377] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24647; }","duration":"390.732178ms","start":"2026-08-08T06:40:36.832666Z","end":"2026-08-08T06:40:37.223399Z","steps":["trace[113896377] 'agreement among raft nodes before linearized reading' (duration: 390.501294ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:37.223475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:36.832653Z","time spent":"390.81112ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-08T06:40:37.224288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.703134ms","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-08-08T06:40:37.224469Z","caller":"traceutil/trace.go:171","msg":"trace[45069024] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24649; }","duration":"383.920319ms","start":"2026-08-08T06:40:36.840505Z","end":"2026-08-08T06:40:37.224425Z","steps":["trace[45069024] 'agreement among raft nodes before linearized reading' (duration: 383.49335ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:37.22454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:36.840494Z","time spent":"384.029641ms","remote":"127.0.0.1:40312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-08T06:40:37.224581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.061984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T06:40:37.224622Z","caller":"traceutil/trace.go:171","msg":"trace[621218477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24649; }","duration":"208.140046ms","start":"2026-08-08T06:40:37.01647Z","end":"2026-08-08T06:40:37.22461Z","steps":["trace[621218477] 'agreement among raft nodes before linearized reading' (duration: 208.074464ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:37.224617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.128575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T06:40:37.224681Z","caller":"traceutil/trace.go:171","msg":"trace[1997887232] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:24649; }","duration":"289.226738ms","start":"2026-08-08T06:40:36.935435Z","end":"2026-08-08T06:40:37.224662Z","steps":["trace[1997887232] 'agreement among raft nodes before linearized reading' (duration: 289.132406ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:37.660355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.472387ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-08T06:40:37.660468Z","caller":"traceutil/trace.go:171","msg":"trace[1995316668] transaction","detail":"{read_only:false; response_revision:24650; number_of_response:1; }","duration":"430.84484ms","start":"2026-08-08T06:40:37.229592Z","end":"2026-08-08T06:40:37.660437Z","steps":["trace[1995316668] 'process raft request' (duration: 112.182799ms)","trace[1995316668] 'compare' (duration: 318.227502ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T06:40:37.660536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:37.229566Z","time spent":"430.939462ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T06:40:37.908729Z","caller":"traceutil/trace.go:171","msg":"trace[1602090682] transaction","detail":"{read_only:false; response_revision:24651; number_of_response:1; }","duration":"679.089658ms","start":"2026-08-08T06:40:37.229611Z","end":"2026-08-08T06:40:37.9087Z","steps":["trace[1602090682] 'process raft request' (duration: 642.158402ms)","trace[1602090682] 'compare' (duration: 36.674371ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T06:40:37.909042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:37.229589Z","time spent":"679.218291ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T06:40:37.909033Z","caller":"traceutil/trace.go:171","msg":"trace[176568491] transaction","detail":"{read_only:false; response_revision:24652; number_of_response:1; }","duration":"679.129418ms","start":"2026-08-08T06:40:37.229872Z","end":"2026-08-08T06:40:37.909001Z","steps":["trace[176568491] 'process raft request' (duration: 678.789102ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:37.90905Z","caller":"traceutil/trace.go:171","msg":"trace[1407578189] linearizableReadLoop","detail":"{readStateIndex:25454; appliedIndex:25449; }","duration":"678.038127ms","start":"2026-08-08T06:40:37.230985Z","end":"2026-08-08T06:40:37.909023Z","steps":["trace[1407578189] 'read index received' (duration: 110.809042ms)","trace[1407578189] 'applied index is now lower than readState.Index' (duration: 567.227715ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:40:37.909133Z","caller":"traceutil/trace.go:171","msg":"trace[356525277] transaction","detail":"{read_only:false; response_revision:24653; number_of_response:1; }","duration":"679.18062ms","start":"2026-08-08T06:40:37.229929Z","end":"2026-08-08T06:40:37.90911Z","steps":["trace[356525277] 'process raft request' (duration: 678.958645ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:37.909174Z","caller":"traceutil/trace.go:171","msg":"trace[1786348166] transaction","detail":"{read_only:false; response_revision:24654; number_of_response:1; }","duration":"678.457286ms","start":"2026-08-08T06:40:37.230705Z","end":"2026-08-08T06:40:37.909163Z","steps":["trace[1786348166] 'process raft request' (duration: 678.248261ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:37.90918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:37.229864Z","time spent":"679.238212ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T06:40:37.909237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:37.229914Z","time spent":"679.276552ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T06:40:37.909267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:37.230697Z","time spent":"678.501386ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T06:40:37.909329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.897183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-08T06:40:37.909377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.021651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T06:40:37.909414Z","caller":"traceutil/trace.go:171","msg":"trace[704159842] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24655; }","duration":"223.092892ms","start":"2026-08-08T06:40:37.686309Z","end":"2026-08-08T06:40:37.909402Z","steps":["trace[704159842] 'agreement among raft nodes before linearized reading' (duration: 223.02904ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:37.909422Z","caller":"traceutil/trace.go:171","msg":"trace[336294184] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:24655; }","duration":"498.037756ms","start":"2026-08-08T06:40:37.411358Z","end":"2026-08-08T06:40:37.909396Z","steps":["trace[336294184] 'agreement among raft nodes before linearized reading' (duration: 497.901614ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:37.909431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.697354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-08-08T06:40:37.909472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.484236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"warn","ts":"2026-08-08T06:40:37.909488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:37.411325Z","time spent":"498.15219ms","remote":"127.0.0.1:52980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true "} {"level":"info","ts":"2026-08-08T06:40:37.909496Z","caller":"traceutil/trace.go:171","msg":"trace[489422681] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24655; }","duration":"201.799676ms","start":"2026-08-08T06:40:37.707679Z","end":"2026-08-08T06:40:37.909479Z","steps":["trace[489422681] 'agreement among raft nodes before linearized reading' (duration: 201.634863ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:37.909528Z","caller":"traceutil/trace.go:171","msg":"trace[587833113] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24655; }","duration":"678.569348ms","start":"2026-08-08T06:40:37.230948Z","end":"2026-08-08T06:40:37.909517Z","steps":["trace[587833113] 'agreement among raft nodes before linearized reading' (duration: 678.375934ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:37.909565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:37.230933Z","time spent":"678.625809ms","remote":"127.0.0.1:40312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-08T06:40:45.857116Z","caller":"traceutil/trace.go:171","msg":"trace[860843716] transaction","detail":"{read_only:false; response_revision:24770; number_of_response:1; }","duration":"158.857805ms","start":"2026-08-08T06:40:45.698227Z","end":"2026-08-08T06:40:45.857085Z","steps":["trace[860843716] 'process raft request' (duration: 158.62308ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:46.161327Z","caller":"traceutil/trace.go:171","msg":"trace[420605861] transaction","detail":"{read_only:false; response_revision:24771; number_of_response:1; }","duration":"292.959595ms","start":"2026-08-08T06:40:45.868334Z","end":"2026-08-08T06:40:46.161293Z","steps":["trace[420605861] 'process raft request' (duration: 292.68916ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:46.691776Z","caller":"traceutil/trace.go:171","msg":"trace[1698303134] linearizableReadLoop","detail":"{readStateIndex:25574; appliedIndex:25572; }","duration":"750.499652ms","start":"2026-08-08T06:40:45.941248Z","end":"2026-08-08T06:40:46.691747Z","steps":["trace[1698303134] 'read index received' (duration: 219.824976ms)","trace[1698303134] 'applied index is now lower than readState.Index' (duration: 530.673636ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:40:46.691901Z","caller":"traceutil/trace.go:171","msg":"trace[1512597177] transaction","detail":"{read_only:false; response_revision:24772; number_of_response:1; }","duration":"755.218189ms","start":"2026-08-08T06:40:45.936621Z","end":"2026-08-08T06:40:46.69184Z","steps":["trace[1512597177] 'process raft request' (duration: 678.967486ms)","trace[1512597177] 'compare' (duration: 76.047209ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T06:40:46.692027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.756448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-08T06:40:46.692079Z","caller":"traceutil/trace.go:171","msg":"trace[270184908] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24772; }","duration":"750.853139ms","start":"2026-08-08T06:40:45.941211Z","end":"2026-08-08T06:40:46.692064Z","steps":["trace[270184908] 'agreement among raft nodes before linearized reading' (duration: 750.677156ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:46.692077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:45.936574Z","time spent":"755.413823ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T06:40:46.692124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:45.941191Z","time spent":"750.923861ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-08T06:40:46.747698Z","caller":"traceutil/trace.go:171","msg":"trace[1376930352] transaction","detail":"{read_only:false; response_revision:24773; number_of_response:1; }","duration":"530.3905ms","start":"2026-08-08T06:40:46.21723Z","end":"2026-08-08T06:40:46.747621Z","steps":["trace[1376930352] 'process raft request' (duration: 530.133025ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:46.747739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.382798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-08T06:40:46.747757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.806222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T06:40:46.747827Z","caller":"traceutil/trace.go:171","msg":"trace[1072681791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24773; }","duration":"806.572942ms","start":"2026-08-08T06:40:45.941235Z","end":"2026-08-08T06:40:46.747808Z","steps":["trace[1072681791] 'agreement among raft nodes before linearized reading' (duration: 806.281906ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:46.747827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.666978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-08-08T06:40:46.747868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:45.941217Z","time spent":"806.642393ms","remote":"127.0.0.1:40386","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":"info","ts":"2026-08-08T06:40:46.747896Z","caller":"traceutil/trace.go:171","msg":"trace[71905174] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24773; }","duration":"755.780519ms","start":"2026-08-08T06:40:45.992097Z","end":"2026-08-08T06:40:46.747878Z","steps":["trace[71905174] 'agreement among raft nodes before linearized reading' (duration: 755.526754ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:46.74791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.365862ms","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":"warn","ts":"2026-08-08T06:40:46.747913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:46.217203Z","time spent":"530.601505ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T06:40:46.747942Z","caller":"traceutil/trace.go:171","msg":"trace[1080581049] 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:24773; }","duration":"110.400662ms","start":"2026-08-08T06:40:46.637532Z","end":"2026-08-08T06:40:46.747933Z","steps":["trace[1080581049] 'agreement among raft nodes before linearized reading' (duration: 110.341281ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:46.747935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:45.992079Z","time spent":"755.848981ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-08T06:40:46.748008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.780382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6b821c33-ed07-4dfa-aed2-6541fd7302e3\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-08-08T06:40:46.748032Z","caller":"traceutil/trace.go:171","msg":"trace[1197892405] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6b821c33-ed07-4dfa-aed2-6541fd7302e3; range_end:; response_count:1; response_revision:24773; }","duration":"618.844944ms","start":"2026-08-08T06:40:46.12918Z","end":"2026-08-08T06:40:46.748025Z","steps":["trace[1197892405] 'agreement among raft nodes before linearized reading' (duration: 618.776163ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:46.748051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:46.129154Z","time spent":"618.892145ms","remote":"127.0.0.1:40312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3787,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6b821c33-ed07-4dfa-aed2-6541fd7302e3\" "} {"level":"info","ts":"2026-08-08T06:40:46.747845Z","caller":"traceutil/trace.go:171","msg":"trace[1710480109] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:24773; }","duration":"475.975236ms","start":"2026-08-08T06:40:46.271848Z","end":"2026-08-08T06:40:46.747823Z","steps":["trace[1710480109] 'agreement among raft nodes before linearized reading' (duration: 475.728951ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:46.748087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.454448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-08-08T06:40:46.748106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.003202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T06:40:46.748176Z","caller":"traceutil/trace.go:171","msg":"trace[2124117455] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24773; }","duration":"806.577251ms","start":"2026-08-08T06:40:45.941574Z","end":"2026-08-08T06:40:46.748151Z","steps":["trace[2124117455] 'agreement among raft nodes before linearized reading' (duration: 806.277005ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:46.748231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:45.941566Z","time spent":"806.656972ms","remote":"127.0.0.1:40386","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":"info","ts":"2026-08-08T06:40:46.74823Z","caller":"traceutil/trace.go:171","msg":"trace[1371166332] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:24773; }","duration":"349.207297ms","start":"2026-08-08T06:40:46.398997Z","end":"2026-08-08T06:40:46.748204Z","steps":["trace[1371166332] 'agreement among raft nodes before linearized reading' (duration: 348.933061ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:46.748122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:46.271837Z","time spent":"476.272882ms","remote":"127.0.0.1:35240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true "} {"level":"warn","ts":"2026-08-08T06:40:46.748327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:46.398977Z","time spent":"349.33693ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true "} {"level":"warn","ts":"2026-08-08T06:40:46.748723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.49956ms","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-08-08T06:40:46.748787Z","caller":"traceutil/trace.go:171","msg":"trace[203866654] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24773; }","duration":"805.582031ms","start":"2026-08-08T06:40:45.943188Z","end":"2026-08-08T06:40:46.74877Z","steps":["trace[203866654] 'agreement among raft nodes before linearized reading' (duration: 805.414048ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:46.748818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:45.943178Z","time spent":"805.633833ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-08T06:40:47.14382Z","caller":"traceutil/trace.go:171","msg":"trace[1774094050] transaction","detail":"{read_only:false; response_revision:24774; number_of_response:1; }","duration":"447.055923ms","start":"2026-08-08T06:40:46.696732Z","end":"2026-08-08T06:40:47.143788Z","steps":["trace[1774094050] 'process raft request' (duration: 406.458561ms)","trace[1774094050] 'compare' (duration: 40.419799ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T06:40:47.143963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:46.696708Z","time spent":"447.181955ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T06:40:47.406587Z","caller":"traceutil/trace.go:171","msg":"trace[2112190336] transaction","detail":"{read_only:false; response_revision:24775; number_of_response:1; }","duration":"653.717918ms","start":"2026-08-08T06:40:46.752839Z","end":"2026-08-08T06:40:47.406557Z","steps":["trace[2112190336] 'process raft request' (duration: 642.714004ms)","trace[2112190336] 'compare' (duration: 10.222398ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:40:47.406653Z","caller":"traceutil/trace.go:171","msg":"trace[1153107581] transaction","detail":"{read_only:false; response_revision:24777; number_of_response:1; }","duration":"652.434111ms","start":"2026-08-08T06:40:46.754196Z","end":"2026-08-08T06:40:47.40663Z","steps":["trace[1153107581] 'process raft request' (duration: 652.211426ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:47.406649Z","caller":"traceutil/trace.go:171","msg":"trace[181160669] transaction","detail":"{read_only:false; response_revision:24776; number_of_response:1; }","duration":"652.960612ms","start":"2026-08-08T06:40:46.75366Z","end":"2026-08-08T06:40:47.40662Z","steps":["trace[181160669] 'process raft request' (duration: 652.650866ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:47.406767Z","caller":"traceutil/trace.go:171","msg":"trace[2027270969] transaction","detail":"{read_only:false; response_revision:24778; number_of_response:1; }","duration":"651.685007ms","start":"2026-08-08T06:40:46.755063Z","end":"2026-08-08T06:40:47.406748Z","steps":["trace[2027270969] 'process raft request' (duration: 651.37795ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:47.406709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:46.752815Z","time spent":"653.831251ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T06:40:47.406921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.256185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/groups/\" range_end:\"/registry/openstack.k-orc.cloud/groups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-08T06:40:47.406789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:46.754179Z","time spent":"652.522794ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T06:40:47.40702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:46.755046Z","time spent":"651.886191ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T06:40:47.406802Z","caller":"traceutil/trace.go:171","msg":"trace[268138075] transaction","detail":"{read_only:false; response_revision:24779; number_of_response:1; }","duration":"542.730164ms","start":"2026-08-08T06:40:46.864033Z","end":"2026-08-08T06:40:47.406763Z","steps":["trace[268138075] 'process raft request' (duration: 542.476658ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:47.40701Z","caller":"traceutil/trace.go:171","msg":"trace[1546201699] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/groups/; range_end:/registry/openstack.k-orc.cloud/groups0; response_count:0; response_revision:24779; }","duration":"503.395528ms","start":"2026-08-08T06:40:46.903596Z","end":"2026-08-08T06:40:47.406992Z","steps":["trace[1546201699] 'agreement among raft nodes before linearized reading' (duration: 503.243915ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:47.407105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:46.903569Z","time spent":"503.52225ms","remote":"127.0.0.1:52910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/groups/\" range_end:\"/registry/openstack.k-orc.cloud/groups0\" count_only:true "} {"level":"info","ts":"2026-08-08T06:40:47.407166Z","caller":"traceutil/trace.go:171","msg":"trace[255316255] linearizableReadLoop","detail":"{readStateIndex:25581; appliedIndex:25575; }","duration":"503.050081ms","start":"2026-08-08T06:40:46.903651Z","end":"2026-08-08T06:40:47.406701Z","steps":["trace[255316255] 'read index received' (duration: 199.55248ms)","trace[255316255] 'applied index is now lower than readState.Index' (duration: 303.496291ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T06:40:47.407163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:46.864002Z","time spent":"543.070561ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T06:40:47.408189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:46.75364Z","time spent":"653.138778ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T06:40:47.604319Z","caller":"traceutil/trace.go:171","msg":"trace[637780264] transaction","detail":"{read_only:false; response_revision:24782; number_of_response:1; }","duration":"308.072024ms","start":"2026-08-08T06:40:47.296227Z","end":"2026-08-08T06:40:47.604299Z","steps":["trace[637780264] 'process raft request' (duration: 307.801979ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:47.60443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:47.296202Z","time spent":"308.167636ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T06:40:47.604502Z","caller":"traceutil/trace.go:171","msg":"trace[2056318439] transaction","detail":"{read_only:false; response_revision:24780; number_of_response:1; }","duration":"318.62407ms","start":"2026-08-08T06:40:47.285869Z","end":"2026-08-08T06:40:47.604493Z","steps":["trace[2056318439] 'process raft request' (duration: 279.650431ms)","trace[2056318439] 'compare' (duration: 38.278455ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T06:40:47.604539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:47.285845Z","time spent":"318.675052ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T06:40:47.604559Z","caller":"traceutil/trace.go:171","msg":"trace[431836537] transaction","detail":"{read_only:false; response_revision:24783; number_of_response:1; }","duration":"306.662685ms","start":"2026-08-08T06:40:47.297875Z","end":"2026-08-08T06:40:47.604538Z","steps":["trace[431836537] 'process raft request' (duration: 306.189226ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:47.604629Z","caller":"traceutil/trace.go:171","msg":"trace[377166535] transaction","detail":"{read_only:false; response_revision:24781; number_of_response:1; }","duration":"308.512603ms","start":"2026-08-08T06:40:47.29611Z","end":"2026-08-08T06:40:47.604623Z","steps":["trace[377166535] 'process raft request' (duration: 307.87093ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:47.60467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:47.296096Z","time spent":"308.554054ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T06:40:47.604701Z","caller":"traceutil/trace.go:171","msg":"trace[1654793787] linearizableReadLoop","detail":"{readStateIndex:25585; appliedIndex:25581; }","duration":"197.480517ms","start":"2026-08-08T06:40:47.407209Z","end":"2026-08-08T06:40:47.60469Z","steps":["trace[1654793787] 'read index received' (duration: 158.324445ms)","trace[1654793787] 'applied index is now lower than readState.Index' (duration: 39.155032ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T06:40:47.604659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:47.297863Z","time spent":"306.734996ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T06:40:47.604773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.372899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T06:40:47.604807Z","caller":"traceutil/trace.go:171","msg":"trace[2016885153] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24784; }","duration":"588.472352ms","start":"2026-08-08T06:40:47.016327Z","end":"2026-08-08T06:40:47.604799Z","steps":["trace[2016885153] 'agreement among raft nodes before linearized reading' (duration: 588.41066ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:47.604817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.204823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-08T06:40:47.604838Z","caller":"traceutil/trace.go:171","msg":"trace[833611762] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:24784; }","duration":"300.268994ms","start":"2026-08-08T06:40:47.304564Z","end":"2026-08-08T06:40:47.604833Z","steps":["trace[833611762] 'agreement among raft nodes before linearized reading' (duration: 300.222703ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:47.604841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:47.016306Z","time spent":"588.528712ms","remote":"127.0.0.1:40114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-08T06:40:47.604861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:47.304543Z","time spent":"300.313125ms","remote":"127.0.0.1:40374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":7,"response size":31,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-08-08T06:40:47.605085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.351912ms","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-08-08T06:40:47.605125Z","caller":"traceutil/trace.go:171","msg":"trace[1498358940] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24784; }","duration":"434.431014ms","start":"2026-08-08T06:40:47.170682Z","end":"2026-08-08T06:40:47.605113Z","steps":["trace[1498358940] 'agreement among raft nodes before linearized reading' (duration: 434.308841ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:47.605158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:47.170661Z","time spent":"434.490146ms","remote":"127.0.0.1:40216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":380,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"info","ts":"2026-08-08T06:40:49.257741Z","caller":"traceutil/trace.go:171","msg":"trace[92810526] transaction","detail":"{read_only:false; response_revision:24791; number_of_response:1; }","duration":"105.110344ms","start":"2026-08-08T06:40:49.152582Z","end":"2026-08-08T06:40:49.257692Z","steps":["trace[92810526] 'process raft request' (duration: 104.91012ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:52.915702Z","caller":"traceutil/trace.go:171","msg":"trace[683397181] transaction","detail":"{read_only:false; response_revision:24835; number_of_response:1; }","duration":"148.958332ms","start":"2026-08-08T06:40:52.766709Z","end":"2026-08-08T06:40:52.915667Z","steps":["trace[683397181] 'process raft request' (duration: 148.716648ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:57.304578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.149122ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-08T06:40:57.304743Z","caller":"traceutil/trace.go:171","msg":"trace[289717927] linearizableReadLoop","detail":"{readStateIndex:25679; appliedIndex:25678; }","duration":"287.382361ms","start":"2026-08-08T06:40:57.017331Z","end":"2026-08-08T06:40:57.304714Z","steps":["trace[289717927] 'read index received' (duration: 27.963683ms)","trace[289717927] 'applied index is now lower than readState.Index' (duration: 259.416738ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:40:57.304754Z","caller":"traceutil/trace.go:171","msg":"trace[195449422] transaction","detail":"{read_only:false; response_revision:24874; number_of_response:1; }","duration":"369.160187ms","start":"2026-08-08T06:40:56.935564Z","end":"2026-08-08T06:40:57.304724Z","steps":["trace[195449422] 'process raft request' (duration: 109.722089ms)","trace[195449422] 'compare' (duration: 258.890067ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T06:40:57.304899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:56.935537Z","time spent":"369.278129ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T06:40:57.304943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.597125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T06:40:57.305064Z","caller":"traceutil/trace.go:171","msg":"trace[400643026] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24874; }","duration":"287.745458ms","start":"2026-08-08T06:40:57.01727Z","end":"2026-08-08T06:40:57.305015Z","steps":["trace[400643026] 'agreement among raft nodes before linearized reading' (duration: 287.537173ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:57.594581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.165643ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-08T06:40:57.59466Z","caller":"traceutil/trace.go:171","msg":"trace[689706618] linearizableReadLoop","detail":"{readStateIndex:25680; appliedIndex:25679; }","duration":"289.86791ms","start":"2026-08-08T06:40:57.304779Z","end":"2026-08-08T06:40:57.594647Z","steps":["trace[689706618] 'read index received' (duration: 127.530613ms)","trace[689706618] 'applied index is now lower than readState.Index' (duration: 162.336477ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:40:57.594833Z","caller":"traceutil/trace.go:171","msg":"trace[1381964309] transaction","detail":"{read_only:false; response_revision:24875; number_of_response:1; }","duration":"549.335529ms","start":"2026-08-08T06:40:57.045478Z","end":"2026-08-08T06:40:57.594814Z","steps":["trace[1381964309] 'process raft request' (duration: 386.872149ms)","trace[1381964309] 'compare' (duration: 162.00798ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T06:40:57.594879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:57.045456Z","time spent":"549.40221ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T06:40:57.595036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.631423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-08T06:40:57.595014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.190511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-08T06:40:57.595134Z","caller":"traceutil/trace.go:171","msg":"trace[1846329179] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:24875; }","duration":"501.340854ms","start":"2026-08-08T06:40:57.093761Z","end":"2026-08-08T06:40:57.595102Z","steps":["trace[1846329179] 'agreement among raft nodes before linearized reading' (duration: 501.069859ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:57.59516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.750723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-08T06:40:57.595063Z","caller":"traceutil/trace.go:171","msg":"trace[1851484595] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:24875; }","duration":"506.706074ms","start":"2026-08-08T06:40:57.088349Z","end":"2026-08-08T06:40:57.595055Z","steps":["trace[1851484595] 'agreement among raft nodes before linearized reading' (duration: 506.653153ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:57.595191Z","caller":"traceutil/trace.go:171","msg":"trace[551617576] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24875; }","duration":"300.830575ms","start":"2026-08-08T06:40:57.294351Z","end":"2026-08-08T06:40:57.595182Z","steps":["trace[551617576] 'agreement among raft nodes before linearized reading' (duration: 300.715733ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:57.595195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:57.093688Z","time spent":"501.494088ms","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":15,"response size":31,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-08-08T06:40:57.595171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.453744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-08T06:40:57.595287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:57.088221Z","time spent":"506.997511ms","remote":"127.0.0.1:60064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true "} {"level":"info","ts":"2026-08-08T06:40:57.595314Z","caller":"traceutil/trace.go:171","msg":"trace[539344185] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:24875; }","duration":"353.637139ms","start":"2026-08-08T06:40:57.241635Z","end":"2026-08-08T06:40:57.595273Z","steps":["trace[539344185] 'agreement among raft nodes before linearized reading' (duration: 353.448784ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:57.595216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:57.294324Z","time spent":"300.886648ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-08T06:40:57.595373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:57.241608Z","time spent":"353.750571ms","remote":"127.0.0.1:52854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true "} {"level":"warn","ts":"2026-08-08T06:40:57.628656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.220831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-08T06:40:57.628683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.606932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T06:40:57.628708Z","caller":"traceutil/trace.go:171","msg":"trace[128771483] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24876; }","duration":"156.301603ms","start":"2026-08-08T06:40:57.472395Z","end":"2026-08-08T06:40:57.628696Z","steps":["trace[128771483] 'agreement among raft nodes before linearized reading' (duration: 156.14687ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:57.628721Z","caller":"traceutil/trace.go:171","msg":"trace[1077513633] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24876; }","duration":"322.683283ms","start":"2026-08-08T06:40:57.306024Z","end":"2026-08-08T06:40:57.628707Z","steps":["trace[1077513633] 'agreement among raft nodes before linearized reading' (duration: 322.626282ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:57.628715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.366488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-08-08T06:40:57.628745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:40:57.306001Z","time spent":"322.740394ms","remote":"127.0.0.1:40102","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-08-08T06:40:57.628764Z","caller":"traceutil/trace.go:171","msg":"trace[1513606864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24876; }","duration":"163.46244ms","start":"2026-08-08T06:40:57.465289Z","end":"2026-08-08T06:40:57.628751Z","steps":["trace[1513606864] 'agreement among raft nodes before linearized reading' (duration: 163.297527ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:57.628706Z","caller":"traceutil/trace.go:171","msg":"trace[2139104532] transaction","detail":"{read_only:false; response_revision:24876; number_of_response:1; }","duration":"171.372252ms","start":"2026-08-08T06:40:57.457282Z","end":"2026-08-08T06:40:57.628654Z","steps":["trace[2139104532] 'process raft request' (duration: 171.083976ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:57.628901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.657996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-08T06:40:57.628992Z","caller":"traceutil/trace.go:171","msg":"trace[951834568] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24876; }","duration":"145.784978ms","start":"2026-08-08T06:40:57.483187Z","end":"2026-08-08T06:40:57.628972Z","steps":["trace[951834568] 'agreement among raft nodes before linearized reading' (duration: 145.481701ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:40:57.629175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.295325ms","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-08-08T06:40:57.629275Z","caller":"traceutil/trace.go:171","msg":"trace[1860752403] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24876; }","duration":"159.482119ms","start":"2026-08-08T06:40:57.469762Z","end":"2026-08-08T06:40:57.629244Z","steps":["trace[1860752403] 'agreement among raft nodes before linearized reading' (duration: 159.089021ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:58.055097Z","caller":"traceutil/trace.go:171","msg":"trace[1324693983] transaction","detail":"{read_only:false; response_revision:24887; number_of_response:1; }","duration":"112.016946ms","start":"2026-08-08T06:40:57.943057Z","end":"2026-08-08T06:40:58.055074Z","steps":["trace[1324693983] 'process raft request' (duration: 64.159945ms)","trace[1324693983] 'compare' (duration: 47.611046ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:40:58.05598Z","caller":"traceutil/trace.go:171","msg":"trace[1767574593] transaction","detail":"{read_only:false; response_revision:24888; number_of_response:1; }","duration":"109.974174ms","start":"2026-08-08T06:40:57.945993Z","end":"2026-08-08T06:40:58.055967Z","steps":["trace[1767574593] 'process raft request' (duration: 109.888692ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:59.760008Z","caller":"traceutil/trace.go:171","msg":"trace[1178643290] transaction","detail":"{read_only:false; response_revision:24898; number_of_response:1; }","duration":"112.387783ms","start":"2026-08-08T06:40:59.647595Z","end":"2026-08-08T06:40:59.759983Z","steps":["trace[1178643290] 'process raft request' (duration: 112.294371ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:59.760292Z","caller":"traceutil/trace.go:171","msg":"trace[1888073189] transaction","detail":"{read_only:false; response_revision:24899; number_of_response:1; }","duration":"110.581466ms","start":"2026-08-08T06:40:59.649653Z","end":"2026-08-08T06:40:59.760234Z","steps":["trace[1888073189] 'process raft request' (duration: 110.27737ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:59.760345Z","caller":"traceutil/trace.go:171","msg":"trace[33636542] transaction","detail":"{read_only:false; response_revision:24897; number_of_response:1; }","duration":"112.779881ms","start":"2026-08-08T06:40:59.647554Z","end":"2026-08-08T06:40:59.760333Z","steps":["trace[33636542] 'process raft request' (duration: 112.1965ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:59.760515Z","caller":"traceutil/trace.go:171","msg":"trace[567560087] transaction","detail":"{read_only:false; response_revision:24900; number_of_response:1; }","duration":"110.852831ms","start":"2026-08-08T06:40:59.649654Z","end":"2026-08-08T06:40:59.760507Z","steps":["trace[567560087] 'process raft request' (duration: 110.513144ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:59.760723Z","caller":"traceutil/trace.go:171","msg":"trace[256571896] transaction","detail":"{read_only:false; response_revision:24901; number_of_response:1; }","duration":"110.656539ms","start":"2026-08-08T06:40:59.650048Z","end":"2026-08-08T06:40:59.760704Z","steps":["trace[256571896] 'process raft request' (duration: 110.431663ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:59.760861Z","caller":"traceutil/trace.go:171","msg":"trace[1370805598] transaction","detail":"{read_only:false; response_revision:24904; number_of_response:1; }","duration":"109.621658ms","start":"2026-08-08T06:40:59.651233Z","end":"2026-08-08T06:40:59.760855Z","steps":["trace[1370805598] 'process raft request' (duration: 109.466955ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:59.760884Z","caller":"traceutil/trace.go:171","msg":"trace[1608747109] transaction","detail":"{read_only:false; response_revision:24902; number_of_response:1; }","duration":"110.783451ms","start":"2026-08-08T06:40:59.650089Z","end":"2026-08-08T06:40:59.760873Z","steps":["trace[1608747109] 'process raft request' (duration: 110.521765ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:40:59.760909Z","caller":"traceutil/trace.go:171","msg":"trace[820529544] transaction","detail":"{read_only:false; response_revision:24903; number_of_response:1; }","duration":"110.545187ms","start":"2026-08-08T06:40:59.650345Z","end":"2026-08-08T06:40:59.76089Z","steps":["trace[820529544] 'process raft request' (duration: 110.318752ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:41:03.519717Z","caller":"traceutil/trace.go:171","msg":"trace[695804767] transaction","detail":"{read_only:false; response_revision:24927; number_of_response:1; }","duration":"106.176856ms","start":"2026-08-08T06:41:03.413509Z","end":"2026-08-08T06:41:03.519686Z","steps":["trace[695804767] 'process raft request' (duration: 15.007578ms)","trace[695804767] 'compare' (duration: 89.029725ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T06:41:03.745424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.982852ms","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-08-08T06:41:03.745507Z","caller":"traceutil/trace.go:171","msg":"trace[1303267426] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24927; }","duration":"125.094244ms","start":"2026-08-08T06:41:03.620393Z","end":"2026-08-08T06:41:03.745487Z","steps":["trace[1303267426] 'range keys from in-memory index tree' (duration: 124.833999ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:41:03.783729Z","caller":"traceutil/trace.go:171","msg":"trace[421046565] transaction","detail":"{read_only:false; response_revision:24928; number_of_response:1; }","duration":"134.352013ms","start":"2026-08-08T06:41:03.647481Z","end":"2026-08-08T06:41:03.781833Z","steps":["trace[421046565] 'process raft request' (duration: 134.138299ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:41:03.920697Z","caller":"traceutil/trace.go:171","msg":"trace[1328521353] transaction","detail":"{read_only:false; response_revision:24930; number_of_response:1; }","duration":"142.49208ms","start":"2026-08-08T06:41:03.778168Z","end":"2026-08-08T06:41:03.92066Z","steps":["trace[1328521353] 'process raft request' (duration: 142.405818ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:41:03.920996Z","caller":"traceutil/trace.go:171","msg":"trace[173336077] transaction","detail":"{read_only:false; response_revision:24929; number_of_response:1; }","duration":"147.551475ms","start":"2026-08-08T06:41:03.773429Z","end":"2026-08-08T06:41:03.920981Z","steps":["trace[173336077] 'process raft request' (duration: 90.423683ms)","trace[173336077] 'compare' (duration: 56.577799ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:41:03.920967Z","caller":"traceutil/trace.go:171","msg":"trace[286068027] linearizableReadLoop","detail":"{readStateIndex:25739; appliedIndex:25736; }","duration":"138.952098ms","start":"2026-08-08T06:41:03.781969Z","end":"2026-08-08T06:41:03.920921Z","steps":["trace[286068027] 'read index received' (duration: 81.99725ms)","trace[286068027] 'applied index is now lower than readState.Index' (duration: 56.952928ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T06:41:03.921291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.540851ms","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":"warn","ts":"2026-08-08T06:41:03.921287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.580422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-08T06:41:03.921351Z","caller":"traceutil/trace.go:171","msg":"trace[1702995617] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24931; }","duration":"141.633974ms","start":"2026-08-08T06:41:03.7797Z","end":"2026-08-08T06:41:03.921334Z","steps":["trace[1702995617] 'agreement among raft nodes before linearized reading' (duration: 141.418159ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:41:03.921372Z","caller":"traceutil/trace.go:171","msg":"trace[1275527712] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24931; }","duration":"141.695065ms","start":"2026-08-08T06:41:03.779657Z","end":"2026-08-08T06:41:03.921352Z","steps":["trace[1275527712] 'agreement among raft nodes before linearized reading' (duration: 141.43795ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:41:03.921329Z","caller":"traceutil/trace.go:171","msg":"trace[1816128783] transaction","detail":"{read_only:false; response_revision:24931; number_of_response:1; }","duration":"143.083592ms","start":"2026-08-08T06:41:03.778182Z","end":"2026-08-08T06:41:03.921266Z","steps":["trace[1816128783] 'process raft request' (duration: 142.437908ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:41:03.92149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.327333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18230"} {"level":"info","ts":"2026-08-08T06:41:03.921521Z","caller":"traceutil/trace.go:171","msg":"trace[1978378664] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24931; }","duration":"174.381113ms","start":"2026-08-08T06:41:03.747133Z","end":"2026-08-08T06:41:03.921514Z","steps":["trace[1978378664] 'agreement among raft nodes before linearized reading' (duration: 173.958215ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:41:03.921518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.761896ms","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-08-08T06:41:03.921589Z","caller":"traceutil/trace.go:171","msg":"trace[996204615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24931; }","duration":"141.863618ms","start":"2026-08-08T06:41:03.77971Z","end":"2026-08-08T06:41:03.921574Z","steps":["trace[996204615] 'agreement among raft nodes before linearized reading' (duration: 141.373739ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:41:03.935212Z","caller":"traceutil/trace.go:171","msg":"trace[1418421758] transaction","detail":"{read_only:false; response_revision:24932; number_of_response:1; }","duration":"152.410475ms","start":"2026-08-08T06:41:03.782781Z","end":"2026-08-08T06:41:03.935192Z","steps":["trace[1418421758] 'process raft request' (duration: 152.141499ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:41:03.935273Z","caller":"traceutil/trace.go:171","msg":"trace[920519999] transaction","detail":"{read_only:false; response_revision:24933; number_of_response:1; }","duration":"150.650538ms","start":"2026-08-08T06:41:03.784604Z","end":"2026-08-08T06:41:03.935255Z","steps":["trace[920519999] 'process raft request' (duration: 150.477085ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:41:03.935399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.978785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-08T06:41:03.93547Z","caller":"traceutil/trace.go:171","msg":"trace[1816742940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24933; }","duration":"151.062826ms","start":"2026-08-08T06:41:03.784386Z","end":"2026-08-08T06:41:03.935449Z","steps":["trace[1816742940] 'agreement among raft nodes before linearized reading' (duration: 150.896063ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:41:18.073877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.893778ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-08T06:41:18.074078Z","caller":"traceutil/trace.go:171","msg":"trace[1159833937] linearizableReadLoop","detail":"{readStateIndex:25852; appliedIndex:25850; }","duration":"213.508136ms","start":"2026-08-08T06:41:17.860554Z","end":"2026-08-08T06:41:18.074062Z","steps":["trace[1159833937] 'read index received' (duration: 31.343462ms)","trace[1159833937] 'applied index is now lower than readState.Index' (duration: 182.163814ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:41:18.074076Z","caller":"traceutil/trace.go:171","msg":"trace[1980429752] transaction","detail":"{read_only:false; response_revision:25040; number_of_response:1; }","duration":"236.59349ms","start":"2026-08-08T06:41:17.83745Z","end":"2026-08-08T06:41:18.074044Z","steps":["trace[1980429752] 'process raft request' (duration: 54.455517ms)","trace[1980429752] 'compare' (duration: 181.723834ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:41:18.074112Z","caller":"traceutil/trace.go:171","msg":"trace[837812403] transaction","detail":"{read_only:false; response_revision:25041; number_of_response:1; }","duration":"222.844977ms","start":"2026-08-08T06:41:17.851239Z","end":"2026-08-08T06:41:18.074084Z","steps":["trace[837812403] 'process raft request' (duration: 222.728055ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:41:18.074239Z","caller":"traceutil/trace.go:171","msg":"trace[1565185063] transaction","detail":"{read_only:false; response_revision:25042; number_of_response:1; }","duration":"104.658864ms","start":"2026-08-08T06:41:17.969572Z","end":"2026-08-08T06:41:18.07423Z","steps":["trace[1565185063] 'process raft request' (duration: 104.45546ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:41:18.074279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.70656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T06:41:18.074318Z","caller":"traceutil/trace.go:171","msg":"trace[312760393] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumetypes/; range_end:/registry/openstack.k-orc.cloud/volumetypes0; response_count:0; response_revision:25042; }","duration":"213.774051ms","start":"2026-08-08T06:41:17.860532Z","end":"2026-08-08T06:41:18.074306Z","steps":["trace[312760393] 'agreement among raft nodes before linearized reading' (duration: 213.628438ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:41:18.466665Z","caller":"traceutil/trace.go:171","msg":"trace[53195148] transaction","detail":"{read_only:false; response_revision:25052; number_of_response:1; }","duration":"139.479999ms","start":"2026-08-08T06:41:18.327162Z","end":"2026-08-08T06:41:18.466642Z","steps":["trace[53195148] 'process raft request' (duration: 139.354916ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:41:18.716411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.107282ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-08T06:41:18.716808Z","caller":"traceutil/trace.go:171","msg":"trace[649355733] transaction","detail":"{read_only:false; response_revision:25053; number_of_response:1; }","duration":"178.273444ms","start":"2026-08-08T06:41:18.538515Z","end":"2026-08-08T06:41:18.716788Z","steps":["trace[649355733] 'process raft request' (duration: 38.706743ms)","trace[649355733] 'compare' (duration: 138.865857ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:41:30.75916Z","caller":"traceutil/trace.go:171","msg":"trace[1966944259] linearizableReadLoop","detail":"{readStateIndex:25961; appliedIndex:25960; }","duration":"106.509113ms","start":"2026-08-08T06:41:30.65263Z","end":"2026-08-08T06:41:30.759139Z","steps":["trace[1966944259] 'read index received' (duration: 106.220448ms)","trace[1966944259] 'applied index is now lower than readState.Index' (duration: 287.745µs)"],"step_count":2} {"level":"warn","ts":"2026-08-08T06:41:30.759283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.642656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T06:41:30.759314Z","caller":"traceutil/trace.go:171","msg":"trace[1870351989] transaction","detail":"{read_only:false; response_revision:25148; number_of_response:1; }","duration":"130.258389ms","start":"2026-08-08T06:41:30.629003Z","end":"2026-08-08T06:41:30.759261Z","steps":["trace[1870351989] 'process raft request' (duration: 129.988373ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:41:30.759369Z","caller":"traceutil/trace.go:171","msg":"trace[1256442836] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:25148; }","duration":"106.698227ms","start":"2026-08-08T06:41:30.652608Z","end":"2026-08-08T06:41:30.759306Z","steps":["trace[1256442836] 'agreement among raft nodes before linearized reading' (duration: 106.619715ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:41:31.014515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.845562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-08T06:41:31.014659Z","caller":"traceutil/trace.go:171","msg":"trace[191093660] linearizableReadLoop","detail":"{readStateIndex:25962; appliedIndex:25961; }","duration":"250.714618ms","start":"2026-08-08T06:41:30.76392Z","end":"2026-08-08T06:41:31.014635Z","steps":["trace[191093660] 'read index received' (duration: 118.907966ms)","trace[191093660] 'applied index is now lower than readState.Index' (duration: 131.805002ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T06:41:31.014873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.956163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:208"} {"level":"info","ts":"2026-08-08T06:41:31.014916Z","caller":"traceutil/trace.go:171","msg":"trace[477658708] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:25149; }","duration":"251.039324ms","start":"2026-08-08T06:41:30.763862Z","end":"2026-08-08T06:41:31.014902Z","steps":["trace[477658708] 'agreement among raft nodes before linearized reading' (duration: 250.83343ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:41:31.015265Z","caller":"traceutil/trace.go:171","msg":"trace[1668553285] transaction","detail":"{read_only:false; response_revision:25149; number_of_response:1; }","duration":"316.246141ms","start":"2026-08-08T06:41:30.699005Z","end":"2026-08-08T06:41:31.015251Z","steps":["trace[1668553285] 'process raft request' (duration: 183.544782ms)","trace[1668553285] 'compare' (duration: 131.578787ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T06:41:31.015342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T06:41:30.698989Z","time spent":"316.317852ms","remote":"127.0.0.1:40386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T06:41:31.035424Z","caller":"traceutil/trace.go:171","msg":"trace[1291821736] transaction","detail":"{read_only:false; response_revision:25151; number_of_response:1; }","duration":"268.911351ms","start":"2026-08-08T06:41:30.766475Z","end":"2026-08-08T06:41:31.035387Z","steps":["trace[1291821736] 'process raft request' (duration: 268.695767ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:41:31.035411Z","caller":"traceutil/trace.go:171","msg":"trace[1925481339] transaction","detail":"{read_only:false; response_revision:25150; number_of_response:1; }","duration":"270.968834ms","start":"2026-08-08T06:41:30.764385Z","end":"2026-08-08T06:41:31.035354Z","steps":["trace[1925481339] 'process raft request' (duration: 270.652546ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:41:31.323241Z","caller":"traceutil/trace.go:171","msg":"trace[1931536903] transaction","detail":"{read_only:false; response_revision:25152; number_of_response:1; }","duration":"278.538229ms","start":"2026-08-08T06:41:31.044661Z","end":"2026-08-08T06:41:31.323199Z","steps":["trace[1931536903] 'process raft request' (duration: 278.262053ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:41:31.334884Z","caller":"traceutil/trace.go:171","msg":"trace[402347172] linearizableReadLoop","detail":"{readStateIndex:25966; appliedIndex:25964; }","duration":"229.199637ms","start":"2026-08-08T06:41:31.105659Z","end":"2026-08-08T06:41:31.334859Z","steps":["trace[402347172] 'read index received' (duration: 217.14677ms)","trace[402347172] 'applied index is now lower than readState.Index' (duration: 12.051747ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:41:31.335219Z","caller":"traceutil/trace.go:171","msg":"trace[929011513] transaction","detail":"{read_only:false; response_revision:25153; number_of_response:1; }","duration":"282.361538ms","start":"2026-08-08T06:41:31.052841Z","end":"2026-08-08T06:41:31.335203Z","steps":["trace[929011513] 'process raft request' (duration: 281.883477ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:41:31.335488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.809431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-08T06:41:31.335527Z","caller":"traceutil/trace.go:171","msg":"trace[872609834] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:25153; }","duration":"229.907322ms","start":"2026-08-08T06:41:31.105608Z","end":"2026-08-08T06:41:31.335515Z","steps":["trace[872609834] 'agreement among raft nodes before linearized reading' (duration: 229.8224ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:41:31.335763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.28827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-08T06:41:31.335792Z","caller":"traceutil/trace.go:171","msg":"trace[971488512] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:25153; }","duration":"208.32062ms","start":"2026-08-08T06:41:31.127465Z","end":"2026-08-08T06:41:31.335785Z","steps":["trace[971488512] 'agreement among raft nodes before linearized reading' (duration: 208.234189ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:41:31.553627Z","caller":"traceutil/trace.go:171","msg":"trace[1341138653] linearizableReadLoop","detail":"{readStateIndex:25967; appliedIndex:25966; }","duration":"130.178098ms","start":"2026-08-08T06:41:31.423381Z","end":"2026-08-08T06:41:31.553559Z","steps":["trace[1341138653] 'read index received' (duration: 102.823197ms)","trace[1341138653] 'applied index is now lower than readState.Index' (duration: 27.352371ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T06:41:31.55368Z","caller":"traceutil/trace.go:171","msg":"trace[1416676816] transaction","detail":"{read_only:false; response_revision:25154; number_of_response:1; }","duration":"206.320419ms","start":"2026-08-08T06:41:31.347296Z","end":"2026-08-08T06:41:31.553617Z","steps":["trace[1416676816] 'process raft request' (duration: 178.915507ms)","trace[1416676816] 'compare' (duration: 27.056014ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T06:41:31.553892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.461485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-08T06:41:31.553948Z","caller":"traceutil/trace.go:171","msg":"trace[18531850] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:25154; }","duration":"130.597817ms","start":"2026-08-08T06:41:31.423333Z","end":"2026-08-08T06:41:31.553931Z","steps":["trace[18531850] 'agreement among raft nodes before linearized reading' (duration: 130.430783ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:41:35.732393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.802485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T06:41:35.732482Z","caller":"traceutil/trace.go:171","msg":"trace[736879889] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:25184; }","duration":"120.959549ms","start":"2026-08-08T06:41:35.611501Z","end":"2026-08-08T06:41:35.732461Z","steps":["trace[736879889] 'count revisions from in-memory index tree' (duration: 120.703654ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T06:41:35.732395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.457752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-14232817-87d1-424e-80c2-4a66bfb9bc7c\" ","response":"range_response_count:1 size:1320"} {"level":"info","ts":"2026-08-08T06:41:35.732607Z","caller":"traceutil/trace.go:171","msg":"trace[1797031563] range","detail":"{range_begin:/registry/persistentvolumes/pvc-14232817-87d1-424e-80c2-4a66bfb9bc7c; range_end:; response_count:1; response_revision:25184; }","duration":"145.705187ms","start":"2026-08-08T06:41:35.586867Z","end":"2026-08-08T06:41:35.732572Z","steps":["trace[1797031563] 'range keys from in-memory index tree' (duration: 145.203967ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:41:41.356656Z","caller":"traceutil/trace.go:171","msg":"trace[785775898] transaction","detail":"{read_only:false; response_revision:25225; number_of_response:1; }","duration":"126.35534ms","start":"2026-08-08T06:41:41.230258Z","end":"2026-08-08T06:41:41.356614Z","steps":["trace[785775898] 'process raft request' (duration: 126.009933ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:41:41.670844Z","caller":"traceutil/trace.go:171","msg":"trace[1130395066] transaction","detail":"{read_only:false; response_revision:25228; number_of_response:1; }","duration":"141.106672ms","start":"2026-08-08T06:41:41.529711Z","end":"2026-08-08T06:41:41.670818Z","steps":["trace[1130395066] 'process raft request' (duration: 140.931868ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:41:41.915115Z","caller":"traceutil/trace.go:171","msg":"trace[1865378519] transaction","detail":"{read_only:false; response_revision:25229; number_of_response:1; }","duration":"296.791383ms","start":"2026-08-08T06:41:41.618298Z","end":"2026-08-08T06:41:41.915089Z","steps":["trace[1865378519] 'process raft request' (duration: 296.457286ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T06:41:58.235113Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000668b.wal"} {"level":"info","ts":"2026-08-08T06:43:04.772685Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23197} {"level":"info","ts":"2026-08-08T06:43:04.904081Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23197,"took":"126.265628ms","hash":3809059670,"current-db-size-bytes":58441728,"current-db-size":"58 MB","current-db-size-in-use-bytes":36294656,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-08-08T06:43:04.904141Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3809059670,"revision":23197,"compact-revision":20036} {"level":"info","ts":"2026-08-08T06:48:04.779122Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26072} {"level":"info","ts":"2026-08-08T06:48:04.916003Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26072,"took":"134.044508ms","hash":3163417119,"current-db-size-bytes":58441728,"current-db-size":"58 MB","current-db-size-in-use-bytes":33792000,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-08T06:48:04.916077Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3163417119,"revision":26072,"compact-revision":23197} {"level":"info","ts":"2026-08-08T06:48:56.582566Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a31e123290fcf0df","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-08T06:48:56.586838Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-08T06:48:56.58692Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-08T06:53:04.785271Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28703} {"level":"info","ts":"2026-08-08T06:53:04.919749Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28703,"took":"130.960115ms","hash":2140154619,"current-db-size-bytes":58441728,"current-db-size":"58 MB","current-db-size-in-use-bytes":30343168,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-08T06:53:04.919831Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2140154619,"revision":28703,"compact-revision":26072} {"level":"info","ts":"2026-08-08T06:58:04.797483Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30921} {"level":"info","ts":"2026-08-08T06:58:04.909131Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30921,"took":"107.569687ms","hash":1595583247,"current-db-size-bytes":58441728,"current-db-size":"58 MB","current-db-size-in-use-bytes":31219712,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-08T06:58:04.909219Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1595583247,"revision":30921,"compact-revision":28703} {"level":"info","ts":"2026-08-08T07:03:04.802931Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33183} {"level":"info","ts":"2026-08-08T07:03:04.954366Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33183,"took":"147.285589ms","hash":743745346,"current-db-size-bytes":59486208,"current-db-size":"60 MB","current-db-size-in-use-bytes":32243712,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-08T07:03:04.954455Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":743745346,"revision":33183,"compact-revision":30921} {"level":"info","ts":"2026-08-08T07:08:04.808589Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35541} {"level":"info","ts":"2026-08-08T07:08:04.949501Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35541,"took":"137.644272ms","hash":1053533948,"current-db-size-bytes":59957248,"current-db-size":"60 MB","current-db-size-in-use-bytes":32301056,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-08T07:08:04.94957Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1053533948,"revision":35541,"compact-revision":33183} {"level":"info","ts":"2026-08-08T07:10:09.685742Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a31e123290fcf0df","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-08T07:10:09.690559Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-08-08T07:10:09.690643Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}