{"level":"warn","ts":"2026-07-02T05:10:06.980043Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T05:10:06.980158Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.57: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.57:2380","--initial-cluster=instance=https://199.204.45.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.57:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-02T05:10:06.980231Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T05:10:06.980247Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-07-02T05:10:06.980299Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-02T05:10:06.980983Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"]} {"level":"info","ts":"2026-07-02T05:10:06.981082Z","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.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.57:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-02T05:10:06.984893Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.596848ms"} {"level":"info","ts":"2026-07-02T05:10:06.990544Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6478f0ac5aeb8e1","cluster-id":"6c58cff80d4a5b62"} {"level":"info","ts":"2026-07-02T05:10:06.990609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=()"} {"level":"info","ts":"2026-07-02T05:10:06.990628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 0"} {"level":"info","ts":"2026-07-02T05:10:06.99064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b6478f0ac5aeb8e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-02T05:10:06.99065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 1"} {"level":"info","ts":"2026-07-02T05:10:06.990687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"warn","ts":"2026-07-02T05:10:06.996627Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-02T05:10:06.998252Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-02T05:10:06.999414Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-02T05:10:07.001191Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b6478f0ac5aeb8e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-02T05:10:07.001567Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b6478f0ac5aeb8e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-02T05:10:07.001918Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-02T05:10:07.002144Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-02T05:10:07.002177Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-02T05:10:07.005375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"info","ts":"2026-07-02T05:10:07.005549Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","added-peer-id":"b6478f0ac5aeb8e1","added-peer-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-07-02T05:10:07.006124Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-02T05:10:07.006387Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b6478f0ac5aeb8e1","initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-02T05:10:07.006427Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-02T05:10:07.006604Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-07-02T05:10:07.006624Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-07-02T05:10:07.891046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 is starting a new election at term 1"} {"level":"info","ts":"2026-07-02T05:10:07.891133Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-02T05:10:07.891152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgPreVoteResp from b6478f0ac5aeb8e1 at term 1"} {"level":"info","ts":"2026-07-02T05:10:07.891169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became candidate at term 2"} {"level":"info","ts":"2026-07-02T05:10:07.891178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgVoteResp from b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-07-02T05:10:07.891189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became leader at term 2"} {"level":"info","ts":"2026-07-02T05:10:07.891204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6478f0ac5aeb8e1 elected leader b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-07-02T05:10:07.892258Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b6478f0ac5aeb8e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.57:2379]}","request-path":"/0/members/b6478f0ac5aeb8e1/attributes","cluster-id":"6c58cff80d4a5b62","publish-timeout":"7s"} {"level":"info","ts":"2026-07-02T05:10:07.892312Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:10:07.892495Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T05:10:07.892593Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T05:10:07.89282Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-02T05:10:07.89291Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-02T05:10:07.89349Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:10:07.893648Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:10:07.893689Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:10:07.894375Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.57:2379"} {"level":"info","ts":"2026-07-02T05:10:07.895886Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-02T05:10:31.336683Z","caller":"traceutil/trace.go:171","msg":"trace[1337883581] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"195.696029ms","start":"2026-07-02T05:10:31.14096Z","end":"2026-07-02T05:10:31.336656Z","steps":["trace[1337883581] 'process raft request' (duration: 148.795165ms)","trace[1337883581] 'compare' (duration: 46.68801ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:10:31.336843Z","caller":"traceutil/trace.go:171","msg":"trace[491747064] linearizableReadLoop","detail":"{readStateIndex:418; appliedIndex:417; }","duration":"173.963755ms","start":"2026-07-02T05:10:31.162843Z","end":"2026-07-02T05:10:31.336806Z","steps":["trace[491747064] 'read index received' (duration: 126.9925ms)","trace[491747064] 'applied index is now lower than readState.Index' (duration: 46.968165ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:10:31.337022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.535838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-07-02T05:10:31.337104Z","caller":"traceutil/trace.go:171","msg":"trace[1961017047] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:406; }","duration":"194.650679ms","start":"2026-07-02T05:10:31.142438Z","end":"2026-07-02T05:10:31.337088Z","steps":["trace[1961017047] 'agreement among raft nodes before linearized reading' (duration: 194.465176ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:10:31.337083Z","caller":"traceutil/trace.go:171","msg":"trace[2035334243] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"172.970757ms","start":"2026-07-02T05:10:31.1641Z","end":"2026-07-02T05:10:31.337071Z","steps":["trace[2035334243] 'process raft request' (duration: 172.508058ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:10:44.834863Z","caller":"traceutil/trace.go:171","msg":"trace[384936877] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"125.349208ms","start":"2026-07-02T05:10:44.70947Z","end":"2026-07-02T05:10:44.83482Z","steps":["trace[384936877] 'process raft request' (duration: 125.123784ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:10:51.140761Z","caller":"traceutil/trace.go:171","msg":"trace[64096171] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"281.042959ms","start":"2026-07-02T05:10:50.859663Z","end":"2026-07-02T05:10:51.140705Z","steps":["trace[64096171] 'read index received' (duration: 192.406023ms)","trace[64096171] 'applied index is now lower than readState.Index' (duration: 88.636075ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:10:51.141051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.910331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:10:51.141097Z","caller":"traceutil/trace.go:171","msg":"trace[1345723110] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:584; }","duration":"262.014623ms","start":"2026-07-02T05:10:50.879068Z","end":"2026-07-02T05:10:51.141082Z","steps":["trace[1345723110] 'agreement among raft nodes before linearized reading' (duration: 261.933682ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:10:51.141273Z","caller":"traceutil/trace.go:171","msg":"trace[2018016165] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"310.719145ms","start":"2026-07-02T05:10:50.830522Z","end":"2026-07-02T05:10:51.141241Z","steps":["trace[2018016165] 'process raft request' (duration: 221.57864ms)","trace[2018016165] 'compare' (duration: 88.498644ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:10:51.14143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.028027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-07-02T05:10:51.141457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.707001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-02T05:10:51.141504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.726619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-02T05:10:51.141627Z","caller":"traceutil/trace.go:171","msg":"trace[1991834596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:584; }","duration":"179.881763ms","start":"2026-07-02T05:10:50.961718Z","end":"2026-07-02T05:10:51.1416Z","steps":["trace[1991834596] 'agreement among raft nodes before linearized reading' (duration: 179.560737ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:10:51.141529Z","caller":"traceutil/trace.go:171","msg":"trace[996870763] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:584; }","duration":"224.162859ms","start":"2026-07-02T05:10:50.917341Z","end":"2026-07-02T05:10:51.141504Z","steps":["trace[996870763] 'agreement among raft nodes before linearized reading' (duration: 223.943705ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:51.141791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:50.830499Z","time spent":"310.825678ms","remote":"127.0.0.1:44634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:10:51.142348Z","caller":"traceutil/trace.go:171","msg":"trace[1359283302] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:584; }","duration":"281.954046ms","start":"2026-07-02T05:10:50.859622Z","end":"2026-07-02T05:10:51.141576Z","steps":["trace[1359283302] 'agreement among raft nodes before linearized reading' (duration: 281.332384ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:10:51.654839Z","caller":"traceutil/trace.go:171","msg":"trace[1388867364] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"112.150436ms","start":"2026-07-02T05:10:51.542605Z","end":"2026-07-02T05:10:51.654756Z","steps":["trace[1388867364] 'process raft request' (duration: 109.147179ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:11:05.898488Z","caller":"traceutil/trace.go:171","msg":"trace[96474918] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"145.086446ms","start":"2026-07-02T05:11:05.753385Z","end":"2026-07-02T05:11:05.898471Z","steps":["trace[96474918] 'process raft request' (duration: 144.842682ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:10.51382Z","caller":"traceutil/trace.go:171","msg":"trace[213621917] linearizableReadLoop","detail":"{readStateIndex:2201; appliedIndex:2200; }","duration":"101.117918ms","start":"2026-07-02T05:13:10.412666Z","end":"2026-07-02T05:13:10.513784Z","steps":["trace[213621917] 'read index received' (duration: 100.822122ms)","trace[213621917] 'applied index is now lower than readState.Index' (duration: 294.006µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:13:10.513926Z","caller":"traceutil/trace.go:171","msg":"trace[853027707] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"101.285781ms","start":"2026-07-02T05:13:10.4126Z","end":"2026-07-02T05:13:10.513886Z","steps":["trace[853027707] 'process raft request' (duration: 100.965005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:10.514183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.481785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-07-02T05:13:10.514242Z","caller":"traceutil/trace.go:171","msg":"trace[1116737738] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2125; }","duration":"101.581887ms","start":"2026-07-02T05:13:10.412639Z","end":"2026-07-02T05:13:10.514221Z","steps":["trace[1116737738] 'agreement among raft nodes before linearized reading' (duration: 101.314492ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:14:05.192499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.51635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"info","ts":"2026-07-02T05:14:05.192598Z","caller":"traceutil/trace.go:171","msg":"trace[1583513847] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2593; }","duration":"162.656222ms","start":"2026-07-02T05:14:05.029918Z","end":"2026-07-02T05:14:05.192575Z","steps":["trace[1583513847] 'range keys from in-memory index tree' (duration: 162.305356ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:14:05.192672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.214456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-07-02T05:14:05.192764Z","caller":"traceutil/trace.go:171","msg":"trace[1826522796] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2593; }","duration":"160.380249ms","start":"2026-07-02T05:14:05.032366Z","end":"2026-07-02T05:14:05.192746Z","steps":["trace[1826522796] 'range keys from in-memory index tree' (duration: 159.950481ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:14:38.433718Z","caller":"traceutil/trace.go:171","msg":"trace[807204229] transaction","detail":"{read_only:false; response_revision:2794; number_of_response:1; }","duration":"106.579033ms","start":"2026-07-02T05:14:38.327108Z","end":"2026-07-02T05:14:38.433687Z","steps":["trace[807204229] 'process raft request' (duration: 90.119662ms)","trace[807204229] 'compare' (duration: 16.203786ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:14:38.433985Z","caller":"traceutil/trace.go:171","msg":"trace[296402096] transaction","detail":"{read_only:false; response_revision:2795; number_of_response:1; }","duration":"105.550803ms","start":"2026-07-02T05:14:38.328371Z","end":"2026-07-02T05:14:38.433922Z","steps":["trace[296402096] 'process raft request' (duration: 105.246708ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:17:14.376548Z","caller":"traceutil/trace.go:171","msg":"trace[1437313227] transaction","detail":"{read_only:false; response_revision:3820; number_of_response:1; }","duration":"145.8018ms","start":"2026-07-02T05:17:14.230716Z","end":"2026-07-02T05:17:14.376518Z","steps":["trace[1437313227] 'process raft request' (duration: 128.761238ms)","trace[1437313227] 'compare' (duration: 16.885219ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:18:32.62535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.062964ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:18:32.625879Z","caller":"traceutil/trace.go:171","msg":"trace[859819242] transaction","detail":"{read_only:false; response_revision:4839; number_of_response:1; }","duration":"135.768132ms","start":"2026-07-02T05:18:32.489982Z","end":"2026-07-02T05:18:32.62575Z","steps":["trace[859819242] 'process raft request' (duration: 31.076786ms)","trace[859819242] 'compare' (duration: 103.918251ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:18:38.187194Z","caller":"traceutil/trace.go:171","msg":"trace[183258791] transaction","detail":"{read_only:false; response_revision:5010; number_of_response:1; }","duration":"213.247803ms","start":"2026-07-02T05:18:37.973912Z","end":"2026-07-02T05:18:38.18716Z","steps":["trace[183258791] 'process raft request' (duration: 173.474093ms)","trace[183258791] 'compare' (duration: 39.543636ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:18:38.187303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.281912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T05:18:38.18719Z","caller":"traceutil/trace.go:171","msg":"trace[1565295778] linearizableReadLoop","detail":"{readStateIndex:5195; appliedIndex:5194; }","duration":"198.16684ms","start":"2026-07-02T05:18:37.988991Z","end":"2026-07-02T05:18:38.187158Z","steps":["trace[1565295778] 'read index received' (duration: 158.39271ms)","trace[1565295778] 'applied index is now lower than readState.Index' (duration: 39.77294ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:18:38.187368Z","caller":"traceutil/trace.go:171","msg":"trace[220186127] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:5010; }","duration":"198.419455ms","start":"2026-07-02T05:18:37.988938Z","end":"2026-07-02T05:18:38.187357Z","steps":["trace[220186127] 'agreement among raft nodes before linearized reading' (duration: 198.281583ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:18:38.187596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.884729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9e858417-c456-4e4e-ad99-93bbaa25e806\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-07-02T05:18:38.187655Z","caller":"traceutil/trace.go:171","msg":"trace[1547611440] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9e858417-c456-4e4e-ad99-93bbaa25e806; range_end:; response_count:1; response_revision:5010; }","duration":"148.982741ms","start":"2026-07-02T05:18:38.038652Z","end":"2026-07-02T05:18:38.187635Z","steps":["trace[1547611440] 'agreement among raft nodes before linearized reading' (duration: 148.782867ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:18:38.474885Z","caller":"traceutil/trace.go:171","msg":"trace[2046591978] transaction","detail":"{read_only:false; response_revision:5011; number_of_response:1; }","duration":"119.444194ms","start":"2026-07-02T05:18:38.355401Z","end":"2026-07-02T05:18:38.474845Z","steps":["trace[2046591978] 'process raft request' (duration: 119.296901ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:18:38.924232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.392274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:18:38.924384Z","caller":"traceutil/trace.go:171","msg":"trace[1741601251] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:5016; }","duration":"118.588758ms","start":"2026-07-02T05:18:38.805766Z","end":"2026-07-02T05:18:38.924355Z","steps":["trace[1741601251] 'count revisions from in-memory index tree' (duration: 118.286822ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:18:38.924861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.632358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:786853"} {"level":"warn","ts":"2026-07-02T05:18:38.925171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.310577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-02T05:18:38.925268Z","caller":"traceutil/trace.go:171","msg":"trace[1430408968] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5016; }","duration":"194.46393ms","start":"2026-07-02T05:18:38.730781Z","end":"2026-07-02T05:18:38.925245Z","steps":["trace[1430408968] 'range keys from in-memory index tree' (duration: 193.42141ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:18:38.925192Z","caller":"traceutil/trace.go:171","msg":"trace[231105595] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5016; }","duration":"269.995195ms","start":"2026-07-02T05:18:38.655174Z","end":"2026-07-02T05:18:38.925169Z","steps":["trace[231105595] 'range keys from in-memory index tree' (duration: 268.955495ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:18:39.086872Z","caller":"traceutil/trace.go:171","msg":"trace[2055582958] transaction","detail":"{read_only:false; response_revision:5019; number_of_response:1; }","duration":"103.145077ms","start":"2026-07-02T05:18:38.983703Z","end":"2026-07-02T05:18:39.086848Z","steps":["trace[2055582958] 'process raft request' (duration: 44.188663ms)","trace[2055582958] 'compare' (duration: 58.857751ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:18:49.796226Z","caller":"traceutil/trace.go:171","msg":"trace[150190936] transaction","detail":"{read_only:false; response_revision:5237; number_of_response:1; }","duration":"109.907284ms","start":"2026-07-02T05:18:49.686276Z","end":"2026-07-02T05:18:49.796184Z","steps":["trace[150190936] 'process raft request' (duration: 45.5909ms)","trace[150190936] 'compare' (duration: 63.923247ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:18:50.506372Z","caller":"traceutil/trace.go:171","msg":"trace[426692551] transaction","detail":"{read_only:false; response_revision:5241; number_of_response:1; }","duration":"281.317009ms","start":"2026-07-02T05:18:50.225035Z","end":"2026-07-02T05:18:50.506352Z","steps":["trace[426692551] 'process raft request' (duration: 281.028483ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:18:57.828593Z","caller":"traceutil/trace.go:171","msg":"trace[1262133350] transaction","detail":"{read_only:false; response_revision:5402; number_of_response:1; }","duration":"171.72006ms","start":"2026-07-02T05:18:57.656834Z","end":"2026-07-02T05:18:57.828554Z","steps":["trace[1262133350] 'process raft request' (duration: 171.514796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:18:58.059115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.952751ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:18:58.05934Z","caller":"traceutil/trace.go:171","msg":"trace[1356828379] transaction","detail":"{read_only:false; response_revision:5404; number_of_response:1; }","duration":"205.235694ms","start":"2026-07-02T05:18:57.854086Z","end":"2026-07-02T05:18:58.059322Z","steps":["trace[1356828379] 'process raft request' (duration: 205.146063ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:18:58.059341Z","caller":"traceutil/trace.go:171","msg":"trace[741121057] transaction","detail":"{read_only:false; response_revision:5403; number_of_response:1; }","duration":"208.323702ms","start":"2026-07-02T05:18:57.850987Z","end":"2026-07-02T05:18:58.059311Z","steps":["trace[741121057] 'process raft request' (duration: 49.089216ms)","trace[741121057] 'compare' (duration: 158.769538ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:18:58.05975Z","caller":"traceutil/trace.go:171","msg":"trace[950898089] transaction","detail":"{read_only:false; response_revision:5405; number_of_response:1; }","duration":"205.416256ms","start":"2026-07-02T05:18:57.854272Z","end":"2026-07-02T05:18:58.059688Z","steps":["trace[950898089] 'process raft request' (duration: 205.015609ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:20:07.944557Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2957} {"level":"info","ts":"2026-07-02T05:20:08.01297Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2957,"took":"66.662544ms","hash":2698463357,"current-db-size-bytes":57470976,"current-db-size":"58 MB","current-db-size-in-use-bytes":34160640,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-02T05:20:08.013048Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2698463357,"revision":2957,"compact-revision":-1} {"level":"warn","ts":"2026-07-02T05:24:57.540157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.907881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:24:57.54029Z","caller":"traceutil/trace.go:171","msg":"trace[1592681269] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:8203; }","duration":"136.067265ms","start":"2026-07-02T05:24:57.404197Z","end":"2026-07-02T05:24:57.540264Z","steps":["trace[1592681269] 'count revisions from in-memory index tree' (duration: 135.79955ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:24:57.540254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.770143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T05:24:57.540346Z","caller":"traceutil/trace.go:171","msg":"trace[237062578] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8203; }","duration":"231.916405ms","start":"2026-07-02T05:24:57.308413Z","end":"2026-07-02T05:24:57.54033Z","steps":["trace[237062578] 'count revisions from in-memory index tree' (duration: 231.652061ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:24:59.064247Z","caller":"traceutil/trace.go:171","msg":"trace[429520215] transaction","detail":"{read_only:false; response_revision:8214; number_of_response:1; }","duration":"160.911437ms","start":"2026-07-02T05:24:58.903303Z","end":"2026-07-02T05:24:59.064215Z","steps":["trace[429520215] 'process raft request' (duration: 160.409668ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:25:07.951903Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6065} {"level":"info","ts":"2026-07-02T05:25:08.050863Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6065,"took":"96.843011ms","hash":2802715246,"current-db-size-bytes":57470976,"current-db-size":"58 MB","current-db-size-in-use-bytes":23363584,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-07-02T05:25:08.050934Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2802715246,"revision":6065,"compact-revision":2957} {"level":"info","ts":"2026-07-02T05:25:26.265556Z","caller":"traceutil/trace.go:171","msg":"trace[1025167243] linearizableReadLoop","detail":"{readStateIndex:8705; appliedIndex:8704; }","duration":"193.371193ms","start":"2026-07-02T05:25:26.072161Z","end":"2026-07-02T05:25:26.265533Z","steps":["trace[1025167243] 'read index received' (duration: 193.290631ms)","trace[1025167243] 'applied index is now lower than readState.Index' (duration: 79.602µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:25:26.265612Z","caller":"traceutil/trace.go:171","msg":"trace[338616875] transaction","detail":"{read_only:false; response_revision:8413; number_of_response:1; }","duration":"231.163591ms","start":"2026-07-02T05:25:26.034418Z","end":"2026-07-02T05:25:26.265581Z","steps":["trace[338616875] 'process raft request' (duration: 230.865235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:25:26.265779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.608697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-02T05:25:26.265814Z","caller":"traceutil/trace.go:171","msg":"trace[563042271] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8413; }","duration":"193.678439ms","start":"2026-07-02T05:25:26.072124Z","end":"2026-07-02T05:25:26.265803Z","steps":["trace[563042271] 'agreement among raft nodes before linearized reading' (duration: 193.550156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:25:26.491726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.973143ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-02T05:25:26.491953Z","caller":"traceutil/trace.go:171","msg":"trace[1735832746] linearizableReadLoop","detail":"{readStateIndex:8706; appliedIndex:8705; }","duration":"226.274667ms","start":"2026-07-02T05:25:26.265655Z","end":"2026-07-02T05:25:26.49193Z","steps":["trace[1735832746] 'read index received' (duration: 90.102011ms)","trace[1735832746] 'applied index is now lower than readState.Index' (duration: 136.170366ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:25:26.491999Z","caller":"traceutil/trace.go:171","msg":"trace[1311440585] transaction","detail":"{read_only:false; response_revision:8414; number_of_response:1; }","duration":"221.449227ms","start":"2026-07-02T05:25:26.270533Z","end":"2026-07-02T05:25:26.491982Z","steps":["trace[1311440585] 'process raft request' (duration: 221.323224ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:25:26.492361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.057627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T05:25:26.492401Z","caller":"traceutil/trace.go:171","msg":"trace[280317767] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8414; }","duration":"383.137058ms","start":"2026-07-02T05:25:26.109254Z","end":"2026-07-02T05:25:26.492391Z","steps":["trace[280317767] 'agreement among raft nodes before linearized reading' (duration: 382.786111ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:25:26.492429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:25:26.109244Z","time spent":"383.178888ms","remote":"127.0.0.1:34898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:25:26.492676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.822989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-02T05:25:26.4927Z","caller":"traceutil/trace.go:171","msg":"trace[193522720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8414; }","duration":"395.87528ms","start":"2026-07-02T05:25:26.09682Z","end":"2026-07-02T05:25:26.492695Z","steps":["trace[193522720] 'agreement among raft nodes before linearized reading' (duration: 395.763198ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:25:26.492717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:25:26.096804Z","time spent":"395.909461ms","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T05:25:26.49288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.069173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-02T05:25:26.492931Z","caller":"traceutil/trace.go:171","msg":"trace[141065498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8414; }","duration":"396.149744ms","start":"2026-07-02T05:25:26.096769Z","end":"2026-07-02T05:25:26.492919Z","steps":["trace[141065498] 'agreement among raft nodes before linearized reading' (duration: 396.018942ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:25:26.492979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:25:26.096758Z","time spent":"396.212116ms","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-02T05:25:38.195972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.05536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:25:38.196091Z","caller":"traceutil/trace.go:171","msg":"trace[370862955] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8517; }","duration":"245.264418ms","start":"2026-07-02T05:25:37.950814Z","end":"2026-07-02T05:25:38.196078Z","steps":["trace[370862955] 'process raft request' (duration: 114.992264ms)","trace[370862955] 'compare' (duration: 129.958209ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:25:38.210168Z","caller":"traceutil/trace.go:171","msg":"trace[1551781184] transaction","detail":"{read_only:false; response_revision:8518; number_of_response:1; }","duration":"258.137083ms","start":"2026-07-02T05:25:37.952014Z","end":"2026-07-02T05:25:38.210151Z","steps":["trace[1551781184] 'process raft request' (duration: 257.979531ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:25:38.210327Z","caller":"traceutil/trace.go:171","msg":"trace[1487118991] transaction","detail":"{read_only:false; response_revision:8519; number_of_response:1; }","duration":"196.360359ms","start":"2026-07-02T05:25:38.013935Z","end":"2026-07-02T05:25:38.210295Z","steps":["trace[1487118991] 'process raft request' (duration: 196.160255ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:25:38.806473Z","caller":"traceutil/trace.go:171","msg":"trace[1504439041] linearizableReadLoop","detail":"{readStateIndex:8819; appliedIndex:8817; }","duration":"236.824718ms","start":"2026-07-02T05:25:38.569627Z","end":"2026-07-02T05:25:38.806452Z","steps":["trace[1504439041] 'read index received' (duration: 89.179133ms)","trace[1504439041] 'applied index is now lower than readState.Index' (duration: 147.644635ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:25:38.806521Z","caller":"traceutil/trace.go:171","msg":"trace[1342451462] transaction","detail":"{read_only:false; response_revision:8521; number_of_response:1; }","duration":"237.407349ms","start":"2026-07-02T05:25:38.569074Z","end":"2026-07-02T05:25:38.806481Z","steps":["trace[1342451462] 'process raft request' (duration: 195.115246ms)","trace[1342451462] 'compare' (duration: 42.159581ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:25:38.806664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.017741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-02T05:25:38.8067Z","caller":"traceutil/trace.go:171","msg":"trace[882598274] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8521; }","duration":"237.079992ms","start":"2026-07-02T05:25:38.56961Z","end":"2026-07-02T05:25:38.80669Z","steps":["trace[882598274] 'agreement among raft nodes before linearized reading' (duration: 236.95506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:25:38.873461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.315071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-02T05:25:38.873491Z","caller":"traceutil/trace.go:171","msg":"trace[1190995041] transaction","detail":"{read_only:false; response_revision:8524; number_of_response:1; }","duration":"111.331395ms","start":"2026-07-02T05:25:38.762134Z","end":"2026-07-02T05:25:38.873465Z","steps":["trace[1190995041] 'process raft request' (duration: 111.08375ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:25:38.873394Z","caller":"traceutil/trace.go:171","msg":"trace[1883618400] transaction","detail":"{read_only:false; response_revision:8522; number_of_response:1; }","duration":"195.496793ms","start":"2026-07-02T05:25:38.677837Z","end":"2026-07-02T05:25:38.873334Z","steps":["trace[1883618400] 'process raft request' (duration: 195.171627ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:25:38.873545Z","caller":"traceutil/trace.go:171","msg":"trace[1105182143] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8524; }","duration":"134.449194ms","start":"2026-07-02T05:25:38.739078Z","end":"2026-07-02T05:25:38.873527Z","steps":["trace[1105182143] 'agreement among raft nodes before linearized reading' (duration: 134.233869ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:25:38.873629Z","caller":"traceutil/trace.go:171","msg":"trace[1367545089] transaction","detail":"{read_only:false; response_revision:8523; number_of_response:1; }","duration":"116.403141ms","start":"2026-07-02T05:25:38.757135Z","end":"2026-07-02T05:25:38.873538Z","steps":["trace[1367545089] 'process raft request' (duration: 116.024803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:25:38.873768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.086044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-bpblb\" ","response":"range_response_count:1 size:4652"} {"level":"info","ts":"2026-07-02T05:25:38.873856Z","caller":"traceutil/trace.go:171","msg":"trace[1801688479] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-bpblb; range_end:; response_count:1; response_revision:8524; }","duration":"117.207817ms","start":"2026-07-02T05:25:38.756619Z","end":"2026-07-02T05:25:38.873827Z","steps":["trace[1801688479] 'agreement among raft nodes before linearized reading' (duration: 116.990403ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:25:39.573457Z","caller":"traceutil/trace.go:171","msg":"trace[1911627685] linearizableReadLoop","detail":"{readStateIndex:8828; appliedIndex:8827; }","duration":"164.686009ms","start":"2026-07-02T05:25:39.408748Z","end":"2026-07-02T05:25:39.573434Z","steps":["trace[1911627685] 'read index received' (duration: 164.454583ms)","trace[1911627685] 'applied index is now lower than readState.Index' (duration: 230.716µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:25:39.573484Z","caller":"traceutil/trace.go:171","msg":"trace[712480078] transaction","detail":"{read_only:false; response_revision:8530; number_of_response:1; }","duration":"189.357918ms","start":"2026-07-02T05:25:39.384104Z","end":"2026-07-02T05:25:39.573462Z","steps":["trace[712480078] 'process raft request' (duration: 189.181924ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:25:39.573688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.933014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-07-02T05:25:39.573756Z","caller":"traceutil/trace.go:171","msg":"trace[1422376566] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8530; }","duration":"165.037055ms","start":"2026-07-02T05:25:39.408701Z","end":"2026-07-02T05:25:39.573738Z","steps":["trace[1422376566] 'agreement among raft nodes before linearized reading' (duration: 164.850832ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:26:29.044292Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000248c.wal"} {"level":"info","ts":"2026-07-02T05:26:37.931324Z","caller":"traceutil/trace.go:171","msg":"trace[1401390342] transaction","detail":"{read_only:false; response_revision:9151; number_of_response:1; }","duration":"108.187655ms","start":"2026-07-02T05:26:37.823102Z","end":"2026-07-02T05:26:37.93129Z","steps":["trace[1401390342] 'process raft request' (duration: 107.89966ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:27:49.332554Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b6478f0ac5aeb8e1","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T05:27:49.338556Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-02T05:27:49.338682Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-02T05:30:07.95644Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8266} {"level":"info","ts":"2026-07-02T05:30:08.031348Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8266,"took":"73.348607ms","hash":1047159752,"current-db-size-bytes":57470976,"current-db-size":"58 MB","current-db-size-in-use-bytes":26251264,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-02T05:30:08.031442Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1047159752,"revision":8266,"compact-revision":6065} {"level":"warn","ts":"2026-07-02T05:30:21.259157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.195321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T05:30:21.259227Z","caller":"traceutil/trace.go:171","msg":"trace[1162144490] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:11023; }","duration":"112.317554ms","start":"2026-07-02T05:30:21.146894Z","end":"2026-07-02T05:30:21.259212Z","steps":["trace[1162144490] 'count revisions from in-memory index tree' (duration: 112.106769ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:30:21.400401Z","caller":"traceutil/trace.go:171","msg":"trace[606472363] transaction","detail":"{read_only:false; response_revision:11025; number_of_response:1; }","duration":"111.738241ms","start":"2026-07-02T05:30:21.28863Z","end":"2026-07-02T05:30:21.400369Z","steps":["trace[606472363] 'process raft request' (duration: 71.590483ms)","trace[606472363] 'compare' (duration: 40.033146ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:35:07.96317Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10947} {"level":"info","ts":"2026-07-02T05:35:08.045327Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10947,"took":"79.579688ms","hash":3109268221,"current-db-size-bytes":57470976,"current-db-size":"58 MB","current-db-size-in-use-bytes":28241920,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-02T05:35:08.045404Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3109268221,"revision":10947,"compact-revision":8266} {"level":"info","ts":"2026-07-02T05:36:03.973352Z","caller":"traceutil/trace.go:171","msg":"trace[602873041] transaction","detail":"{read_only:false; response_revision:13377; number_of_response:1; }","duration":"149.117474ms","start":"2026-07-02T05:36:03.824208Z","end":"2026-07-02T05:36:03.973326Z","steps":["trace[602873041] 'process raft request' (duration: 74.604923ms)","trace[602873041] 'compare' (duration: 74.126774ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:36:03.973919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.020436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-02T05:36:03.974026Z","caller":"traceutil/trace.go:171","msg":"trace[1547509667] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13378; }","duration":"145.14459ms","start":"2026-07-02T05:36:03.828845Z","end":"2026-07-02T05:36:03.97399Z","steps":["trace[1547509667] 'agreement among raft nodes before linearized reading' (duration: 144.951016ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:36:03.973546Z","caller":"traceutil/trace.go:171","msg":"trace[604318318] linearizableReadLoop","detail":"{readStateIndex:13828; appliedIndex:13827; }","duration":"144.64475ms","start":"2026-07-02T05:36:03.828878Z","end":"2026-07-02T05:36:03.973522Z","steps":["trace[604318318] 'read index received' (duration: 69.895464ms)","trace[604318318] 'applied index is now lower than readState.Index' (duration: 74.747746ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:36:45.272474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.208819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:36:45.272569Z","caller":"traceutil/trace.go:171","msg":"trace[42994886] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:13616; }","duration":"285.359202ms","start":"2026-07-02T05:36:44.987193Z","end":"2026-07-02T05:36:45.272552Z","steps":["trace[42994886] 'count revisions from in-memory index tree' (duration: 285.106977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:36:45.272757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.836013ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:36:45.272899Z","caller":"traceutil/trace.go:171","msg":"trace[1286615397] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:13616; }","duration":"500.993466ms","start":"2026-07-02T05:36:44.771883Z","end":"2026-07-02T05:36:45.272876Z","steps":["trace[1286615397] 'range keys from in-memory index tree' (duration: 500.818083ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:36:45.272912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.41372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-02T05:36:45.273066Z","caller":"traceutil/trace.go:171","msg":"trace[496429664] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13616; }","duration":"495.565213ms","start":"2026-07-02T05:36:44.777481Z","end":"2026-07-02T05:36:45.273046Z","steps":["trace[496429664] 'range keys from in-memory index tree' (duration: 495.235756ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:36:45.273139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:36:44.777401Z","time spent":"495.693845ms","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-02T05:36:45.428193Z","caller":"traceutil/trace.go:171","msg":"trace[757504161] linearizableReadLoop","detail":"{readStateIndex:14078; appliedIndex:14077; }","duration":"255.703398ms","start":"2026-07-02T05:36:45.172465Z","end":"2026-07-02T05:36:45.428169Z","steps":["trace[757504161] 'read index received' (duration: 255.632556ms)","trace[757504161] 'applied index is now lower than readState.Index' (duration: 69.802µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:36:45.42832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.8295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:36:45.428365Z","caller":"traceutil/trace.go:171","msg":"trace[1894046165] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:13617; }","duration":"255.922651ms","start":"2026-07-02T05:36:45.172429Z","end":"2026-07-02T05:36:45.428351Z","steps":["trace[1894046165] 'agreement among raft nodes before linearized reading' (duration: 255.836639ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:36:45.428335Z","caller":"traceutil/trace.go:171","msg":"trace[653025430] transaction","detail":"{read_only:false; response_revision:13617; number_of_response:1; }","duration":"415.433158ms","start":"2026-07-02T05:36:45.012802Z","end":"2026-07-02T05:36:45.428235Z","steps":["trace[653025430] 'process raft request' (duration: 415.225284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:36:45.42856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:36:45.012766Z","time spent":"415.708422ms","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:36:45.428998Z","caller":"traceutil/trace.go:171","msg":"trace[290142292] transaction","detail":"{read_only:false; response_revision:13618; number_of_response:1; }","duration":"151.436552ms","start":"2026-07-02T05:36:45.277534Z","end":"2026-07-02T05:36:45.42897Z","steps":["trace[290142292] 'process raft request' (duration: 151.266829ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:36:45.429119Z","caller":"traceutil/trace.go:171","msg":"trace[1617657226] transaction","detail":"{read_only:false; response_revision:13619; number_of_response:1; }","duration":"151.31808ms","start":"2026-07-02T05:36:45.277786Z","end":"2026-07-02T05:36:45.429104Z","steps":["trace[1617657226] 'process raft request' (duration: 151.147807ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:40:07.970956Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12988} {"level":"info","ts":"2026-07-02T05:40:08.044959Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12988,"took":"71.951169ms","hash":3853560894,"current-db-size-bytes":57470976,"current-db-size":"58 MB","current-db-size-in-use-bytes":31506432,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-02T05:40:08.04502Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3853560894,"revision":12988,"compact-revision":10947} {"level":"warn","ts":"2026-07-02T05:44:04.893572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.73377ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:44:04.893845Z","caller":"traceutil/trace.go:171","msg":"trace[189164678] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:17549; }","duration":"122.068716ms","start":"2026-07-02T05:44:04.771744Z","end":"2026-07-02T05:44:04.893812Z","steps":["trace[189164678] 'range keys from in-memory index tree' (duration: 121.700409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:44:06.292269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.834441ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:44:06.292498Z","caller":"traceutil/trace.go:171","msg":"trace[1705294255] transaction","detail":"{read_only:false; response_revision:17561; number_of_response:1; }","duration":"147.075121ms","start":"2026-07-02T05:44:06.14541Z","end":"2026-07-02T05:44:06.292485Z","steps":["trace[1705294255] 'process raft request' (duration: 23.947354ms)","trace[1705294255] 'compare' (duration: 122.705719ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:44:10.35287Z","caller":"traceutil/trace.go:171","msg":"trace[336340316] linearizableReadLoop","detail":"{readStateIndex:18170; appliedIndex:18169; }","duration":"103.480833ms","start":"2026-07-02T05:44:10.249361Z","end":"2026-07-02T05:44:10.352842Z","steps":["trace[336340316] 'read index received' (duration: 33.523936ms)","trace[336340316] 'applied index is now lower than readState.Index' (duration: 69.955857ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:44:10.352958Z","caller":"traceutil/trace.go:171","msg":"trace[1685303666] transaction","detail":"{read_only:false; response_revision:17586; number_of_response:1; }","duration":"199.504016ms","start":"2026-07-02T05:44:10.153416Z","end":"2026-07-02T05:44:10.35292Z","steps":["trace[1685303666] 'process raft request' (duration: 129.507358ms)","trace[1685303666] 'compare' (duration: 69.817305ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:44:10.353037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.657176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:44:10.353079Z","caller":"traceutil/trace.go:171","msg":"trace[992932483] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:17586; }","duration":"103.759569ms","start":"2026-07-02T05:44:10.249304Z","end":"2026-07-02T05:44:10.353063Z","steps":["trace[992932483] 'agreement among raft nodes before linearized reading' (duration: 103.671657ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:44:10.480611Z","caller":"traceutil/trace.go:171","msg":"trace[123733258] transaction","detail":"{read_only:false; response_revision:17587; number_of_response:1; }","duration":"122.678487ms","start":"2026-07-02T05:44:10.35791Z","end":"2026-07-02T05:44:10.480589Z","steps":["trace[123733258] 'process raft request' (duration: 115.98568ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:44:10.484221Z","caller":"traceutil/trace.go:171","msg":"trace[1318451477] transaction","detail":"{read_only:false; response_revision:17588; number_of_response:1; }","duration":"123.586255ms","start":"2026-07-02T05:44:10.360625Z","end":"2026-07-02T05:44:10.484211Z","steps":["trace[1318451477] 'process raft request' (duration: 123.500623ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:44:10.484369Z","caller":"traceutil/trace.go:171","msg":"trace[1310450892] transaction","detail":"{read_only:false; response_revision:17589; number_of_response:1; }","duration":"123.488853ms","start":"2026-07-02T05:44:10.360873Z","end":"2026-07-02T05:44:10.484362Z","steps":["trace[1310450892] 'process raft request' (duration: 123.306879ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:45:07.977634Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15551} {"level":"info","ts":"2026-07-02T05:45:08.070723Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15551,"took":"90.6804ms","hash":365672579,"current-db-size-bytes":57470976,"current-db-size":"58 MB","current-db-size-in-use-bytes":34738176,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-07-02T05:45:08.070782Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":365672579,"revision":15551,"compact-revision":12988} {"level":"info","ts":"2026-07-02T05:47:51.220009Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b6478f0ac5aeb8e1","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T05:47:51.224252Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-02T05:47:51.224349Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-02T05:48:50.16563Z","caller":"traceutil/trace.go:171","msg":"trace[2058982988] linearizableReadLoop","detail":"{readStateIndex:20326; appliedIndex:20325; }","duration":"142.271621ms","start":"2026-07-02T05:48:50.02333Z","end":"2026-07-02T05:48:50.165602Z","steps":["trace[2058982988] 'read index received' (duration: 142.092648ms)","trace[2058982988] 'applied index is now lower than readState.Index' (duration: 177.913µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:48:50.165744Z","caller":"traceutil/trace.go:171","msg":"trace[1510726636] transaction","detail":"{read_only:false; response_revision:19669; number_of_response:1; }","duration":"152.217931ms","start":"2026-07-02T05:48:50.01348Z","end":"2026-07-02T05:48:50.165698Z","steps":["trace[1510726636] 'process raft request' (duration: 151.956266ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:48:50.165819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.476834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T05:48:50.165867Z","caller":"traceutil/trace.go:171","msg":"trace[214663232] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:19669; }","duration":"142.601567ms","start":"2026-07-02T05:48:50.023253Z","end":"2026-07-02T05:48:50.165854Z","steps":["trace[214663232] 'agreement among raft nodes before linearized reading' (duration: 142.500316ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:48:51.301741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.859397ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:48:51.301861Z","caller":"traceutil/trace.go:171","msg":"trace[2088675572] linearizableReadLoop","detail":"{readStateIndex:20329; appliedIndex:20328; }","duration":"109.699163ms","start":"2026-07-02T05:48:51.192145Z","end":"2026-07-02T05:48:51.301844Z","steps":["trace[2088675572] 'read index received' (duration: 49.511µs)","trace[2088675572] 'applied index is now lower than readState.Index' (duration: 109.648432ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:48:51.301944Z","caller":"traceutil/trace.go:171","msg":"trace[1533697177] transaction","detail":"{read_only:false; response_revision:19671; number_of_response:1; }","duration":"142.430464ms","start":"2026-07-02T05:48:51.159468Z","end":"2026-07-02T05:48:51.301899Z","steps":["trace[1533697177] 'process raft request' (duration: 13.321042ms)","trace[1533697177] 'compare' (duration: 128.743404ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:48:51.302104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.933917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T05:48:51.302195Z","caller":"traceutil/trace.go:171","msg":"trace[1270396299] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:19671; }","duration":"110.08256ms","start":"2026-07-02T05:48:51.192093Z","end":"2026-07-02T05:48:51.302175Z","steps":["trace[1270396299] 'agreement among raft nodes before linearized reading' (duration: 109.829586ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:50:07.9839Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18130} {"level":"info","ts":"2026-07-02T05:50:08.110368Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18130,"took":"122.747979ms","hash":3593004190,"current-db-size-bytes":57470976,"current-db-size":"58 MB","current-db-size-in-use-bytes":30842880,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-02T05:50:08.11042Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3593004190,"revision":18130,"compact-revision":15551} {"level":"info","ts":"2026-07-02T05:52:22.870903Z","caller":"traceutil/trace.go:171","msg":"trace[280593064] transaction","detail":"{read_only:false; response_revision:21986; number_of_response:1; }","duration":"235.116352ms","start":"2026-07-02T05:52:22.635752Z","end":"2026-07-02T05:52:22.870869Z","steps":["trace[280593064] 'process raft request' (duration: 234.874047ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:52:22.909247Z","caller":"traceutil/trace.go:171","msg":"trace[510791025] transaction","detail":"{read_only:false; response_revision:21988; number_of_response:1; }","duration":"161.82676ms","start":"2026-07-02T05:52:22.747395Z","end":"2026-07-02T05:52:22.909222Z","steps":["trace[510791025] 'process raft request' (duration: 161.741259ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:52:22.909261Z","caller":"traceutil/trace.go:171","msg":"trace[519197839] transaction","detail":"{read_only:false; response_revision:21987; number_of_response:1; }","duration":"244.178444ms","start":"2026-07-02T05:52:22.665062Z","end":"2026-07-02T05:52:22.909241Z","steps":["trace[519197839] 'process raft request' (duration: 215.704004ms)","trace[519197839] 'compare' (duration: 28.231545ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:53:47.483476Z","caller":"traceutil/trace.go:171","msg":"trace[21941245] transaction","detail":"{read_only:false; response_revision:22899; number_of_response:1; }","duration":"116.746586ms","start":"2026-07-02T05:53:47.366705Z","end":"2026-07-02T05:53:47.483451Z","steps":["trace[21941245] 'process raft request' (duration: 97.033142ms)","trace[21941245] 'compare' (duration: 19.595782ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:53:47.502139Z","caller":"traceutil/trace.go:171","msg":"trace[1450155319] transaction","detail":"{read_only:false; response_revision:22902; number_of_response:1; }","duration":"134.881479ms","start":"2026-07-02T05:53:47.367106Z","end":"2026-07-02T05:53:47.501987Z","steps":["trace[1450155319] 'process raft request' (duration: 134.788637ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:53:47.502137Z","caller":"traceutil/trace.go:171","msg":"trace[1291767406] transaction","detail":"{read_only:false; response_revision:22900; number_of_response:1; }","duration":"135.085003ms","start":"2026-07-02T05:53:47.366996Z","end":"2026-07-02T05:53:47.502081Z","steps":["trace[1291767406] 'process raft request' (duration: 134.803368ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:53:47.502155Z","caller":"traceutil/trace.go:171","msg":"trace[1875726945] transaction","detail":"{read_only:false; response_revision:22901; number_of_response:1; }","duration":"135.066133ms","start":"2026-07-02T05:53:47.367059Z","end":"2026-07-02T05:53:47.502125Z","steps":["trace[1875726945] 'process raft request' (duration: 134.808298ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:55:07.990057Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20409} {"level":"info","ts":"2026-07-02T05:55:08.094611Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20409,"took":"100.743572ms","hash":4245826353,"current-db-size-bytes":57892864,"current-db-size":"58 MB","current-db-size-in-use-bytes":44441600,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-07-02T05:55:08.094663Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4245826353,"revision":20409,"compact-revision":18130} {"level":"warn","ts":"2026-07-02T05:55:39.268929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.433993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:55:39.26914Z","caller":"traceutil/trace.go:171","msg":"trace[1992331645] transaction","detail":"{read_only:false; response_revision:24015; number_of_response:1; }","duration":"151.79777ms","start":"2026-07-02T05:55:39.117325Z","end":"2026-07-02T05:55:39.269123Z","steps":["trace[1992331645] 'process raft request' (duration: 151.713549ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:55:39.269205Z","caller":"traceutil/trace.go:171","msg":"trace[388948838] transaction","detail":"{read_only:false; response_revision:24014; number_of_response:1; }","duration":"276.246782ms","start":"2026-07-02T05:55:38.992928Z","end":"2026-07-02T05:55:39.269174Z","steps":["trace[388948838] 'process raft request' (duration: 124.484433ms)","trace[388948838] 'compare' (duration: 150.918624ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:55:39.756372Z","caller":"traceutil/trace.go:171","msg":"trace[2080504967] transaction","detail":"{read_only:false; response_revision:24016; number_of_response:1; }","duration":"208.502427ms","start":"2026-07-02T05:55:39.54785Z","end":"2026-07-02T05:55:39.756353Z","steps":["trace[2080504967] 'process raft request' (duration: 208.386285ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:55:39.757366Z","caller":"traceutil/trace.go:171","msg":"trace[773576386] linearizableReadLoop","detail":"{readStateIndex:24797; appliedIndex:24795; }","duration":"195.161064ms","start":"2026-07-02T05:55:39.562192Z","end":"2026-07-02T05:55:39.757353Z","steps":["trace[773576386] 'read index received' (duration: 193.981252ms)","trace[773576386] 'applied index is now lower than readState.Index' (duration: 1.179362ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:55:39.75746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.239055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:55:39.757481Z","caller":"traceutil/trace.go:171","msg":"trace[1148134764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24018; }","duration":"195.337607ms","start":"2026-07-02T05:55:39.562137Z","end":"2026-07-02T05:55:39.757474Z","steps":["trace[1148134764] 'agreement among raft nodes before linearized reading' (duration: 195.259246ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:55:39.757726Z","caller":"traceutil/trace.go:171","msg":"trace[656502505] transaction","detail":"{read_only:false; response_revision:24017; number_of_response:1; }","duration":"209.21946ms","start":"2026-07-02T05:55:39.548496Z","end":"2026-07-02T05:55:39.757715Z","steps":["trace[656502505] 'process raft request' (duration: 208.788182ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:55:39.757823Z","caller":"traceutil/trace.go:171","msg":"trace[593115131] transaction","detail":"{read_only:false; response_revision:24018; number_of_response:1; }","duration":"157.303874ms","start":"2026-07-02T05:55:39.600509Z","end":"2026-07-02T05:55:39.757812Z","steps":["trace[593115131] 'process raft request' (duration: 156.822286ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:55:43.903567Z","caller":"traceutil/trace.go:171","msg":"trace[1348833047] transaction","detail":"{read_only:false; response_revision:24046; number_of_response:1; }","duration":"125.325798ms","start":"2026-07-02T05:55:43.7782Z","end":"2026-07-02T05:55:43.903526Z","steps":["trace[1348833047] 'process raft request' (duration: 124.503983ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:55:44.18729Z","caller":"traceutil/trace.go:171","msg":"trace[1319680940] transaction","detail":"{read_only:false; response_revision:24047; number_of_response:1; }","duration":"405.912334ms","start":"2026-07-02T05:55:43.781349Z","end":"2026-07-02T05:55:44.187261Z","steps":["trace[1319680940] 'process raft request' (duration: 326.070888ms)","trace[1319680940] 'compare' (duration: 79.627102ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:55:44.187465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:55:43.78132Z","time spent":"406.058676ms","remote":"127.0.0.1:44726","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-07-02T05:55:44.187641Z","caller":"traceutil/trace.go:171","msg":"trace[318811706] transaction","detail":"{read_only:false; response_revision:24049; number_of_response:1; }","duration":"330.345979ms","start":"2026-07-02T05:55:43.857275Z","end":"2026-07-02T05:55:44.187621Z","steps":["trace[318811706] 'process raft request' (duration: 330.190346ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:55:44.187629Z","caller":"traceutil/trace.go:171","msg":"trace[977851400] linearizableReadLoop","detail":"{readStateIndex:24830; appliedIndex:24827; }","duration":"203.692226ms","start":"2026-07-02T05:55:43.983906Z","end":"2026-07-02T05:55:44.187598Z","steps":["trace[977851400] 'read index received' (duration: 123.402222ms)","trace[977851400] 'applied index is now lower than readState.Index' (duration: 80.288774ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:55:44.187716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:55:43.857246Z","time spent":"330.435531ms","remote":"127.0.0.1:44726","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-07-02T05:55:44.187715Z","caller":"traceutil/trace.go:171","msg":"trace[105884072] transaction","detail":"{read_only:false; response_revision:24048; number_of_response:1; }","duration":"404.527607ms","start":"2026-07-02T05:55:43.783156Z","end":"2026-07-02T05:55:44.187683Z","steps":["trace[105884072] 'process raft request' (duration: 404.062679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:55:44.187852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.904899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T05:55:44.187895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.062225ms","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-07-02T05:55:44.187899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:55:43.783142Z","time spent":"404.663339ms","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:55:44.18792Z","caller":"traceutil/trace.go:171","msg":"trace[1429989473] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:24049; }","duration":"204.036012ms","start":"2026-07-02T05:55:43.983865Z","end":"2026-07-02T05:55:44.187901Z","steps":["trace[1429989473] 'agreement among raft nodes before linearized reading' (duration: 203.831859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:55:44.188014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.930395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-02T05:55:44.187928Z","caller":"traceutil/trace.go:171","msg":"trace[1081621533] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24049; }","duration":"133.154407ms","start":"2026-07-02T05:55:44.054764Z","end":"2026-07-02T05:55:44.187918Z","steps":["trace[1081621533] 'agreement among raft nodes before linearized reading' (duration: 133.004234ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:55:44.188078Z","caller":"traceutil/trace.go:171","msg":"trace[1856096810] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24049; }","duration":"110.984936ms","start":"2026-07-02T05:55:44.077072Z","end":"2026-07-02T05:55:44.188057Z","steps":["trace[1856096810] 'agreement among raft nodes before linearized reading' (duration: 110.875704ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:55:44.431897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.651381ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:55:44.432167Z","caller":"traceutil/trace.go:171","msg":"trace[322676555] linearizableReadLoop","detail":"{readStateIndex:24834; appliedIndex:24830; }","duration":"180.444474ms","start":"2026-07-02T05:55:44.251704Z","end":"2026-07-02T05:55:44.432149Z","steps":["trace[322676555] 'read index received' (duration: 61.406875ms)","trace[322676555] 'applied index is now lower than readState.Index' (duration: 119.036529ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:55:44.432213Z","caller":"traceutil/trace.go:171","msg":"trace[669725696] transaction","detail":"{read_only:false; response_revision:24050; number_of_response:1; }","duration":"240.052457ms","start":"2026-07-02T05:55:44.19212Z","end":"2026-07-02T05:55:44.432172Z","steps":["trace[669725696] 'process raft request' (duration: 121.036548ms)","trace[669725696] 'compare' (duration: 118.521309ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:55:44.432488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.792351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-07-02T05:55:44.43257Z","caller":"traceutil/trace.go:171","msg":"trace[936325820] transaction","detail":"{read_only:false; response_revision:24052; number_of_response:1; }","duration":"240.184479ms","start":"2026-07-02T05:55:44.192354Z","end":"2026-07-02T05:55:44.432538Z","steps":["trace[936325820] 'process raft request' (duration: 239.718131ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:55:44.432605Z","caller":"traceutil/trace.go:171","msg":"trace[1085240773] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24053; }","duration":"180.958334ms","start":"2026-07-02T05:55:44.251636Z","end":"2026-07-02T05:55:44.432594Z","steps":["trace[1085240773] 'agreement among raft nodes before linearized reading' (duration: 180.570947ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:55:44.432598Z","caller":"traceutil/trace.go:171","msg":"trace[2074890990] transaction","detail":"{read_only:false; response_revision:24053; number_of_response:1; }","duration":"239.864512ms","start":"2026-07-02T05:55:44.192705Z","end":"2026-07-02T05:55:44.43257Z","steps":["trace[2074890990] 'process raft request' (duration: 239.403573ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:55:44.432639Z","caller":"traceutil/trace.go:171","msg":"trace[1525094459] transaction","detail":"{read_only:false; response_revision:24051; number_of_response:1; }","duration":"240.24374ms","start":"2026-07-02T05:55:44.192347Z","end":"2026-07-02T05:55:44.432591Z","steps":["trace[1525094459] 'process raft request' (duration: 239.67435ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:55:44.709875Z","caller":"traceutil/trace.go:171","msg":"trace[1990680143] transaction","detail":"{read_only:false; response_revision:24054; number_of_response:1; }","duration":"328.306011ms","start":"2026-07-02T05:55:44.381512Z","end":"2026-07-02T05:55:44.709818Z","steps":["trace[1990680143] 'process raft request' (duration: 326.716991ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:55:44.710058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:55:44.381481Z","time spent":"328.468904ms","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:55:44.847797Z","caller":"traceutil/trace.go:171","msg":"trace[1055963356] linearizableReadLoop","detail":"{readStateIndex:24836; appliedIndex:24834; }","duration":"366.977955ms","start":"2026-07-02T05:55:44.480789Z","end":"2026-07-02T05:55:44.847767Z","steps":["trace[1055963356] 'read index received' (duration: 227.421077ms)","trace[1055963356] 'applied index is now lower than readState.Index' (duration: 139.555848ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:55:44.847837Z","caller":"traceutil/trace.go:171","msg":"trace[538857194] transaction","detail":"{read_only:false; response_revision:24055; number_of_response:1; }","duration":"410.585852ms","start":"2026-07-02T05:55:44.437204Z","end":"2026-07-02T05:55:44.847789Z","steps":["trace[538857194] 'process raft request' (duration: 407.29459ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:55:44.847843Z","caller":"traceutil/trace.go:171","msg":"trace[21953205] transaction","detail":"{read_only:false; response_revision:24056; number_of_response:1; }","duration":"307.939764ms","start":"2026-07-02T05:55:44.53987Z","end":"2026-07-02T05:55:44.84781Z","steps":["trace[21953205] 'process raft request' (duration: 307.837291ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:55:44.847997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.057092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T05:55:44.848021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:55:44.539854Z","time spent":"308.078477ms","remote":"127.0.0.1:44726","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-07-02T05:55:44.848074Z","caller":"traceutil/trace.go:171","msg":"trace[841840152] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24056; }","duration":"283.135344ms","start":"2026-07-02T05:55:44.564889Z","end":"2026-07-02T05:55:44.848024Z","steps":["trace[841840152] 'agreement among raft nodes before linearized reading' (duration: 283.043333ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:55:44.848064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:55:44.437177Z","time spent":"410.746525ms","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:55:44.848085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.276141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18432"} {"level":"info","ts":"2026-07-02T05:55:44.848213Z","caller":"traceutil/trace.go:171","msg":"trace[943526818] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24056; }","duration":"367.479014ms","start":"2026-07-02T05:55:44.480718Z","end":"2026-07-02T05:55:44.848197Z","steps":["trace[943526818] 'agreement among raft nodes before linearized reading' (duration: 367.176179ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:55:44.84825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:55:44.4807Z","time spent":"367.541625ms","remote":"127.0.0.1:44622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18455,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-02T05:55:45.399553Z","caller":"traceutil/trace.go:171","msg":"trace[847212155] transaction","detail":"{read_only:false; response_revision:24058; number_of_response:1; }","duration":"106.44792ms","start":"2026-07-02T05:55:45.293079Z","end":"2026-07-02T05:55:45.399527Z","steps":["trace[847212155] 'process raft request' (duration: 106.279586ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:55:45.715898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.772711ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:55:45.716122Z","caller":"traceutil/trace.go:171","msg":"trace[1503209250] transaction","detail":"{read_only:false; response_revision:24059; number_of_response:1; }","duration":"421.182703ms","start":"2026-07-02T05:55:45.294911Z","end":"2026-07-02T05:55:45.716094Z","steps":["trace[1503209250] 'process raft request' (duration: 251.121495ms)","trace[1503209250] 'compare' (duration: 169.410035ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:55:45.716169Z","caller":"traceutil/trace.go:171","msg":"trace[462602765] linearizableReadLoop","detail":"{readStateIndex:24840; appliedIndex:24839; }","duration":"206.993898ms","start":"2026-07-02T05:55:45.509158Z","end":"2026-07-02T05:55:45.716152Z","steps":["trace[462602765] 'read index received' (duration: 36.780217ms)","trace[462602765] 'applied index is now lower than readState.Index' (duration: 170.211971ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:55:45.7163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:55:45.294878Z","time spent":"421.311595ms","remote":"127.0.0.1:44726","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-07-02T05:55:45.716343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.163142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T05:55:45.716401Z","caller":"traceutil/trace.go:171","msg":"trace[139426014] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:24059; }","duration":"207.279553ms","start":"2026-07-02T05:55:45.509107Z","end":"2026-07-02T05:55:45.716386Z","steps":["trace[139426014] 'agreement among raft nodes before linearized reading' (duration: 207.161341ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:55:45.71678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.495331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:55:45.71686Z","caller":"traceutil/trace.go:171","msg":"trace[1727399425] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:24060; }","duration":"154.844408ms","start":"2026-07-02T05:55:45.561995Z","end":"2026-07-02T05:55:45.71684Z","steps":["trace[1727399425] 'agreement among raft nodes before linearized reading' (duration: 154.490242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:55:46.712816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.819318ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-02T05:55:46.712917Z","caller":"traceutil/trace.go:171","msg":"trace[1324451974] linearizableReadLoop","detail":"{readStateIndex:24846; appliedIndex:24845; }","duration":"275.907605ms","start":"2026-07-02T05:55:46.436993Z","end":"2026-07-02T05:55:46.712901Z","steps":["trace[1324451974] 'read index received' (duration: 13.915463ms)","trace[1324451974] 'applied index is now lower than readState.Index' (duration: 261.990522ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:55:46.713118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.032348ms","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-07-02T05:55:46.713109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.09992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-02T05:55:46.713147Z","caller":"traceutil/trace.go:171","msg":"trace[1603138261] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24064; }","duration":"276.063039ms","start":"2026-07-02T05:55:46.437068Z","end":"2026-07-02T05:55:46.713132Z","steps":["trace[1603138261] 'agreement among raft nodes before linearized reading' (duration: 275.924726ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:55:46.713178Z","caller":"traceutil/trace.go:171","msg":"trace[1618324976] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24064; }","duration":"276.227462ms","start":"2026-07-02T05:55:46.436935Z","end":"2026-07-02T05:55:46.713163Z","steps":["trace[1618324976] 'agreement among raft nodes before linearized reading' (duration: 276.022788ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:55:46.713277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.023058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:55:46.713294Z","caller":"traceutil/trace.go:171","msg":"trace[1775814641] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24064; }","duration":"149.0931ms","start":"2026-07-02T05:55:46.564195Z","end":"2026-07-02T05:55:46.713288Z","steps":["trace[1775814641] 'agreement among raft nodes before linearized reading' (duration: 149.060329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:55:46.713436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.271574ms","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-07-02T05:55:46.713452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.90238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:55:46.713461Z","caller":"traceutil/trace.go:171","msg":"trace[2136536237] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24064; }","duration":"275.316696ms","start":"2026-07-02T05:55:46.438136Z","end":"2026-07-02T05:55:46.713453Z","steps":["trace[2136536237] 'agreement among raft nodes before linearized reading' (duration: 275.245414ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:55:46.713474Z","caller":"traceutil/trace.go:171","msg":"trace[257958169] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:24064; }","duration":"144.944941ms","start":"2026-07-02T05:55:46.568523Z","end":"2026-07-02T05:55:46.713467Z","steps":["trace[257958169] 'agreement among raft nodes before linearized reading' (duration: 144.89048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:55:46.713589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.390317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-02T05:55:46.713627Z","caller":"traceutil/trace.go:171","msg":"trace[1712579438] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24064; }","duration":"275.442248ms","start":"2026-07-02T05:55:46.438178Z","end":"2026-07-02T05:55:46.71362Z","steps":["trace[1712579438] 'agreement among raft nodes before linearized reading' (duration: 275.169513ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:55:46.92195Z","caller":"traceutil/trace.go:171","msg":"trace[1179328236] transaction","detail":"{read_only:false; response_revision:24065; number_of_response:1; }","duration":"203.494512ms","start":"2026-07-02T05:55:46.71843Z","end":"2026-07-02T05:55:46.921925Z","steps":["trace[1179328236] 'process raft request' (duration: 203.293848ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:55:46.986587Z","caller":"traceutil/trace.go:171","msg":"trace[1003339750] transaction","detail":"{read_only:false; response_revision:24068; number_of_response:1; }","duration":"268.051576ms","start":"2026-07-02T05:55:46.718511Z","end":"2026-07-02T05:55:46.986563Z","steps":["trace[1003339750] 'process raft request' (duration: 267.998205ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:55:46.986834Z","caller":"traceutil/trace.go:171","msg":"trace[1717095893] transaction","detail":"{read_only:false; response_revision:24066; number_of_response:1; }","duration":"268.356992ms","start":"2026-07-02T05:55:46.718468Z","end":"2026-07-02T05:55:46.986825Z","steps":["trace[1717095893] 'process raft request' (duration: 267.877763ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:55:46.986928Z","caller":"traceutil/trace.go:171","msg":"trace[1737984223] transaction","detail":"{read_only:false; response_revision:24067; number_of_response:1; }","duration":"268.434004ms","start":"2026-07-02T05:55:46.718487Z","end":"2026-07-02T05:55:46.986921Z","steps":["trace[1737984223] 'process raft request' (duration: 267.980056ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:55:46.987055Z","caller":"traceutil/trace.go:171","msg":"trace[687124180] transaction","detail":"{read_only:false; response_revision:24069; number_of_response:1; }","duration":"268.504555ms","start":"2026-07-02T05:55:46.718533Z","end":"2026-07-02T05:55:46.987038Z","steps":["trace[687124180] 'process raft request' (duration: 268.003736ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:55:46.987299Z","caller":"traceutil/trace.go:171","msg":"trace[1643109808] linearizableReadLoop","detail":"{readStateIndex:24851; appliedIndex:24846; }","duration":"165.497282ms","start":"2026-07-02T05:55:46.821792Z","end":"2026-07-02T05:55:46.987289Z","steps":["trace[1643109808] 'read index received' (duration: 99.998729ms)","trace[1643109808] 'applied index is now lower than readState.Index' (duration: 65.497783ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:55:46.987392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.579593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:55:46.9874Z","caller":"traceutil/trace.go:171","msg":"trace[1441792056] transaction","detail":"{read_only:false; response_revision:24070; number_of_response:1; }","duration":"134.266579ms","start":"2026-07-02T05:55:46.853127Z","end":"2026-07-02T05:55:46.987394Z","steps":["trace[1441792056] 'process raft request' (duration: 133.85254ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:55:46.987424Z","caller":"traceutil/trace.go:171","msg":"trace[1021127936] 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:24070; }","duration":"165.665774ms","start":"2026-07-02T05:55:46.821749Z","end":"2026-07-02T05:55:46.987414Z","steps":["trace[1021127936] 'agreement among raft nodes before linearized reading' (duration: 165.581912ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:00.865056Z","caller":"traceutil/trace.go:171","msg":"trace[2018503318] linearizableReadLoop","detail":"{readStateIndex:25029; appliedIndex:25028; }","duration":"302.646533ms","start":"2026-07-02T05:56:00.562388Z","end":"2026-07-02T05:56:00.865034Z","steps":["trace[2018503318] 'read index received' (duration: 302.593572ms)","trace[2018503318] 'applied index is now lower than readState.Index' (duration: 52.001µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:00.865159Z","caller":"traceutil/trace.go:171","msg":"trace[968789333] transaction","detail":"{read_only:false; response_revision:24245; number_of_response:1; }","duration":"345.920694ms","start":"2026-07-02T05:56:00.519201Z","end":"2026-07-02T05:56:00.865122Z","steps":["trace[968789333] 'process raft request' (duration: 345.69093ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:00.865268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.843507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T05:56:00.865321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:00.519177Z","time spent":"346.059417ms","remote":"127.0.0.1:39012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2551,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:56:00.865358Z","caller":"traceutil/trace.go:171","msg":"trace[280092909] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24245; }","duration":"302.99328ms","start":"2026-07-02T05:56:00.562345Z","end":"2026-07-02T05:56:00.865338Z","steps":["trace[280092909] 'agreement among raft nodes before linearized reading' (duration: 302.807226ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:00.865431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:00.562328Z","time spent":"303.070142ms","remote":"127.0.0.1:44454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T05:56:00.942589Z","caller":"traceutil/trace.go:171","msg":"trace[549861238] transaction","detail":"{read_only:false; response_revision:24246; number_of_response:1; }","duration":"211.565564ms","start":"2026-07-02T05:56:00.730997Z","end":"2026-07-02T05:56:00.942562Z","steps":["trace[549861238] 'process raft request' (duration: 211.283348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:00.942635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.70795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-manila-server-0.18be635e58eae18b\" ","response":"range_response_count:1 size:1010"} {"level":"info","ts":"2026-07-02T05:56:00.942692Z","caller":"traceutil/trace.go:171","msg":"trace[271237371] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-manila-server-0.18be635e58eae18b; range_end:; response_count:1; response_revision:24246; }","duration":"248.768022ms","start":"2026-07-02T05:56:00.693909Z","end":"2026-07-02T05:56:00.942677Z","steps":["trace[271237371] 'agreement among raft nodes before linearized reading' (duration: 248.590258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:00.942626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.613443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T05:56:00.942774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.03937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ea450860-73ef-4c89-b6e9-0ed2811fde9f\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-07-02T05:56:00.94286Z","caller":"traceutil/trace.go:171","msg":"trace[1237436621] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ea450860-73ef-4c89-b6e9-0ed2811fde9f; range_end:; response_count:1; response_revision:24246; }","duration":"304.177282ms","start":"2026-07-02T05:56:00.638663Z","end":"2026-07-02T05:56:00.94284Z","steps":["trace[1237436621] 'agreement among raft nodes before linearized reading' (duration: 303.925777ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:00.942905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:00.638639Z","time spent":"304.257334ms","remote":"127.0.0.1:44634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3666,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ea450860-73ef-4c89-b6e9-0ed2811fde9f\" "} {"level":"info","ts":"2026-07-02T05:56:00.942875Z","caller":"traceutil/trace.go:171","msg":"trace[112091315] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:24246; }","duration":"234.877937ms","start":"2026-07-02T05:56:00.707945Z","end":"2026-07-02T05:56:00.942823Z","steps":["trace[112091315] 'agreement among raft nodes before linearized reading' (duration: 234.551961ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:01.214198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.88687ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:56:01.214284Z","caller":"traceutil/trace.go:171","msg":"trace[1641541695] linearizableReadLoop","detail":"{readStateIndex:25031; appliedIndex:25030; }","duration":"271.796418ms","start":"2026-07-02T05:56:00.942471Z","end":"2026-07-02T05:56:01.214268Z","steps":["trace[1641541695] 'read index received' (duration: 117.647252ms)","trace[1641541695] 'applied index is now lower than readState.Index' (duration: 154.148036ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:01.214439Z","caller":"traceutil/trace.go:171","msg":"trace[655201385] transaction","detail":"{read_only:false; response_revision:24247; number_of_response:1; }","duration":"342.289066ms","start":"2026-07-02T05:56:00.872115Z","end":"2026-07-02T05:56:01.214404Z","steps":["trace[655201385] 'process raft request' (duration: 187.950717ms)","trace[655201385] 'compare' (duration: 153.770738ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:01.214489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.112756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:56:01.214569Z","caller":"traceutil/trace.go:171","msg":"trace[1679314671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24247; }","duration":"348.201508ms","start":"2026-07-02T05:56:00.866351Z","end":"2026-07-02T05:56:01.214553Z","steps":["trace[1679314671] 'agreement among raft nodes before linearized reading' (duration: 348.052895ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:01.214594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:00.872097Z","time spent":"342.416538ms","remote":"127.0.0.1:39012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:56:01.21461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:00.866334Z","time spent":"348.26719ms","remote":"127.0.0.1:44438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T05:56:01.292032Z","caller":"traceutil/trace.go:171","msg":"trace[1144718988] transaction","detail":"{read_only:false; response_revision:24248; number_of_response:1; }","duration":"346.642848ms","start":"2026-07-02T05:56:00.945367Z","end":"2026-07-02T05:56:01.29201Z","steps":["trace[1144718988] 'process raft request' (duration: 346.457164ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:01.292219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:00.945345Z","time spent":"346.74806ms","remote":"127.0.0.1:44530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:56:01.292241Z","caller":"traceutil/trace.go:171","msg":"trace[1453759505] transaction","detail":"{read_only:false; response_revision:24249; number_of_response:1; }","duration":"343.417506ms","start":"2026-07-02T05:56:00.948808Z","end":"2026-07-02T05:56:01.292225Z","steps":["trace[1453759505] 'process raft request' (duration: 343.138621ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:01.292549Z","caller":"traceutil/trace.go:171","msg":"trace[1396187223] transaction","detail":"{read_only:false; response_revision:24250; number_of_response:1; }","duration":"321.98851ms","start":"2026-07-02T05:56:00.970547Z","end":"2026-07-02T05:56:01.292536Z","steps":["trace[1396187223] 'process raft request' (duration: 321.596582ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:01.292513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:00.948778Z","time spent":"343.604831ms","remote":"127.0.0.1:44634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3745,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:56:01.292522Z","caller":"traceutil/trace.go:171","msg":"trace[15631837] transaction","detail":"{read_only:false; response_revision:24251; number_of_response:1; }","duration":"250.349561ms","start":"2026-07-02T05:56:01.042147Z","end":"2026-07-02T05:56:01.292496Z","steps":["trace[15631837] 'process raft request' (duration: 250.2469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:01.292639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:00.97053Z","time spent":"322.060421ms","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:56:01.421911Z","caller":"traceutil/trace.go:171","msg":"trace[559314842] linearizableReadLoop","detail":"{readStateIndex:25036; appliedIndex:25032; }","duration":"207.485718ms","start":"2026-07-02T05:56:01.214302Z","end":"2026-07-02T05:56:01.421788Z","steps":["trace[559314842] 'read index received' (duration: 77.633763ms)","trace[559314842] 'applied index is now lower than readState.Index' (duration: 129.850385ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:01.422352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.094025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-02T05:56:01.422458Z","caller":"traceutil/trace.go:171","msg":"trace[413306950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24252; }","duration":"359.238457ms","start":"2026-07-02T05:56:01.063205Z","end":"2026-07-02T05:56:01.422443Z","steps":["trace[413306950] 'agreement among raft nodes before linearized reading' (duration: 358.948081ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:01.422527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.944073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-07-02T05:56:01.422605Z","caller":"traceutil/trace.go:171","msg":"trace[1480257668] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:24252; }","duration":"358.057165ms","start":"2026-07-02T05:56:01.064527Z","end":"2026-07-02T05:56:01.422584Z","steps":["trace[1480257668] 'agreement among raft nodes before linearized reading' (duration: 357.322431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:01.422668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:01.064508Z","time spent":"358.146858ms","remote":"127.0.0.1:44486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":160,"request content":"key:\"/registry/masterleases/199.204.45.57\" "} {"level":"info","ts":"2026-07-02T05:56:01.422484Z","caller":"traceutil/trace.go:171","msg":"trace[322284936] transaction","detail":"{read_only:false; response_revision:24252; number_of_response:1; }","duration":"285.343515ms","start":"2026-07-02T05:56:01.137105Z","end":"2026-07-02T05:56:01.422448Z","steps":["trace[322284936] 'process raft request' (duration: 224.159784ms)","trace[322284936] 'compare' (duration: 60.181033ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:01.422681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.870842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-02T05:56:01.422765Z","caller":"traceutil/trace.go:171","msg":"trace[277662800] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24252; }","duration":"358.013044ms","start":"2026-07-02T05:56:01.064732Z","end":"2026-07-02T05:56:01.422745Z","steps":["trace[277662800] 'agreement among raft nodes before linearized reading' (duration: 357.775389ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:01.422808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:01.064718Z","time spent":"358.079946ms","remote":"127.0.0.1:44726","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-07-02T05:56:01.422802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.239174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-07-02T05:56:01.422868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.511318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:56:01.422853Z","caller":"traceutil/trace.go:171","msg":"trace[871303108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24252; }","duration":"363.326086ms","start":"2026-07-02T05:56:01.059514Z","end":"2026-07-02T05:56:01.422841Z","steps":["trace[871303108] 'agreement among raft nodes before linearized reading' (duration: 363.108072ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:01.422583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:01.063194Z","time spent":"359.37609ms","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T05:56:01.42297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:01.059498Z","time spent":"363.455058ms","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-02T05:56:01.423031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.761139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-02T05:56:01.423028Z","caller":"traceutil/trace.go:171","msg":"trace[512373754] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:24252; }","duration":"393.835205ms","start":"2026-07-02T05:56:01.029109Z","end":"2026-07-02T05:56:01.422944Z","steps":["trace[512373754] 'agreement among raft nodes before linearized reading' (duration: 393.489409ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:01.423074Z","caller":"traceutil/trace.go:171","msg":"trace[1142852656] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24252; }","duration":"367.831581ms","start":"2026-07-02T05:56:01.055229Z","end":"2026-07-02T05:56:01.423061Z","steps":["trace[1142852656] 'agreement among raft nodes before linearized reading' (duration: 367.697839ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:01.423104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:01.055214Z","time spent":"367.881872ms","remote":"127.0.0.1:44726","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-07-02T05:56:01.423102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:01.02909Z","time spent":"393.991547ms","remote":"127.0.0.1:42814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true "} {"level":"info","ts":"2026-07-02T05:56:01.559977Z","caller":"traceutil/trace.go:171","msg":"trace[1473946440] linearizableReadLoop","detail":"{readStateIndex:25041; appliedIndex:25036; }","duration":"131.74224ms","start":"2026-07-02T05:56:01.428207Z","end":"2026-07-02T05:56:01.55995Z","steps":["trace[1473946440] 'read index received' (duration: 87.749865ms)","trace[1473946440] 'applied index is now lower than readState.Index' (duration: 43.991135ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:01.560225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.002565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1155"} {"level":"info","ts":"2026-07-02T05:56:01.560211Z","caller":"traceutil/trace.go:171","msg":"trace[861924399] transaction","detail":"{read_only:false; response_revision:24254; number_of_response:1; }","duration":"133.133266ms","start":"2026-07-02T05:56:01.427046Z","end":"2026-07-02T05:56:01.560179Z","steps":["trace[861924399] 'process raft request' (duration: 132.709948ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:01.560304Z","caller":"traceutil/trace.go:171","msg":"trace[1544455412] transaction","detail":"{read_only:false; response_revision:24255; number_of_response:1; }","duration":"132.703258ms","start":"2026-07-02T05:56:01.427591Z","end":"2026-07-02T05:56:01.560294Z","steps":["trace[1544455412] 'process raft request' (duration: 132.22124ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:01.560424Z","caller":"traceutil/trace.go:171","msg":"trace[1635878568] transaction","detail":"{read_only:false; response_revision:24256; number_of_response:1; }","duration":"132.399564ms","start":"2026-07-02T05:56:01.428016Z","end":"2026-07-02T05:56:01.560416Z","steps":["trace[1635878568] 'process raft request' (duration: 131.842353ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:01.560506Z","caller":"traceutil/trace.go:171","msg":"trace[676163296] transaction","detail":"{read_only:false; response_revision:24257; number_of_response:1; }","duration":"106.230096ms","start":"2026-07-02T05:56:01.454266Z","end":"2026-07-02T05:56:01.560496Z","steps":["trace[676163296] 'process raft request' (duration: 105.636965ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:01.560269Z","caller":"traceutil/trace.go:171","msg":"trace[1648768669] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:24257; }","duration":"132.079527ms","start":"2026-07-02T05:56:01.428177Z","end":"2026-07-02T05:56:01.560257Z","steps":["trace[1648768669] 'agreement among raft nodes before linearized reading' (duration: 131.885833ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:01.560717Z","caller":"traceutil/trace.go:171","msg":"trace[1527387890] transaction","detail":"{read_only:false; response_revision:24253; number_of_response:1; }","duration":"133.727007ms","start":"2026-07-02T05:56:01.426981Z","end":"2026-07-02T05:56:01.560708Z","steps":["trace[1527387890] 'process raft request' (duration: 132.592535ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:01.86513Z","caller":"traceutil/trace.go:171","msg":"trace[2131666552] linearizableReadLoop","detail":"{readStateIndex:25044; appliedIndex:25043; }","duration":"276.657621ms","start":"2026-07-02T05:56:01.588448Z","end":"2026-07-02T05:56:01.865106Z","steps":["trace[2131666552] 'read index received' (duration: 275.398617ms)","trace[2131666552] 'applied index is now lower than readState.Index' (duration: 1.257824ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:01.865161Z","caller":"traceutil/trace.go:171","msg":"trace[1249433925] transaction","detail":"{read_only:false; response_revision:24259; number_of_response:1; }","duration":"276.887314ms","start":"2026-07-02T05:56:01.588241Z","end":"2026-07-02T05:56:01.865128Z","steps":["trace[1249433925] 'process raft request' (duration: 275.58498ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:01.865368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.897275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-02T05:56:01.865462Z","caller":"traceutil/trace.go:171","msg":"trace[381878415] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:24259; }","duration":"277.003417ms","start":"2026-07-02T05:56:01.588426Z","end":"2026-07-02T05:56:01.865429Z","steps":["trace[381878415] 'agreement among raft nodes before linearized reading' (duration: 276.807683ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:01.952416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.652616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9602"} {"level":"warn","ts":"2026-07-02T05:56:01.952428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.342038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:56:01.95249Z","caller":"traceutil/trace.go:171","msg":"trace[1183851738] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:24260; }","duration":"358.758739ms","start":"2026-07-02T05:56:01.593716Z","end":"2026-07-02T05:56:01.952475Z","steps":["trace[1183851738] 'agreement among raft nodes before linearized reading' (duration: 358.553015ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:01.952515Z","caller":"traceutil/trace.go:171","msg":"trace[1741520332] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:24260; }","duration":"115.477751ms","start":"2026-07-02T05:56:01.837018Z","end":"2026-07-02T05:56:01.952496Z","steps":["trace[1741520332] 'agreement among raft nodes before linearized reading' (duration: 115.255067ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:01.952529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:01.593702Z","time spent":"358.81672ms","remote":"127.0.0.1:44904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":9625,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" "} {"level":"info","ts":"2026-07-02T05:56:01.952429Z","caller":"traceutil/trace.go:171","msg":"trace[1942534434] transaction","detail":"{read_only:false; response_revision:24260; number_of_response:1; }","duration":"185.386618ms","start":"2026-07-02T05:56:01.767007Z","end":"2026-07-02T05:56:01.952393Z","steps":["trace[1942534434] 'process raft request' (duration: 185.107703ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:02.156311Z","caller":"traceutil/trace.go:171","msg":"trace[1299428896] transaction","detail":"{read_only:false; response_revision:24261; number_of_response:1; }","duration":"201.533665ms","start":"2026-07-02T05:56:01.954746Z","end":"2026-07-02T05:56:02.15628Z","steps":["trace[1299428896] 'process raft request' (duration: 125.351348ms)","trace[1299428896] 'compare' (duration: 75.902891ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:02.156585Z","caller":"traceutil/trace.go:171","msg":"trace[318675949] linearizableReadLoop","detail":"{readStateIndex:25046; appliedIndex:25045; }","duration":"199.597648ms","start":"2026-07-02T05:56:01.956962Z","end":"2026-07-02T05:56:02.15656Z","steps":["trace[318675949] 'read index received' (duration: 123.152527ms)","trace[318675949] 'applied index is now lower than readState.Index' (duration: 76.442741ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:02.156809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.819582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1446"} {"level":"info","ts":"2026-07-02T05:56:02.156888Z","caller":"traceutil/trace.go:171","msg":"trace[906183172] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24262; }","duration":"199.931634ms","start":"2026-07-02T05:56:01.956939Z","end":"2026-07-02T05:56:02.156871Z","steps":["trace[906183172] 'agreement among raft nodes before linearized reading' (duration: 199.70458ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:02.48519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.322876ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:56:02.485375Z","caller":"traceutil/trace.go:171","msg":"trace[1439798728] linearizableReadLoop","detail":"{readStateIndex:25049; appliedIndex:25047; }","duration":"147.46018ms","start":"2026-07-02T05:56:02.337899Z","end":"2026-07-02T05:56:02.485359Z","steps":["trace[1439798728] 'read index received' (duration: 146.200006ms)","trace[1439798728] 'applied index is now lower than readState.Index' (duration: 1.259294ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:02.485454Z","caller":"traceutil/trace.go:171","msg":"trace[1515663181] transaction","detail":"{read_only:false; response_revision:24264; number_of_response:1; }","duration":"160.038547ms","start":"2026-07-02T05:56:02.325406Z","end":"2026-07-02T05:56:02.485445Z","steps":["trace[1515663181] 'process raft request' (duration: 159.881594ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:02.485563Z","caller":"traceutil/trace.go:171","msg":"trace[1479362095] transaction","detail":"{read_only:false; response_revision:24263; number_of_response:1; }","duration":"313.709853ms","start":"2026-07-02T05:56:02.171834Z","end":"2026-07-02T05:56:02.485544Z","steps":["trace[1479362095] 'process raft request' (duration: 126.890117ms)","trace[1479362095] 'compare' (duration: 186.207764ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:02.485651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:02.171807Z","time spent":"313.802705ms","remote":"127.0.0.1:44530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":729,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:56:02.485667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.671563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-02T05:56:02.485748Z","caller":"traceutil/trace.go:171","msg":"trace[204796407] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24264; }","duration":"147.761524ms","start":"2026-07-02T05:56:02.337967Z","end":"2026-07-02T05:56:02.485728Z","steps":["trace[204796407] 'agreement among raft nodes before linearized reading' (duration: 147.554361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:02.485767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.856037ms","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-07-02T05:56:02.485811Z","caller":"traceutil/trace.go:171","msg":"trace[443619734] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24264; }","duration":"147.904268ms","start":"2026-07-02T05:56:02.337894Z","end":"2026-07-02T05:56:02.485798Z","steps":["trace[443619734] 'agreement among raft nodes before linearized reading' (duration: 147.769235ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:02.646306Z","caller":"traceutil/trace.go:171","msg":"trace[67702649] transaction","detail":"{read_only:false; response_revision:24265; number_of_response:1; }","duration":"156.98852ms","start":"2026-07-02T05:56:02.489294Z","end":"2026-07-02T05:56:02.646282Z","steps":["trace[67702649] 'process raft request' (duration: 156.837617ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:02.659242Z","caller":"traceutil/trace.go:171","msg":"trace[361863438] transaction","detail":"{read_only:false; response_revision:24266; number_of_response:1; }","duration":"169.484726ms","start":"2026-07-02T05:56:02.489735Z","end":"2026-07-02T05:56:02.659219Z","steps":["trace[361863438] 'process raft request' (duration: 169.009626ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:03.400882Z","caller":"traceutil/trace.go:171","msg":"trace[1048765937] transaction","detail":"{read_only:false; response_revision:24267; number_of_response:1; }","duration":"102.611188ms","start":"2026-07-02T05:56:03.298246Z","end":"2026-07-02T05:56:03.400857Z","steps":["trace[1048765937] 'process raft request' (duration: 102.449135ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:03.507208Z","caller":"traceutil/trace.go:171","msg":"trace[1262810626] transaction","detail":"{read_only:false; response_revision:24268; number_of_response:1; }","duration":"208.875374ms","start":"2026-07-02T05:56:03.29831Z","end":"2026-07-02T05:56:03.507186Z","steps":["trace[1262810626] 'process raft request' (duration: 194.105444ms)","trace[1262810626] 'compare' (duration: 14.389683ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:03.59831Z","caller":"traceutil/trace.go:171","msg":"trace[996404090] transaction","detail":"{read_only:false; response_revision:24269; number_of_response:1; }","duration":"170.347872ms","start":"2026-07-02T05:56:03.427927Z","end":"2026-07-02T05:56:03.598275Z","steps":["trace[996404090] 'process raft request' (duration: 170.135128ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:03.59841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.015991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ea450860-73ef-4c89-b6e9-0ed2811fde9f\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-07-02T05:56:03.598456Z","caller":"traceutil/trace.go:171","msg":"trace[866591886] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ea450860-73ef-4c89-b6e9-0ed2811fde9f; range_end:; response_count:1; response_revision:24269; }","duration":"108.117973ms","start":"2026-07-02T05:56:03.490326Z","end":"2026-07-02T05:56:03.598444Z","steps":["trace[866591886] 'agreement among raft nodes before linearized reading' (duration: 107.948949ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:03.845589Z","caller":"traceutil/trace.go:171","msg":"trace[562123451] linearizableReadLoop","detail":"{readStateIndex:25056; appliedIndex:25054; }","duration":"247.375155ms","start":"2026-07-02T05:56:03.59819Z","end":"2026-07-02T05:56:03.845565Z","steps":["trace[562123451] 'read index received' (duration: 225.979479ms)","trace[562123451] 'applied index is now lower than readState.Index' (duration: 21.394746ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:03.845837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.836711ms","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-07-02T05:56:03.845906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.425459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:56:03.845937Z","caller":"traceutil/trace.go:171","msg":"trace[1472474637] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24271; }","duration":"282.45239ms","start":"2026-07-02T05:56:03.563474Z","end":"2026-07-02T05:56:03.845927Z","steps":["trace[1472474637] 'agreement among raft nodes before linearized reading' (duration: 282.410029ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:03.84591Z","caller":"traceutil/trace.go:171","msg":"trace[1170609972] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24271; }","duration":"278.954433ms","start":"2026-07-02T05:56:03.566936Z","end":"2026-07-02T05:56:03.84589Z","steps":["trace[1170609972] 'agreement among raft nodes before linearized reading' (duration: 278.650667ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:03.845909Z","caller":"traceutil/trace.go:171","msg":"trace[1456503869] transaction","detail":"{read_only:false; response_revision:24270; number_of_response:1; }","duration":"305.339095ms","start":"2026-07-02T05:56:03.540533Z","end":"2026-07-02T05:56:03.845872Z","steps":["trace[1456503869] 'process raft request' (duration: 283.742035ms)","trace[1456503869] 'compare' (duration: 18.760066ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:03.846246Z","caller":"traceutil/trace.go:171","msg":"trace[950163408] transaction","detail":"{read_only:false; response_revision:24271; number_of_response:1; }","duration":"278.173059ms","start":"2026-07-02T05:56:03.568033Z","end":"2026-07-02T05:56:03.846206Z","steps":["trace[950163408] 'process raft request' (duration: 277.433204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:03.846509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.970384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T05:56:03.845863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.480032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-02T05:56:03.846562Z","caller":"traceutil/trace.go:171","msg":"trace[1419881800] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:24271; }","duration":"269.047995ms","start":"2026-07-02T05:56:03.577498Z","end":"2026-07-02T05:56:03.846546Z","steps":["trace[1419881800] 'agreement among raft nodes before linearized reading' (duration: 268.955903ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:03.846609Z","caller":"traceutil/trace.go:171","msg":"trace[642391325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24271; }","duration":"281.258438ms","start":"2026-07-02T05:56:03.565338Z","end":"2026-07-02T05:56:03.846596Z","steps":["trace[642391325] 'agreement among raft nodes before linearized reading' (duration: 280.433742ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:03.846629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.068985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-02T05:56:03.846672Z","caller":"traceutil/trace.go:171","msg":"trace[770438201] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24271; }","duration":"280.125456ms","start":"2026-07-02T05:56:03.566534Z","end":"2026-07-02T05:56:03.846659Z","steps":["trace[770438201] 'agreement among raft nodes before linearized reading' (duration: 279.987084ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:03.846818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.934121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-02T05:56:03.846917Z","caller":"traceutil/trace.go:171","msg":"trace[1850284298] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24271; }","duration":"281.051633ms","start":"2026-07-02T05:56:03.565843Z","end":"2026-07-02T05:56:03.846894Z","steps":["trace[1850284298] 'agreement among raft nodes before linearized reading' (duration: 279.758219ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:03.84625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:03.540515Z","time spent":"305.67024ms","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:56:04.405545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.154056ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:56:04.406049Z","caller":"traceutil/trace.go:171","msg":"trace[438874408] transaction","detail":"{read_only:false; response_revision:24273; number_of_response:1; }","duration":"555.158765ms","start":"2026-07-02T05:56:03.850863Z","end":"2026-07-02T05:56:04.406021Z","steps":["trace[438874408] 'process raft request' (duration: 185.446039ms)","trace[438874408] 'compare' (duration: 369.039924ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:04.406123Z","caller":"traceutil/trace.go:171","msg":"trace[1620267316] transaction","detail":"{read_only:false; response_revision:24275; number_of_response:1; }","duration":"555.116694ms","start":"2026-07-02T05:56:03.850985Z","end":"2026-07-02T05:56:04.406102Z","steps":["trace[1620267316] 'process raft request' (duration: 554.966202ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:04.406123Z","caller":"traceutil/trace.go:171","msg":"trace[1161766148] transaction","detail":"{read_only:false; response_revision:24274; number_of_response:1; }","duration":"555.217876ms","start":"2026-07-02T05:56:03.850878Z","end":"2026-07-02T05:56:04.406096Z","steps":["trace[1161766148] 'process raft request' (duration: 555.014102ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:04.406234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.046947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"warn","ts":"2026-07-02T05:56:04.406249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:03.850856Z","time spent":"555.335068ms","remote":"127.0.0.1:44726","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-07-02T05:56:04.406248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:03.850936Z","time spent":"555.252557ms","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:56:04.406277Z","caller":"traceutil/trace.go:171","msg":"trace[1127272472] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24276; }","duration":"336.091629ms","start":"2026-07-02T05:56:04.070174Z","end":"2026-07-02T05:56:04.406266Z","steps":["trace[1127272472] 'agreement among raft nodes before linearized reading' (duration: 335.932775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:04.406308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:04.070119Z","time spent":"336.182131ms","remote":"127.0.0.1:42746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3259,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-07-02T05:56:04.4064Z","caller":"traceutil/trace.go:171","msg":"trace[1004124434] transaction","detail":"{read_only:false; response_revision:24276; number_of_response:1; }","duration":"450.01617ms","start":"2026-07-02T05:56:03.956368Z","end":"2026-07-02T05:56:04.406384Z","steps":["trace[1004124434] 'process raft request' (duration: 449.651773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:04.40649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:03.956345Z","time spent":"450.101443ms","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:56:04.406073Z","caller":"traceutil/trace.go:171","msg":"trace[2056797479] linearizableReadLoop","detail":"{readStateIndex:25061; appliedIndex:25057; }","duration":"335.873604ms","start":"2026-07-02T05:56:04.070181Z","end":"2026-07-02T05:56:04.406055Z","steps":["trace[2056797479] 'read index received' (duration: 64.684889ms)","trace[2056797479] 'applied index is now lower than readState.Index' (duration: 271.187505ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:04.406915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:03.850854Z","time spent":"555.377849ms","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:56:04.444167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.944568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:56:04.444218Z","caller":"traceutil/trace.go:171","msg":"trace[2130141713] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:24277; }","duration":"138.03336ms","start":"2026-07-02T05:56:04.30617Z","end":"2026-07-02T05:56:04.444204Z","steps":["trace[2130141713] 'agreement among raft nodes before linearized reading' (duration: 137.937038ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:04.444178Z","caller":"traceutil/trace.go:171","msg":"trace[1979225257] transaction","detail":"{read_only:false; response_revision:24277; number_of_response:1; }","duration":"282.565622ms","start":"2026-07-02T05:56:04.161592Z","end":"2026-07-02T05:56:04.444157Z","steps":["trace[1979225257] 'process raft request' (duration: 282.408578ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:04.598739Z","caller":"traceutil/trace.go:171","msg":"trace[1637804211] transaction","detail":"{read_only:false; response_revision:24278; number_of_response:1; }","duration":"107.574962ms","start":"2026-07-02T05:56:04.491133Z","end":"2026-07-02T05:56:04.598708Z","steps":["trace[1637804211] 'process raft request' (duration: 28.649275ms)","trace[1637804211] 'compare' (duration: 78.747554ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:05.955278Z","caller":"traceutil/trace.go:171","msg":"trace[1652162396] transaction","detail":"{read_only:false; response_revision:24289; number_of_response:1; }","duration":"103.220489ms","start":"2026-07-02T05:56:05.852041Z","end":"2026-07-02T05:56:05.955262Z","steps":["trace[1652162396] 'process raft request' (duration: 102.963614ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:06.213188Z","caller":"traceutil/trace.go:171","msg":"trace[1174236436] transaction","detail":"{read_only:false; response_revision:24290; number_of_response:1; }","duration":"253.465699ms","start":"2026-07-02T05:56:05.959691Z","end":"2026-07-02T05:56:06.213156Z","steps":["trace[1174236436] 'process raft request' (duration: 249.949063ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:06.451083Z","caller":"traceutil/trace.go:171","msg":"trace[745066144] linearizableReadLoop","detail":"{readStateIndex:25076; appliedIndex:25075; }","duration":"110.845884ms","start":"2026-07-02T05:56:06.340214Z","end":"2026-07-02T05:56:06.45106Z","steps":["trace[745066144] 'read index received' (duration: 109.63013ms)","trace[745066144] 'applied index is now lower than readState.Index' (duration: 1.213574ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:06.451281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.044957ms","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-07-02T05:56:06.451327Z","caller":"traceutil/trace.go:171","msg":"trace[1765088053] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:24291; }","duration":"111.144429ms","start":"2026-07-02T05:56:06.34017Z","end":"2026-07-02T05:56:06.451314Z","steps":["trace[1765088053] 'agreement among raft nodes before linearized reading' (duration: 111.024947ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:08.05044Z","caller":"traceutil/trace.go:171","msg":"trace[245447959] linearizableReadLoop","detail":"{readStateIndex:25089; appliedIndex:25088; }","duration":"263.939468ms","start":"2026-07-02T05:56:07.786474Z","end":"2026-07-02T05:56:08.050413Z","steps":["trace[245447959] 'read index received' (duration: 255.699252ms)","trace[245447959] 'applied index is now lower than readState.Index' (duration: 8.238666ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:08.050585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.100261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ea450860-73ef-4c89-b6e9-0ed2811fde9f\" ","response":"range_response_count:1 size:3949"} {"level":"info","ts":"2026-07-02T05:56:08.050626Z","caller":"traceutil/trace.go:171","msg":"trace[891672937] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ea450860-73ef-4c89-b6e9-0ed2811fde9f; range_end:; response_count:1; response_revision:24303; }","duration":"264.184683ms","start":"2026-07-02T05:56:07.78643Z","end":"2026-07-02T05:56:08.050615Z","steps":["trace[891672937] 'agreement among raft nodes before linearized reading' (duration: 264.077991ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:08.05106Z","caller":"traceutil/trace.go:171","msg":"trace[519457108] transaction","detail":"{read_only:false; response_revision:24304; number_of_response:1; }","duration":"120.14397ms","start":"2026-07-02T05:56:07.930904Z","end":"2026-07-02T05:56:08.051048Z","steps":["trace[519457108] 'process raft request' (duration: 119.785702ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:08.050456Z","caller":"traceutil/trace.go:171","msg":"trace[1768127554] transaction","detail":"{read_only:false; response_revision:24303; number_of_response:1; }","duration":"281.689016ms","start":"2026-07-02T05:56:07.76874Z","end":"2026-07-02T05:56:08.050429Z","steps":["trace[1768127554] 'process raft request' (duration: 273.45993ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:10.786085Z","caller":"traceutil/trace.go:171","msg":"trace[927478950] linearizableReadLoop","detail":"{readStateIndex:25129; appliedIndex:25128; }","duration":"136.99384ms","start":"2026-07-02T05:56:10.649075Z","end":"2026-07-02T05:56:10.786068Z","steps":["trace[927478950] 'read index received' (duration: 136.854887ms)","trace[927478950] 'applied index is now lower than readState.Index' (duration: 138.363µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:10.786158Z","caller":"traceutil/trace.go:171","msg":"trace[200331686] transaction","detail":"{read_only:false; response_revision:24343; number_of_response:1; }","duration":"167.174142ms","start":"2026-07-02T05:56:10.618978Z","end":"2026-07-02T05:56:10.786152Z","steps":["trace[200331686] 'process raft request' (duration: 166.967469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:10.786299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.18592ms","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-07-02T05:56:10.786334Z","caller":"traceutil/trace.go:171","msg":"trace[617357021] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24343; }","duration":"111.256231ms","start":"2026-07-02T05:56:10.675067Z","end":"2026-07-02T05:56:10.786323Z","steps":["trace[617357021] 'agreement among raft nodes before linearized reading' (duration: 111.140158ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:10.78638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.313885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:56:10.786399Z","caller":"traceutil/trace.go:171","msg":"trace[607477084] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24343; }","duration":"137.368587ms","start":"2026-07-02T05:56:10.649026Z","end":"2026-07-02T05:56:10.786395Z","steps":["trace[607477084] 'agreement among raft nodes before linearized reading' (duration: 137.329766ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:10.786425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.242401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-02T05:56:10.786445Z","caller":"traceutil/trace.go:171","msg":"trace[1007087351] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24343; }","duration":"101.292992ms","start":"2026-07-02T05:56:10.685143Z","end":"2026-07-02T05:56:10.786436Z","steps":["trace[1007087351] 'agreement among raft nodes before linearized reading' (duration: 101.239961ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:11.062619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.59338ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:56:11.062758Z","caller":"traceutil/trace.go:171","msg":"trace[646695280] transaction","detail":"{read_only:false; response_revision:24344; number_of_response:1; }","duration":"272.904049ms","start":"2026-07-02T05:56:10.789833Z","end":"2026-07-02T05:56:11.062737Z","steps":["trace[646695280] 'process raft request' (duration: 152.097575ms)","trace[646695280] 'compare' (duration: 120.236032ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:11.070057Z","caller":"traceutil/trace.go:171","msg":"trace[1031841412] linearizableReadLoop","detail":"{readStateIndex:25131; appliedIndex:25129; }","duration":"278.023347ms","start":"2026-07-02T05:56:10.792016Z","end":"2026-07-02T05:56:11.070039Z","steps":["trace[1031841412] 'read index received' (duration: 149.927046ms)","trace[1031841412] 'applied index is now lower than readState.Index' (duration: 128.095041ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:11.070099Z","caller":"traceutil/trace.go:171","msg":"trace[328727671] transaction","detail":"{read_only:false; response_revision:24345; number_of_response:1; }","duration":"278.76425ms","start":"2026-07-02T05:56:10.791318Z","end":"2026-07-02T05:56:11.070083Z","steps":["trace[328727671] 'process raft request' (duration: 278.606337ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:11.070216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.912913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10358"} {"level":"info","ts":"2026-07-02T05:56:11.070234Z","caller":"traceutil/trace.go:171","msg":"trace[227996968] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24345; }","duration":"220.974254ms","start":"2026-07-02T05:56:10.849255Z","end":"2026-07-02T05:56:11.070229Z","steps":["trace[227996968] 'agreement among raft nodes before linearized reading' (duration: 220.870662ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:11.070244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.216171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-02T05:56:11.070296Z","caller":"traceutil/trace.go:171","msg":"trace[1126911041] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24345; }","duration":"278.308502ms","start":"2026-07-02T05:56:10.791971Z","end":"2026-07-02T05:56:11.07028Z","steps":["trace[1126911041] 'agreement among raft nodes before linearized reading' (duration: 278.137339ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:11.070309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.841341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-07-02T05:56:11.070354Z","caller":"traceutil/trace.go:171","msg":"trace[546137336] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24345; }","duration":"132.913893ms","start":"2026-07-02T05:56:10.937427Z","end":"2026-07-02T05:56:11.070341Z","steps":["trace[546137336] 'agreement among raft nodes before linearized reading' (duration: 132.77278ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:37.737287Z","caller":"traceutil/trace.go:171","msg":"trace[912690871] transaction","detail":"{read_only:false; response_revision:24561; number_of_response:1; }","duration":"170.085447ms","start":"2026-07-02T05:56:37.567173Z","end":"2026-07-02T05:56:37.737259Z","steps":["trace[912690871] 'process raft request' (duration: 169.914644ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:37.768785Z","caller":"traceutil/trace.go:171","msg":"trace[1340591719] transaction","detail":"{read_only:false; response_revision:24562; number_of_response:1; }","duration":"134.520074ms","start":"2026-07-02T05:56:37.634214Z","end":"2026-07-02T05:56:37.768734Z","steps":["trace[1340591719] 'process raft request' (duration: 134.218488ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:42.329654Z","caller":"traceutil/trace.go:171","msg":"trace[707691592] transaction","detail":"{read_only:false; response_revision:24595; number_of_response:1; }","duration":"102.74754ms","start":"2026-07-02T05:56:42.226886Z","end":"2026-07-02T05:56:42.329634Z","steps":["trace[707691592] 'process raft request' (duration: 102.578327ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:43.156862Z","caller":"traceutil/trace.go:171","msg":"trace[2014657078] transaction","detail":"{read_only:false; response_revision:24603; number_of_response:1; }","duration":"240.432453ms","start":"2026-07-02T05:56:42.916396Z","end":"2026-07-02T05:56:43.156829Z","steps":["trace[2014657078] 'process raft request' (duration: 240.24942ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:43.439761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.846145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-07-02T05:56:43.43978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.670553ms","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-07-02T05:56:43.439827Z","caller":"traceutil/trace.go:171","msg":"trace[1192926889] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24603; }","duration":"197.931466ms","start":"2026-07-02T05:56:43.24188Z","end":"2026-07-02T05:56:43.439811Z","steps":["trace[1192926889] 'range keys from in-memory index tree' (duration: 197.641121ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:43.439856Z","caller":"traceutil/trace.go:171","msg":"trace[138940433] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24603; }","duration":"166.817116ms","start":"2026-07-02T05:56:43.273022Z","end":"2026-07-02T05:56:43.439839Z","steps":["trace[138940433] 'range keys from in-memory index tree' (duration: 166.480059ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:43.440241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.794493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418304"} {"level":"info","ts":"2026-07-02T05:56:43.440543Z","caller":"traceutil/trace.go:171","msg":"trace[1796278311] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:24603; }","duration":"218.149039ms","start":"2026-07-02T05:56:43.222365Z","end":"2026-07-02T05:56:43.440514Z","steps":["trace[1796278311] 'range keys from in-memory index tree' (duration: 217.361245ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:43.584106Z","caller":"traceutil/trace.go:171","msg":"trace[458925360] transaction","detail":"{read_only:false; response_revision:24604; number_of_response:1; }","duration":"139.434246ms","start":"2026-07-02T05:56:43.444645Z","end":"2026-07-02T05:56:43.584079Z","steps":["trace[458925360] 'process raft request' (duration: 139.237203ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:43.591724Z","caller":"traceutil/trace.go:171","msg":"trace[1745621177] transaction","detail":"{read_only:false; response_revision:24605; number_of_response:1; }","duration":"146.02563ms","start":"2026-07-02T05:56:43.445682Z","end":"2026-07-02T05:56:43.591708Z","steps":["trace[1745621177] 'process raft request' (duration: 145.789986ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:43.918691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.766119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T05:56:43.918712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.768111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:56:43.918776Z","caller":"traceutil/trace.go:171","msg":"trace[729124521] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:24605; }","duration":"231.924851ms","start":"2026-07-02T05:56:43.686833Z","end":"2026-07-02T05:56:43.918757Z","steps":["trace[729124521] 'count revisions from in-memory index tree' (duration: 231.668417ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:43.918791Z","caller":"traceutil/trace.go:171","msg":"trace[1473393982] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:24605; }","duration":"170.868903ms","start":"2026-07-02T05:56:43.747902Z","end":"2026-07-02T05:56:43.918771Z","steps":["trace[1473393982] 'count revisions from in-memory index tree' (duration: 170.663489ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:43.918918Z","caller":"traceutil/trace.go:171","msg":"trace[521438215] transaction","detail":"{read_only:false; response_revision:24606; number_of_response:1; }","duration":"144.026984ms","start":"2026-07-02T05:56:43.774871Z","end":"2026-07-02T05:56:43.918898Z","steps":["trace[521438215] 'process raft request' (duration: 134.609415ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:44.068017Z","caller":"traceutil/trace.go:171","msg":"trace[2050258461] transaction","detail":"{read_only:false; response_revision:24607; number_of_response:1; }","duration":"273.530121ms","start":"2026-07-02T05:56:43.794456Z","end":"2026-07-02T05:56:44.067986Z","steps":["trace[2050258461] 'process raft request' (duration: 273.325477ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:44.068395Z","caller":"traceutil/trace.go:171","msg":"trace[1784557029] transaction","detail":"{read_only:false; response_revision:24608; number_of_response:1; }","duration":"270.019124ms","start":"2026-07-02T05:56:43.798298Z","end":"2026-07-02T05:56:44.068317Z","steps":["trace[1784557029] 'process raft request' (duration: 269.646748ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:44.068497Z","caller":"traceutil/trace.go:171","msg":"trace[1002675707] transaction","detail":"{read_only:false; response_revision:24609; number_of_response:1; }","duration":"256.467396ms","start":"2026-07-02T05:56:43.81201Z","end":"2026-07-02T05:56:44.068478Z","steps":["trace[1002675707] 'process raft request' (duration: 256.203702ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:44.068798Z","caller":"traceutil/trace.go:171","msg":"trace[483749356] transaction","detail":"{read_only:false; response_revision:24610; number_of_response:1; }","duration":"164.517542ms","start":"2026-07-02T05:56:43.904257Z","end":"2026-07-02T05:56:44.068774Z","steps":["trace[483749356] 'process raft request' (duration: 164.152115ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:44.347064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.084958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:56:44.347145Z","caller":"traceutil/trace.go:171","msg":"trace[217474220] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:24610; }","duration":"102.20412ms","start":"2026-07-02T05:56:44.244921Z","end":"2026-07-02T05:56:44.347125Z","steps":["trace[217474220] 'count revisions from in-memory index tree' (duration: 102.005986ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:44.880664Z","caller":"traceutil/trace.go:171","msg":"trace[282173979] transaction","detail":"{read_only:false; response_revision:24613; number_of_response:1; }","duration":"228.385674ms","start":"2026-07-02T05:56:44.652239Z","end":"2026-07-02T05:56:44.880624Z","steps":["trace[282173979] 'process raft request' (duration: 228.18899ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:45.56091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.17756ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:56:45.561017Z","caller":"traceutil/trace.go:171","msg":"trace[482164897] linearizableReadLoop","detail":"{readStateIndex:25408; appliedIndex:25407; }","duration":"868.222667ms","start":"2026-07-02T05:56:44.692775Z","end":"2026-07-02T05:56:45.560998Z","steps":["trace[482164897] 'read index received' (duration: 188.293133ms)","trace[482164897] 'applied index is now lower than readState.Index' (duration: 679.928164ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:45.561039Z","caller":"traceutil/trace.go:171","msg":"trace[123968175] transaction","detail":"{read_only:false; response_revision:24614; number_of_response:1; }","duration":"880.918667ms","start":"2026-07-02T05:56:44.680093Z","end":"2026-07-02T05:56:45.561012Z","steps":["trace[123968175] 'process raft request' (duration: 740.533653ms)","trace[123968175] 'compare' (duration: 139.830474ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:45.561158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:44.680073Z","time spent":"881.023639ms","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:56:45.561183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.41022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-02T05:56:45.561233Z","caller":"traceutil/trace.go:171","msg":"trace[193657260] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24614; }","duration":"868.472551ms","start":"2026-07-02T05:56:44.692731Z","end":"2026-07-02T05:56:45.561203Z","steps":["trace[193657260] 'agreement among raft nodes before linearized reading' (duration: 868.337379ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:45.561272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:44.692712Z","time spent":"868.550872ms","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-02T05:56:45.892995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.054145ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:56:45.893212Z","caller":"traceutil/trace.go:171","msg":"trace[1230960978] transaction","detail":"{read_only:false; response_revision:24616; number_of_response:1; }","duration":"327.455144ms","start":"2026-07-02T05:56:45.565727Z","end":"2026-07-02T05:56:45.893183Z","steps":["trace[1230960978] 'process raft request' (duration: 327.385283ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:45.893254Z","caller":"traceutil/trace.go:171","msg":"trace[351650865] linearizableReadLoop","detail":"{readStateIndex:25409; appliedIndex:25408; }","duration":"332.181894ms","start":"2026-07-02T05:56:45.561043Z","end":"2026-07-02T05:56:45.893225Z","steps":["trace[351650865] 'read index received' (duration: 140.813683ms)","trace[351650865] 'applied index is now lower than readState.Index' (duration: 191.365861ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:45.893356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:45.565709Z","time spent":"327.562736ms","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:56:45.893415Z","caller":"traceutil/trace.go:171","msg":"trace[1141351879] transaction","detail":"{read_only:false; response_revision:24615; number_of_response:1; }","duration":"731.458181ms","start":"2026-07-02T05:56:45.161943Z","end":"2026-07-02T05:56:45.893402Z","steps":["trace[1141351879] 'process raft request' (duration: 539.895117ms)","trace[1141351879] 'compare' (duration: 190.881762ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:45.893541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:45.161917Z","time spent":"731.534542ms","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:56:45.893851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.034402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-02T05:56:45.893907Z","caller":"traceutil/trace.go:171","msg":"trace[993770200] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:24616; }","duration":"497.122564ms","start":"2026-07-02T05:56:45.396773Z","end":"2026-07-02T05:56:45.893896Z","steps":["trace[993770200] 'agreement among raft nodes before linearized reading' (duration: 496.89157ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:45.89394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:45.396749Z","time spent":"497.182875ms","remote":"127.0.0.1:39012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":162,"response size":32,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:56:45.8942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.148870453s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T05:56:45.894265Z","caller":"traceutil/trace.go:171","msg":"trace[1177007317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24616; }","duration":"1.148970805s","start":"2026-07-02T05:56:44.745278Z","end":"2026-07-02T05:56:45.894249Z","steps":["trace[1177007317] 'agreement among raft nodes before linearized reading' (duration: 1.148782161s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:45.894296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.309329ms","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":"warn","ts":"2026-07-02T05:56:45.894339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.437526ms","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":"info","ts":"2026-07-02T05:56:45.894381Z","caller":"traceutil/trace.go:171","msg":"trace[585085716] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24616; }","duration":"759.447482ms","start":"2026-07-02T05:56:45.134915Z","end":"2026-07-02T05:56:45.894363Z","steps":["trace[585085716] 'agreement among raft nodes before linearized reading' (duration: 759.297529ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:45.894403Z","caller":"traceutil/trace.go:171","msg":"trace[313120798] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:24616; }","duration":"227.580078ms","start":"2026-07-02T05:56:45.666807Z","end":"2026-07-02T05:56:45.894387Z","steps":["trace[313120798] 'agreement among raft nodes before linearized reading' (duration: 227.458946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:45.894401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.113627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-07-02T05:56:45.894419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005709476s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-07-02T05:56:45.894432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:45.134893Z","time spent":"759.527583ms","remote":"127.0.0.1:34880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:56:45.894328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:44.745259Z","time spent":"1.149060396s","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-02T05:56:45.894456Z","caller":"traceutil/trace.go:171","msg":"trace[1707027926] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24616; }","duration":"298.187229ms","start":"2026-07-02T05:56:45.596252Z","end":"2026-07-02T05:56:45.894439Z","steps":["trace[1707027926] 'agreement among raft nodes before linearized reading' (duration: 298.059607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:45.894282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.716417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T05:56:45.894496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.190297909s","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-07-02T05:56:45.894593Z","caller":"traceutil/trace.go:171","msg":"trace[1149018839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24616; }","duration":"1.190449022s","start":"2026-07-02T05:56:44.704124Z","end":"2026-07-02T05:56:45.894573Z","steps":["trace[1149018839] 'agreement among raft nodes before linearized reading' (duration: 1.190153936s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:45.894645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:44.704097Z","time spent":"1.190538553s","remote":"127.0.0.1:44726","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-07-02T05:56:45.894732Z","caller":"traceutil/trace.go:171","msg":"trace[1329908910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24616; }","duration":"330.093494ms","start":"2026-07-02T05:56:45.564508Z","end":"2026-07-02T05:56:45.894601Z","steps":["trace[1329908910] 'agreement among raft nodes before linearized reading' (duration: 329.735828ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:45.89445Z","caller":"traceutil/trace.go:171","msg":"trace[1265093433] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24616; }","duration":"1.005769666s","start":"2026-07-02T05:56:44.888671Z","end":"2026-07-02T05:56:45.894441Z","steps":["trace[1265093433] 'agreement among raft nodes before linearized reading' (duration: 1.005680085s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:45.894883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:44.888655Z","time spent":"1.006214315s","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":555,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-07-02T05:56:45.894499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.6742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-02T05:56:45.894962Z","caller":"traceutil/trace.go:171","msg":"trace[1731872163] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24616; }","duration":"307.169049ms","start":"2026-07-02T05:56:45.587775Z","end":"2026-07-02T05:56:45.894944Z","steps":["trace[1731872163] 'agreement among raft nodes before linearized reading' (duration: 306.608248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:45.895024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:45.587754Z","time spent":"307.254722ms","remote":"127.0.0.1:44726","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-07-02T05:56:45.895153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:45.564487Z","time spent":"330.36236ms","remote":"127.0.0.1:44454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T05:56:46.132952Z","caller":"traceutil/trace.go:171","msg":"trace[1088987509] transaction","detail":"{read_only:false; response_revision:24617; number_of_response:1; }","duration":"233.760166ms","start":"2026-07-02T05:56:45.89915Z","end":"2026-07-02T05:56:46.13291Z","steps":["trace[1088987509] 'process raft request' (duration: 157.596421ms)","trace[1088987509] 'compare' (duration: 75.906121ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:46.37717Z","caller":"traceutil/trace.go:171","msg":"trace[1043370562] transaction","detail":"{read_only:false; response_revision:24618; number_of_response:1; }","duration":"478.210656ms","start":"2026-07-02T05:56:45.898932Z","end":"2026-07-02T05:56:46.377142Z","steps":["trace[1043370562] 'process raft request' (duration: 421.954358ms)","trace[1043370562] 'compare' (duration: 56.058714ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:46.377237Z","caller":"traceutil/trace.go:171","msg":"trace[523405013] transaction","detail":"{read_only:false; response_revision:24620; number_of_response:1; }","duration":"477.726666ms","start":"2026-07-02T05:56:45.899485Z","end":"2026-07-02T05:56:46.377211Z","steps":["trace[523405013] 'process raft request' (duration: 477.643794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:46.3773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:45.898915Z","time spent":"478.314617ms","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:56:46.377375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:45.899479Z","time spent":"477.821178ms","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:56:46.377518Z","caller":"traceutil/trace.go:171","msg":"trace[949084145] transaction","detail":"{read_only:false; response_revision:24623; number_of_response:1; }","duration":"373.08434ms","start":"2026-07-02T05:56:46.0044Z","end":"2026-07-02T05:56:46.377485Z","steps":["trace[949084145] 'process raft request' (duration: 373.020399ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:46.377678Z","caller":"traceutil/trace.go:171","msg":"trace[1046027935] transaction","detail":"{read_only:false; response_revision:24619; number_of_response:1; }","duration":"478.351197ms","start":"2026-07-02T05:56:45.899311Z","end":"2026-07-02T05:56:46.377662Z","steps":["trace[1046027935] 'process raft request' (duration: 477.771747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:46.377727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:46.004375Z","time spent":"373.263713ms","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":472,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:56:46.37775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:45.899293Z","time spent":"478.420528ms","remote":"127.0.0.1:44726","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-07-02T05:56:46.377803Z","caller":"traceutil/trace.go:171","msg":"trace[2147299284] transaction","detail":"{read_only:false; response_revision:24622; number_of_response:1; }","duration":"453.73909ms","start":"2026-07-02T05:56:45.924049Z","end":"2026-07-02T05:56:46.377788Z","steps":["trace[2147299284] 'process raft request' (duration: 453.266432ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:46.377831Z","caller":"traceutil/trace.go:171","msg":"trace[1194877856] transaction","detail":"{read_only:false; response_revision:24621; number_of_response:1; }","duration":"478.260926ms","start":"2026-07-02T05:56:45.899532Z","end":"2026-07-02T05:56:46.377793Z","steps":["trace[1194877856] 'process raft request' (duration: 477.636934ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:46.377879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:45.924035Z","time spent":"453.800041ms","remote":"127.0.0.1:44726","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-07-02T05:56:46.378119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:45.899524Z","time spent":"478.452899ms","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:56:46.691912Z","caller":"traceutil/trace.go:171","msg":"trace[47497047] transaction","detail":"{read_only:false; response_revision:24624; number_of_response:1; }","duration":"618.565429ms","start":"2026-07-02T05:56:46.073313Z","end":"2026-07-02T05:56:46.691878Z","steps":["trace[47497047] 'process raft request' (duration: 589.460376ms)","trace[47497047] 'compare' (duration: 28.94093ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:46.692032Z","caller":"traceutil/trace.go:171","msg":"trace[1655819515] transaction","detail":"{read_only:false; response_revision:24625; number_of_response:1; }","duration":"618.61945ms","start":"2026-07-02T05:56:46.073387Z","end":"2026-07-02T05:56:46.692007Z","steps":["trace[1655819515] 'process raft request' (duration: 618.449427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:46.692085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:46.073296Z","time spent":"618.695381ms","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:56:46.692153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:46.07338Z","time spent":"618.703871ms","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:56:46.763878Z","caller":"traceutil/trace.go:171","msg":"trace[238317262] transaction","detail":"{read_only:false; response_revision:24626; number_of_response:1; }","duration":"408.758827ms","start":"2026-07-02T05:56:46.355086Z","end":"2026-07-02T05:56:46.763845Z","steps":["trace[238317262] 'process raft request' (duration: 408.575944ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:46.76403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:46.355058Z","time spent":"408.89236ms","remote":"127.0.0.1:44726","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-07-02T05:56:46.847079Z","caller":"traceutil/trace.go:171","msg":"trace[1760220747] linearizableReadLoop","detail":"{readStateIndex:25420; appliedIndex:25417; }","duration":"490.958017ms","start":"2026-07-02T05:56:46.356089Z","end":"2026-07-02T05:56:46.847047Z","steps":["trace[1760220747] 'read index received' (duration: 306.64005ms)","trace[1760220747] 'applied index is now lower than readState.Index' (duration: 184.301166ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:46.847297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.195902ms","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-07-02T05:56:46.847363Z","caller":"traceutil/trace.go:171","msg":"trace[941424837] 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:24626; }","duration":"491.275053ms","start":"2026-07-02T05:56:46.356074Z","end":"2026-07-02T05:56:46.847349Z","steps":["trace[941424837] 'agreement among raft nodes before linearized reading' (duration: 491.148561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:46.847399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:46.355996Z","time spent":"491.394875ms","remote":"127.0.0.1:56562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-07-02T05:56:46.847415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.767518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-07-02T05:56:46.84741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.587413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:56:46.847446Z","caller":"traceutil/trace.go:171","msg":"trace[1284077162] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24626; }","duration":"339.82603ms","start":"2026-07-02T05:56:46.507605Z","end":"2026-07-02T05:56:46.847431Z","steps":["trace[1284077162] 'agreement among raft nodes before linearized reading' (duration: 339.741868ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:46.847469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:46.507586Z","time spent":"339.87867ms","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-02T05:56:46.847478Z","caller":"traceutil/trace.go:171","msg":"trace[1256384606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24626; }","duration":"282.702865ms","start":"2026-07-02T05:56:46.564756Z","end":"2026-07-02T05:56:46.847458Z","steps":["trace[1256384606] 'agreement among raft nodes before linearized reading' (duration: 282.582873ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:46.847313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.823946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T05:56:46.847546Z","caller":"traceutil/trace.go:171","msg":"trace[1663540781] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:24626; }","duration":"392.106932ms","start":"2026-07-02T05:56:46.455431Z","end":"2026-07-02T05:56:46.847537Z","steps":["trace[1663540781] 'agreement among raft nodes before linearized reading' (duration: 391.811956ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:46.847573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:46.455407Z","time spent":"392.156482ms","remote":"127.0.0.1:42746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":10,"response size":31,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:56:47.097504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.877978ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:56:47.097762Z","caller":"traceutil/trace.go:171","msg":"trace[421057968] transaction","detail":"{read_only:false; response_revision:24628; number_of_response:1; }","duration":"205.699954ms","start":"2026-07-02T05:56:46.892019Z","end":"2026-07-02T05:56:47.097719Z","steps":["trace[421057968] 'process raft request' (duration: 59.477338ms)","trace[421057968] 'compare' (duration: 145.42702ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:47.097974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.633743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:56:47.098072Z","caller":"traceutil/trace.go:171","msg":"trace[1687356501] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24628; }","duration":"244.792706ms","start":"2026-07-02T05:56:46.853258Z","end":"2026-07-02T05:56:47.098051Z","steps":["trace[1687356501] 'agreement among raft nodes before linearized reading' (duration: 244.598162ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:47.097824Z","caller":"traceutil/trace.go:171","msg":"trace[539436024] linearizableReadLoop","detail":"{readStateIndex:25423; appliedIndex:25422; }","duration":"169.532987ms","start":"2026-07-02T05:56:46.928087Z","end":"2026-07-02T05:56:47.09762Z","steps":["trace[539436024] 'read index received' (duration: 23.331413ms)","trace[539436024] 'applied index is now lower than readState.Index' (duration: 146.199334ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:47.099196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.947958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:56:47.099302Z","caller":"traceutil/trace.go:171","msg":"trace[691951780] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24628; }","duration":"119.07405ms","start":"2026-07-02T05:56:46.980201Z","end":"2026-07-02T05:56:47.099275Z","steps":["trace[691951780] 'agreement among raft nodes before linearized reading' (duration: 118.877805ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:47.693211Z","caller":"traceutil/trace.go:171","msg":"trace[730604056] linearizableReadLoop","detail":"{readStateIndex:25424; appliedIndex:25423; }","duration":"114.719777ms","start":"2026-07-02T05:56:47.578474Z","end":"2026-07-02T05:56:47.693194Z","steps":["trace[730604056] 'read index received' (duration: 114.595245ms)","trace[730604056] 'applied index is now lower than readState.Index' (duration: 123.962µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:47.693382Z","caller":"traceutil/trace.go:171","msg":"trace[840427904] transaction","detail":"{read_only:false; response_revision:24629; number_of_response:1; }","duration":"123.728818ms","start":"2026-07-02T05:56:47.569647Z","end":"2026-07-02T05:56:47.693376Z","steps":["trace[840427904] 'process raft request' (duration: 123.445383ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:47.693517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.032623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:56:47.693541Z","caller":"traceutil/trace.go:171","msg":"trace[381696233] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24629; }","duration":"115.095694ms","start":"2026-07-02T05:56:47.57844Z","end":"2026-07-02T05:56:47.693536Z","steps":["trace[381696233] 'agreement among raft nodes before linearized reading' (duration: 115.046383ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:48.070103Z","caller":"traceutil/trace.go:171","msg":"trace[116679684] transaction","detail":"{read_only:false; response_revision:24630; number_of_response:1; }","duration":"168.409006ms","start":"2026-07-02T05:56:47.901668Z","end":"2026-07-02T05:56:48.070077Z","steps":["trace[116679684] 'process raft request' (duration: 168.222213ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:48.113273Z","caller":"traceutil/trace.go:171","msg":"trace[145751426] transaction","detail":"{read_only:false; response_revision:24631; number_of_response:1; }","duration":"206.650822ms","start":"2026-07-02T05:56:47.906598Z","end":"2026-07-02T05:56:48.113249Z","steps":["trace[145751426] 'process raft request' (duration: 206.5229ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:48.607406Z","caller":"traceutil/trace.go:171","msg":"trace[639429296] transaction","detail":"{read_only:false; response_revision:24636; number_of_response:1; }","duration":"220.526166ms","start":"2026-07-02T05:56:48.386856Z","end":"2026-07-02T05:56:48.607382Z","steps":["trace[639429296] 'process raft request' (duration: 220.475435ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:48.60753Z","caller":"traceutil/trace.go:171","msg":"trace[1175900476] transaction","detail":"{read_only:false; response_revision:24633; number_of_response:1; }","duration":"221.31866ms","start":"2026-07-02T05:56:48.386183Z","end":"2026-07-02T05:56:48.607502Z","steps":["trace[1175900476] 'process raft request' (duration: 219.208949ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:48.607657Z","caller":"traceutil/trace.go:171","msg":"trace[163287845] transaction","detail":"{read_only:false; response_revision:24634; number_of_response:1; }","duration":"221.411731ms","start":"2026-07-02T05:56:48.386234Z","end":"2026-07-02T05:56:48.607646Z","steps":["trace[163287845] 'process raft request' (duration: 220.982194ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:48.607749Z","caller":"traceutil/trace.go:171","msg":"trace[1754558945] transaction","detail":"{read_only:false; response_revision:24635; number_of_response:1; }","duration":"220.902252ms","start":"2026-07-02T05:56:48.386836Z","end":"2026-07-02T05:56:48.607739Z","steps":["trace[1754558945] 'process raft request' (duration: 220.464304ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:49.04108Z","caller":"traceutil/trace.go:171","msg":"trace[871970353] linearizableReadLoop","detail":"{readStateIndex:25436; appliedIndex:25435; }","duration":"146.368407ms","start":"2026-07-02T05:56:48.894689Z","end":"2026-07-02T05:56:49.041058Z","steps":["trace[871970353] 'read index received' (duration: 75.273459ms)","trace[871970353] 'applied index is now lower than readState.Index' (duration: 71.093788ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:49.041222Z","caller":"traceutil/trace.go:171","msg":"trace[1718322523] transaction","detail":"{read_only:false; response_revision:24641; number_of_response:1; }","duration":"245.279356ms","start":"2026-07-02T05:56:48.795926Z","end":"2026-07-02T05:56:49.041205Z","steps":["trace[1718322523] 'process raft request' (duration: 174.129855ms)","trace[1718322523] 'compare' (duration: 70.490338ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:49.041435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.733464ms","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-07-02T05:56:49.041481Z","caller":"traceutil/trace.go:171","msg":"trace[2087211893] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:24641; }","duration":"146.795055ms","start":"2026-07-02T05:56:48.894673Z","end":"2026-07-02T05:56:49.041468Z","steps":["trace[2087211893] 'agreement among raft nodes before linearized reading' (duration: 146.708574ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:49.041551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.001868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-02T05:56:49.041607Z","caller":"traceutil/trace.go:171","msg":"trace[613269674] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24641; }","duration":"110.09762ms","start":"2026-07-02T05:56:48.931496Z","end":"2026-07-02T05:56:49.041593Z","steps":["trace[613269674] 'agreement among raft nodes before linearized reading' (duration: 109.934997ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:49.278794Z","caller":"traceutil/trace.go:171","msg":"trace[2103899440] transaction","detail":"{read_only:false; response_revision:24643; number_of_response:1; }","duration":"205.252695ms","start":"2026-07-02T05:56:49.073513Z","end":"2026-07-02T05:56:49.278766Z","steps":["trace[2103899440] 'process raft request' (duration: 133.3302ms)","trace[2103899440] 'compare' (duration: 71.754181ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:49.278945Z","caller":"traceutil/trace.go:171","msg":"trace[2012315185] linearizableReadLoop","detail":"{readStateIndex:25439; appliedIndex:25437; }","duration":"164.98903ms","start":"2026-07-02T05:56:49.113924Z","end":"2026-07-02T05:56:49.278913Z","steps":["trace[2012315185] 'read index received' (duration: 92.810021ms)","trace[2012315185] 'applied index is now lower than readState.Index' (duration: 72.176719ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:49.279035Z","caller":"traceutil/trace.go:171","msg":"trace[346104384] transaction","detail":"{read_only:false; response_revision:24644; number_of_response:1; }","duration":"175.985859ms","start":"2026-07-02T05:56:49.103015Z","end":"2026-07-02T05:56:49.279001Z","steps":["trace[346104384] 'process raft request' (duration: 175.712784ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:49.279157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.549701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T05:56:49.279203Z","caller":"traceutil/trace.go:171","msg":"trace[2013176187] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:24644; }","duration":"185.638773ms","start":"2026-07-02T05:56:49.093552Z","end":"2026-07-02T05:56:49.279191Z","steps":["trace[2013176187] 'agreement among raft nodes before linearized reading' (duration: 185.462259ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:50.322199Z","caller":"traceutil/trace.go:171","msg":"trace[1898737431] linearizableReadLoop","detail":"{readStateIndex:25442; appliedIndex:25441; }","duration":"158.945386ms","start":"2026-07-02T05:56:50.163229Z","end":"2026-07-02T05:56:50.322174Z","steps":["trace[1898737431] 'read index received' (duration: 10.497579ms)","trace[1898737431] 'applied index is now lower than readState.Index' (duration: 148.446517ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:50.322307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.065889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T05:56:50.32238Z","caller":"traceutil/trace.go:171","msg":"trace[97517966] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:24647; }","duration":"159.193592ms","start":"2026-07-02T05:56:50.163171Z","end":"2026-07-02T05:56:50.322364Z","steps":["trace[97517966] 'agreement among raft nodes before linearized reading' (duration: 159.074849ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:50.322233Z","caller":"traceutil/trace.go:171","msg":"trace[1029986431] transaction","detail":"{read_only:false; response_revision:24647; number_of_response:1; }","duration":"203.655355ms","start":"2026-07-02T05:56:50.118547Z","end":"2026-07-02T05:56:50.322203Z","steps":["trace[1029986431] 'process raft request' (duration: 130.634239ms)","trace[1029986431] 'compare' (duration: 72.617098ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:50.579466Z","caller":"traceutil/trace.go:171","msg":"trace[1899548285] transaction","detail":"{read_only:false; response_revision:24648; number_of_response:1; }","duration":"122.191669ms","start":"2026-07-02T05:56:50.457245Z","end":"2026-07-02T05:56:50.579437Z","steps":["trace[1899548285] 'process raft request' (duration: 39.784715ms)","trace[1899548285] 'compare' (duration: 82.19411ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:50.863995Z","caller":"traceutil/trace.go:171","msg":"trace[667966431] transaction","detail":"{read_only:false; response_revision:24650; number_of_response:1; }","duration":"242.288328ms","start":"2026-07-02T05:56:50.621672Z","end":"2026-07-02T05:56:50.86396Z","steps":["trace[667966431] 'process raft request' (duration: 241.994372ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:50.864216Z","caller":"traceutil/trace.go:171","msg":"trace[1637240076] transaction","detail":"{read_only:false; response_revision:24652; number_of_response:1; }","duration":"242.448802ms","start":"2026-07-02T05:56:50.621748Z","end":"2026-07-02T05:56:50.864197Z","steps":["trace[1637240076] 'process raft request' (duration: 242.35503ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:50.864224Z","caller":"traceutil/trace.go:171","msg":"trace[1344191379] transaction","detail":"{read_only:false; response_revision:24651; number_of_response:1; }","duration":"242.520533ms","start":"2026-07-02T05:56:50.621683Z","end":"2026-07-02T05:56:50.864203Z","steps":["trace[1344191379] 'process raft request' (duration: 242.225467ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:50.974651Z","caller":"traceutil/trace.go:171","msg":"trace[1658291552] transaction","detail":"{read_only:false; response_revision:24653; number_of_response:1; }","duration":"173.772208ms","start":"2026-07-02T05:56:50.80086Z","end":"2026-07-02T05:56:50.974632Z","steps":["trace[1658291552] 'process raft request' (duration: 79.080131ms)","trace[1658291552] 'compare' (duration: 94.284369ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:50.974747Z","caller":"traceutil/trace.go:171","msg":"trace[1303640037] transaction","detail":"{read_only:false; response_revision:24654; number_of_response:1; }","duration":"172.306091ms","start":"2026-07-02T05:56:50.802436Z","end":"2026-07-02T05:56:50.974742Z","steps":["trace[1303640037] 'process raft request' (duration: 171.922683ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:50.974896Z","caller":"traceutil/trace.go:171","msg":"trace[1205625682] transaction","detail":"{read_only:false; response_revision:24655; number_of_response:1; }","duration":"110.906195ms","start":"2026-07-02T05:56:50.863954Z","end":"2026-07-02T05:56:50.97486Z","steps":["trace[1205625682] 'process raft request' (duration: 110.435175ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:51.392572Z","caller":"traceutil/trace.go:171","msg":"trace[2048165210] linearizableReadLoop","detail":"{readStateIndex:25453; appliedIndex:25452; }","duration":"180.915644ms","start":"2026-07-02T05:56:51.211639Z","end":"2026-07-02T05:56:51.392555Z","steps":["trace[2048165210] 'read index received' (duration: 180.840172ms)","trace[2048165210] 'applied index is now lower than readState.Index' (duration: 74.612µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:51.392649Z","caller":"traceutil/trace.go:171","msg":"trace[1850729066] transaction","detail":"{read_only:false; response_revision:24657; number_of_response:1; }","duration":"209.299651ms","start":"2026-07-02T05:56:51.183342Z","end":"2026-07-02T05:56:51.392642Z","steps":["trace[1850729066] 'process raft request' (duration: 209.096067ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:51.392852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.195628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-02T05:56:51.392938Z","caller":"traceutil/trace.go:171","msg":"trace[873742749] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24657; }","duration":"181.324291ms","start":"2026-07-02T05:56:51.211592Z","end":"2026-07-02T05:56:51.392916Z","steps":["trace[873742749] 'agreement among raft nodes before linearized reading' (duration: 181.069237ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:51.426821Z","caller":"traceutil/trace.go:171","msg":"trace[356667708] transaction","detail":"{read_only:false; response_revision:24658; number_of_response:1; }","duration":"142.174657ms","start":"2026-07-02T05:56:51.284606Z","end":"2026-07-02T05:56:51.426781Z","steps":["trace[356667708] 'process raft request' (duration: 142.013765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:51.426915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.553054ms","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-07-02T05:56:51.426983Z","caller":"traceutil/trace.go:171","msg":"trace[1594628815] 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:24658; }","duration":"191.662207ms","start":"2026-07-02T05:56:51.235304Z","end":"2026-07-02T05:56:51.426966Z","steps":["trace[1594628815] 'agreement among raft nodes before linearized reading' (duration: 191.464302ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:52.717931Z","caller":"traceutil/trace.go:171","msg":"trace[539755325] transaction","detail":"{read_only:false; response_revision:24662; number_of_response:1; }","duration":"133.964102ms","start":"2026-07-02T05:56:52.583929Z","end":"2026-07-02T05:56:52.717893Z","steps":["trace[539755325] 'process raft request' (duration: 133.732567ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:53.014203Z","caller":"traceutil/trace.go:171","msg":"trace[457243582] transaction","detail":"{read_only:false; response_revision:24664; number_of_response:1; }","duration":"138.5714ms","start":"2026-07-02T05:56:52.875608Z","end":"2026-07-02T05:56:53.014179Z","steps":["trace[457243582] 'process raft request' (duration: 138.411437ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:53.033806Z","caller":"traceutil/trace.go:171","msg":"trace[1960726745] transaction","detail":"{read_only:false; response_revision:24666; number_of_response:1; }","duration":"157.934237ms","start":"2026-07-02T05:56:52.875846Z","end":"2026-07-02T05:56:53.03378Z","steps":["trace[1960726745] 'process raft request' (duration: 157.845856ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:56:53.033834Z","caller":"traceutil/trace.go:171","msg":"trace[800493541] transaction","detail":"{read_only:false; response_revision:24665; number_of_response:1; }","duration":"158.045179ms","start":"2026-07-02T05:56:52.875748Z","end":"2026-07-02T05:56:53.033793Z","steps":["trace[800493541] 'process raft request' (duration: 157.789765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:55.919453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.395039ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:56:55.919763Z","caller":"traceutil/trace.go:171","msg":"trace[865416170] linearizableReadLoop","detail":"{readStateIndex:25482; appliedIndex:25481; }","duration":"322.286917ms","start":"2026-07-02T05:56:55.597438Z","end":"2026-07-02T05:56:55.919725Z","steps":["trace[865416170] 'read index received' (duration: 107.46813ms)","trace[865416170] 'applied index is now lower than readState.Index' (duration: 214.816337ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:55.919804Z","caller":"traceutil/trace.go:171","msg":"trace[518672354] transaction","detail":"{read_only:false; response_revision:24686; number_of_response:1; }","duration":"477.896109ms","start":"2026-07-02T05:56:55.441867Z","end":"2026-07-02T05:56:55.919763Z","steps":["trace[518672354] 'process raft request' (duration: 263.085773ms)","trace[518672354] 'compare' (duration: 214.219855ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:55.919917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.457159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T05:56:55.919982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:55.441841Z","time spent":"478.049892ms","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:56:55.920009Z","caller":"traceutil/trace.go:171","msg":"trace[2117549174] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:24686; }","duration":"322.603523ms","start":"2026-07-02T05:56:55.597383Z","end":"2026-07-02T05:56:55.919986Z","steps":["trace[2117549174] 'agreement among raft nodes before linearized reading' (duration: 322.447249ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:56:55.92006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:55.597349Z","time spent":"322.700944ms","remote":"127.0.0.1:48440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true "} {"level":"info","ts":"2026-07-02T05:56:56.135579Z","caller":"traceutil/trace.go:171","msg":"trace[1776057440] transaction","detail":"{read_only:false; response_revision:24687; number_of_response:1; }","duration":"306.250311ms","start":"2026-07-02T05:56:55.829306Z","end":"2026-07-02T05:56:56.135557Z","steps":["trace[1776057440] 'process raft request' (duration: 261.580135ms)","trace[1776057440] 'compare' (duration: 44.584175ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:56.135735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:55.829288Z","time spent":"306.365114ms","remote":"127.0.0.1:44726","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-07-02T05:56:56.497073Z","caller":"traceutil/trace.go:171","msg":"trace[1779055853] transaction","detail":"{read_only:false; response_revision:24688; number_of_response:1; }","duration":"285.699221ms","start":"2026-07-02T05:56:56.211347Z","end":"2026-07-02T05:56:56.497046Z","steps":["trace[1779055853] 'process raft request' (duration: 204.179843ms)","trace[1779055853] 'compare' (duration: 81.403036ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:56:56.652757Z","caller":"traceutil/trace.go:171","msg":"trace[1003716163] transaction","detail":"{read_only:false; response_revision:24689; number_of_response:1; }","duration":"304.447568ms","start":"2026-07-02T05:56:56.348276Z","end":"2026-07-02T05:56:56.652723Z","steps":["trace[1003716163] 'process raft request' (duration: 268.681359ms)","trace[1003716163] 'compare' (duration: 35.479904ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:56.652923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:56:56.348247Z","time spent":"304.58073ms","remote":"127.0.0.1:44726","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-07-02T05:56:56.668903Z","caller":"traceutil/trace.go:171","msg":"trace[62748449] linearizableReadLoop","detail":"{readStateIndex:25486; appliedIndex:25484; }","duration":"104.655825ms","start":"2026-07-02T05:56:56.564226Z","end":"2026-07-02T05:56:56.668882Z","steps":["trace[62748449] 'read index received' (duration: 52.7315ms)","trace[62748449] 'applied index is now lower than readState.Index' (duration: 51.923065ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:56:56.669043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.815068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:56:56.669107Z","caller":"traceutil/trace.go:171","msg":"trace[1066124176] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24689; }","duration":"104.920171ms","start":"2026-07-02T05:56:56.564172Z","end":"2026-07-02T05:56:56.669092Z","steps":["trace[1066124176] 'agreement among raft nodes before linearized reading' (duration: 104.813438ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:22.956978Z","caller":"traceutil/trace.go:171","msg":"trace[90144133] transaction","detail":"{read_only:false; response_revision:25014; number_of_response:1; }","duration":"122.314432ms","start":"2026-07-02T05:57:22.834631Z","end":"2026-07-02T05:57:22.956946Z","steps":["trace[90144133] 'process raft request' (duration: 122.049287ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:51.983623Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006816.wal"} {"level":"info","ts":"2026-07-02T06:00:07.999895Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23656} {"level":"info","ts":"2026-07-02T06:00:08.129323Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23656,"took":"126.148371ms","hash":3337323817,"current-db-size-bytes":59596800,"current-db-size":"60 MB","current-db-size-in-use-bytes":38715392,"current-db-size-in-use":"39 MB"} {"level":"info","ts":"2026-07-02T06:00:08.129383Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3337323817,"revision":23656,"compact-revision":20409} {"level":"info","ts":"2026-07-02T06:05:08.015246Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26669} {"level":"info","ts":"2026-07-02T06:05:08.156067Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26669,"took":"136.507898ms","hash":735480493,"current-db-size-bytes":59596800,"current-db-size":"60 MB","current-db-size-in-use-bytes":32837632,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-02T06:05:08.15613Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":735480493,"revision":26669,"compact-revision":23656} {"level":"info","ts":"2026-07-02T06:05:09.584162Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b6478f0ac5aeb8e1","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T06:05:09.593049Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-02T06:05:09.593176Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-02T06:10:08.021842Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29076} {"level":"info","ts":"2026-07-02T06:10:08.15359Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29076,"took":"128.32909ms","hash":2954454853,"current-db-size-bytes":59596800,"current-db-size":"60 MB","current-db-size-in-use-bytes":30593024,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-02T06:10:08.153664Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2954454853,"revision":29076,"compact-revision":26669} {"level":"info","ts":"2026-07-02T06:15:08.026545Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31306} {"level":"info","ts":"2026-07-02T06:15:08.152377Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31306,"took":"122.658369ms","hash":3200648886,"current-db-size-bytes":59596800,"current-db-size":"60 MB","current-db-size-in-use-bytes":31440896,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-02T06:15:08.152437Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3200648886,"revision":31306,"compact-revision":29076} {"level":"info","ts":"2026-07-02T06:20:08.03635Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33637} {"level":"info","ts":"2026-07-02T06:20:08.181473Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33637,"took":"140.030773ms","hash":3208583609,"current-db-size-bytes":61235200,"current-db-size":"61 MB","current-db-size-in-use-bytes":32944128,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-02T06:20:08.181545Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3208583609,"revision":33637,"compact-revision":31306} {"level":"info","ts":"2026-07-02T06:25:08.0445Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35864} {"level":"info","ts":"2026-07-02T06:25:08.183623Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35864,"took":"134.750813ms","hash":3661682609,"current-db-size-bytes":61235200,"current-db-size":"61 MB","current-db-size-in-use-bytes":31485952,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-02T06:25:08.183695Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3661682609,"revision":35864,"compact-revision":33637} {"level":"info","ts":"2026-07-02T06:26:36.287538Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b6478f0ac5aeb8e1","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T06:26:36.292182Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-02T06:26:36.292281Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}