{"level":"warn","ts":"2026-06-03T02:36:09.360548Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-03T02:36:09.360649Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.202: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.19.213.202:2380","--initial-cluster=instance=https://199.19.213.202:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.202:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.202: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-06-03T02:36:09.360715Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-03T02:36:09.360724Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.202:2380"]} {"level":"info","ts":"2026-06-03T02:36:09.360744Z","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-06-03T02:36:09.361364Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.202:2379"]} {"level":"info","ts":"2026-06-03T02:36:09.361433Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.202:2380"],"listen-peer-urls":["https://199.19.213.202:2380"],"advertise-client-urls":["https://199.19.213.202:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.202:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.202: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-06-03T02:36:09.365341Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.696116ms"} {"level":"info","ts":"2026-06-03T02:36:09.376062Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c9b4049ccf4190cf","cluster-id":"2bc66bd50b26302c"} {"level":"info","ts":"2026-06-03T02:36:09.376164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf switched to configuration voters=()"} {"level":"info","ts":"2026-06-03T02:36:09.376195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf became follower at term 0"} {"level":"info","ts":"2026-06-03T02:36:09.376242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c9b4049ccf4190cf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-03T02:36:09.376256Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf became follower at term 1"} {"level":"info","ts":"2026-06-03T02:36:09.376299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf switched to configuration voters=(14534246968970023119)"} {"level":"warn","ts":"2026-06-03T02:36:09.385743Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-03T02:36:09.387134Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-03T02:36:09.388171Z","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-06-03T02:36:09.39034Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c9b4049ccf4190cf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-03T02:36:09.392642Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c9b4049ccf4190cf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-03T02:36:09.392805Z","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-06-03T02:36:09.392902Z","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-06-03T02:36:09.392916Z","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-06-03T02:36:09.393718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf switched to configuration voters=(14534246968970023119)"} {"level":"info","ts":"2026-06-03T02:36:09.395594Z","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-06-03T02:36:09.3957Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.202:2380"} {"level":"info","ts":"2026-06-03T02:36:09.395716Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.202:2380"} {"level":"info","ts":"2026-06-03T02:36:09.3964Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c9b4049ccf4190cf","initial-advertise-peer-urls":["https://199.19.213.202:2380"],"listen-peer-urls":["https://199.19.213.202:2380"],"advertise-client-urls":["https://199.19.213.202:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.202:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-03T02:36:09.396451Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-03T02:36:09.393963Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bc66bd50b26302c","local-member-id":"c9b4049ccf4190cf","added-peer-id":"c9b4049ccf4190cf","added-peer-peer-urls":["https://199.19.213.202:2380"]} {"level":"info","ts":"2026-06-03T02:36:10.177593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf is starting a new election at term 1"} {"level":"info","ts":"2026-06-03T02:36:10.177678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf became pre-candidate at term 1"} {"level":"info","ts":"2026-06-03T02:36:10.177734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf received MsgPreVoteResp from c9b4049ccf4190cf at term 1"} {"level":"info","ts":"2026-06-03T02:36:10.177756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf became candidate at term 2"} {"level":"info","ts":"2026-06-03T02:36:10.177767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf received MsgVoteResp from c9b4049ccf4190cf at term 2"} {"level":"info","ts":"2026-06-03T02:36:10.177784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9b4049ccf4190cf became leader at term 2"} {"level":"info","ts":"2026-06-03T02:36:10.177798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c9b4049ccf4190cf elected leader c9b4049ccf4190cf at term 2"} {"level":"info","ts":"2026-06-03T02:36:10.178978Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c9b4049ccf4190cf","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.202:2379]}","request-path":"/0/members/c9b4049ccf4190cf/attributes","cluster-id":"2bc66bd50b26302c","publish-timeout":"7s"} {"level":"info","ts":"2026-06-03T02:36:10.179024Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-03T02:36:10.178995Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-03T02:36:10.179111Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-03T02:36:10.179312Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-03T02:36:10.179375Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-03T02:36:10.18015Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bc66bd50b26302c","local-member-id":"c9b4049ccf4190cf","cluster-version":"3.5"} {"level":"info","ts":"2026-06-03T02:36:10.180294Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-03T02:36:10.180344Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-03T02:36:10.18093Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-03T02:36:10.182282Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.202:2379"} {"level":"info","ts":"2026-06-03T02:36:15.110145Z","caller":"traceutil/trace.go:171","msg":"trace[566652203] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"139.717026ms","start":"2026-06-03T02:36:14.970404Z","end":"2026-06-03T02:36:15.110121Z","steps":["trace[566652203] 'process raft request' (duration: 139.581223ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:36:15.286406Z","caller":"traceutil/trace.go:171","msg":"trace[1819135790] linearizableReadLoop","detail":"{readStateIndex:285; appliedIndex:283; }","duration":"206.242293ms","start":"2026-06-03T02:36:15.080138Z","end":"2026-06-03T02:36:15.28638Z","steps":["trace[1819135790] 'read index received' (duration: 29.896291ms)","trace[1819135790] 'applied index is now lower than readState.Index' (duration: 176.344992ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:36:15.286572Z","caller":"traceutil/trace.go:171","msg":"trace[389446428] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"274.080985ms","start":"2026-06-03T02:36:15.012481Z","end":"2026-06-03T02:36:15.286562Z","steps":["trace[389446428] 'process raft request' (duration: 272.513605ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:36:15.286693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.545631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T02:36:15.28678Z","caller":"traceutil/trace.go:171","msg":"trace[1442429576] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:279; }","duration":"206.687664ms","start":"2026-06-03T02:36:15.080075Z","end":"2026-06-03T02:36:15.286763Z","steps":["trace[1442429576] 'agreement among raft nodes before linearized reading' (duration: 206.535311ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:36:15.293208Z","caller":"traceutil/trace.go:171","msg":"trace[249690453] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"114.672052ms","start":"2026-06-03T02:36:15.178514Z","end":"2026-06-03T02:36:15.293186Z","steps":["trace[249690453] 'process raft request' (duration: 114.444517ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:36:15.293614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.342089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-06-03T02:36:15.293665Z","caller":"traceutil/trace.go:171","msg":"trace[18324219] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:280; }","duration":"115.416911ms","start":"2026-06-03T02:36:15.178237Z","end":"2026-06-03T02:36:15.293654Z","steps":["trace[18324219] 'agreement among raft nodes before linearized reading' (duration: 115.305978ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:36:15.29374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.330723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-03T02:36:15.293791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.148756ms","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-06-03T02:36:15.29381Z","caller":"traceutil/trace.go:171","msg":"trace[700354701] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:280; }","duration":"116.451806ms","start":"2026-06-03T02:36:15.177341Z","end":"2026-06-03T02:36:15.293792Z","steps":["trace[700354701] 'agreement among raft nodes before linearized reading' (duration: 116.311723ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:36:15.293843Z","caller":"traceutil/trace.go:171","msg":"trace[1231190724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:280; }","duration":"180.217137ms","start":"2026-06-03T02:36:15.113608Z","end":"2026-06-03T02:36:15.293825Z","steps":["trace[1231190724] 'agreement among raft nodes before linearized reading' (duration: 180.093054ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:36:21.826911Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10434733182931379036,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-03T02:36:21.933036Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.309587292s","expected-duration":"1s"} {"level":"info","ts":"2026-06-03T02:36:21.933597Z","caller":"traceutil/trace.go:171","msg":"trace[1905498882] linearizableReadLoop","detail":"{readStateIndex:402; appliedIndex:401; }","duration":"607.37105ms","start":"2026-06-03T02:36:21.326193Z","end":"2026-06-03T02:36:21.933564Z","steps":["trace[1905498882] 'read index received' (duration: 607.174205ms)","trace[1905498882] 'applied index is now lower than readState.Index' (duration: 195.575µs)"],"step_count":2} {"level":"warn","ts":"2026-06-03T02:36:21.93382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.608318ms","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-06-03T02:36:21.933867Z","caller":"traceutil/trace.go:171","msg":"trace[1098725567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:391; }","duration":"607.738261ms","start":"2026-06-03T02:36:21.326116Z","end":"2026-06-03T02:36:21.933854Z","steps":["trace[1098725567] 'agreement among raft nodes before linearized reading' (duration: 607.547836ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:36:21.933943Z","caller":"traceutil/trace.go:171","msg":"trace[1573423663] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"1.310827295s","start":"2026-06-03T02:36:20.623061Z","end":"2026-06-03T02:36:21.933888Z","steps":["trace[1573423663] 'process raft request' (duration: 1.310330181s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:36:21.933907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:36:21.32609Z","time spent":"607.807063ms","remote":"127.0.0.1:58552","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-06-03T02:36:21.934806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:36:20.623031Z","time spent":"1.310991109s","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T02:36:22.590766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.634066ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T02:36:22.59091Z","caller":"traceutil/trace.go:171","msg":"trace[1834012019] linearizableReadLoop","detail":"{readStateIndex:404; appliedIndex:403; }","duration":"456.183353ms","start":"2026-06-03T02:36:22.134707Z","end":"2026-06-03T02:36:22.59089Z","steps":["trace[1834012019] 'read index received' (duration: 245.123386ms)","trace[1834012019] 'applied index is now lower than readState.Index' (duration: 211.058637ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:36:22.591059Z","caller":"traceutil/trace.go:171","msg":"trace[2088609463] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"503.401617ms","start":"2026-06-03T02:36:22.087606Z","end":"2026-06-03T02:36:22.591008Z","steps":["trace[2088609463] 'process raft request' (duration: 292.261577ms)","trace[2088609463] 'compare' (duration: 210.423591ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T02:36:22.591247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.536923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-06-03T02:36:22.591305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.130129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-03T02:36:22.591347Z","caller":"traceutil/trace.go:171","msg":"trace[576917027] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:393; }","duration":"456.677797ms","start":"2026-06-03T02:36:22.134654Z","end":"2026-06-03T02:36:22.591331Z","steps":["trace[576917027] 'agreement among raft nodes before linearized reading' (duration: 456.445501ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:36:22.59135Z","caller":"traceutil/trace.go:171","msg":"trace[1511691513] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:393; }","duration":"234.206511ms","start":"2026-06-03T02:36:22.35713Z","end":"2026-06-03T02:36:22.591336Z","steps":["trace[1511691513] 'agreement among raft nodes before linearized reading' (duration: 234.091168ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:36:22.591391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:36:22.134628Z","time spent":"456.753038ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-03T02:36:22.591385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.77599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-06-03T02:36:22.591469Z","caller":"traceutil/trace.go:171","msg":"trace[2089003431] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:393; }","duration":"192.915234ms","start":"2026-06-03T02:36:22.398533Z","end":"2026-06-03T02:36:22.591448Z","steps":["trace[2089003431] 'agreement among raft nodes before linearized reading' (duration: 192.741879ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:36:22.591281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:36:22.087565Z","time spent":"503.585731ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T02:36:38.221365Z","caller":"traceutil/trace.go:171","msg":"trace[460828265] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:480; }","duration":"158.520592ms","start":"2026-06-03T02:36:38.062817Z","end":"2026-06-03T02:36:38.221338Z","steps":["trace[460828265] 'read index received' (duration: 158.248624ms)","trace[460828265] 'applied index is now lower than readState.Index' (duration: 270.598µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:36:38.221526Z","caller":"traceutil/trace.go:171","msg":"trace[203480272] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"192.141443ms","start":"2026-06-03T02:36:38.02935Z","end":"2026-06-03T02:36:38.221492Z","steps":["trace[203480272] 'process raft request' (duration: 191.747162ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:36:38.222159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.318145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-03T02:36:38.222867Z","caller":"traceutil/trace.go:171","msg":"trace[1812638795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:467; }","duration":"160.074206ms","start":"2026-06-03T02:36:38.062752Z","end":"2026-06-03T02:36:38.222826Z","steps":["trace[1812638795] 'agreement among raft nodes before linearized reading' (duration: 158.855142ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:36:49.066491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.691667ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-03T02:36:49.066742Z","caller":"traceutil/trace.go:171","msg":"trace[2084970807] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"468.583805ms","start":"2026-06-03T02:36:48.598103Z","end":"2026-06-03T02:36:49.066687Z","steps":["trace[2084970807] 'process raft request' (duration: 186.310871ms)","trace[2084970807] 'compare' (duration: 281.283575ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T02:36:49.066931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:36:48.598095Z","time spent":"468.733919ms","remote":"127.0.0.1:58342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-03T02:36:49.066991Z","caller":"traceutil/trace.go:171","msg":"trace[1285694266] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"467.710789ms","start":"2026-06-03T02:36:48.599233Z","end":"2026-06-03T02:36:49.066943Z","steps":["trace[1285694266] 'process raft request' (duration: 467.41103ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:36:49.067143Z","caller":"traceutil/trace.go:171","msg":"trace[120442882] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"464.554315ms","start":"2026-06-03T02:36:48.602578Z","end":"2026-06-03T02:36:49.067132Z","steps":["trace[120442882] 'process raft request' (duration: 464.238126ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:36:49.067226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:36:48.602569Z","time spent":"464.609677ms","remote":"127.0.0.1:58342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T02:36:49.067203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:36:48.599224Z","time spent":"467.864514ms","remote":"127.0.0.1:58342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T02:36:49.067272Z","caller":"traceutil/trace.go:171","msg":"trace[1516055793] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"458.05002ms","start":"2026-06-03T02:36:48.609191Z","end":"2026-06-03T02:36:49.067241Z","steps":["trace[1516055793] 'process raft request' (duration: 457.843564ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:36:49.067341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:36:48.609178Z","time spent":"458.133223ms","remote":"127.0.0.1:58342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-03T02:36:49.067311Z","caller":"traceutil/trace.go:171","msg":"trace[1105681620] transaction","detail":"{read_only:false; number_of_response:0; response_revision:541; }","duration":"466.576175ms","start":"2026-06-03T02:36:48.600672Z","end":"2026-06-03T02:36:49.067249Z","steps":["trace[1105681620] 'process raft request' (duration: 466.111431ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:36:49.068415Z","caller":"traceutil/trace.go:171","msg":"trace[613529643] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"451.502606ms","start":"2026-06-03T02:36:48.616355Z","end":"2026-06-03T02:36:49.067857Z","steps":["trace[613529643] 'process raft request' (duration: 450.801584ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:36:49.068522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:36:48.600657Z","time spent":"467.74134ms","remote":"127.0.0.1:58764","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-03T02:36:49.068548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:36:48.616323Z","time spent":"452.150295ms","remote":"127.0.0.1:58342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-03T02:36:49.069527Z","caller":"traceutil/trace.go:171","msg":"trace[642180538] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:558; }","duration":"308.338082ms","start":"2026-06-03T02:36:48.761109Z","end":"2026-06-03T02:36:49.069447Z","steps":["trace[642180538] 'read index received' (duration: 23.361777ms)","trace[642180538] 'applied index is now lower than readState.Index' (duration: 284.877232ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:36:49.069815Z","caller":"traceutil/trace.go:171","msg":"trace[93798301] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"460.515674ms","start":"2026-06-03T02:36:48.60927Z","end":"2026-06-03T02:36:49.069786Z","steps":["trace[93798301] 'process raft request' (duration: 457.815743ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:36:49.069899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:36:48.609259Z","time spent":"460.581696ms","remote":"127.0.0.1:58342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T02:36:49.067321Z","caller":"traceutil/trace.go:171","msg":"trace[394414793] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"464.586996ms","start":"2026-06-03T02:36:48.602668Z","end":"2026-06-03T02:36:49.067255Z","steps":["trace[394414793] 'process raft request' (duration: 464.310248ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:36:49.070672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:36:48.602652Z","time spent":"467.974958ms","remote":"127.0.0.1:58342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T02:36:49.072356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.25249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-03T02:36:49.072436Z","caller":"traceutil/trace.go:171","msg":"trace[1412912484] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:548; }","duration":"309.340482ms","start":"2026-06-03T02:36:48.763071Z","end":"2026-06-03T02:36:49.072412Z","steps":["trace[1412912484] 'agreement among raft nodes before linearized reading' (duration: 306.923479ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:36:49.072494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:36:48.763067Z","time spent":"309.405354ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-03T02:36:49.072607Z","caller":"traceutil/trace.go:171","msg":"trace[1363583193] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"350.211842ms","start":"2026-06-03T02:36:48.722182Z","end":"2026-06-03T02:36:49.072394Z","steps":["trace[1363583193] 'process raft request' (duration: 345.1529ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:36:49.072689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:36:48.722151Z","time spent":"350.488451ms","remote":"127.0.0.1:58342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-03T02:36:49.072699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.989612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T02:36:49.072756Z","caller":"traceutil/trace.go:171","msg":"trace[645095731] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:548; }","duration":"249.041753ms","start":"2026-06-03T02:36:48.823688Z","end":"2026-06-03T02:36:49.07273Z","steps":["trace[645095731] 'agreement among raft nodes before linearized reading' (duration: 248.812947ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:36:49.07429Z","caller":"traceutil/trace.go:171","msg":"trace[1366320535] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"456.737312ms","start":"2026-06-03T02:36:48.617508Z","end":"2026-06-03T02:36:49.074245Z","steps":["trace[1366320535] 'process raft request' (duration: 449.724091ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:36:49.078353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.250138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-03T02:36:49.078436Z","caller":"traceutil/trace.go:171","msg":"trace[487417695] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:548; }","duration":"317.361372ms","start":"2026-06-03T02:36:48.761064Z","end":"2026-06-03T02:36:49.078426Z","steps":["trace[487417695] 'agreement among raft nodes before linearized reading' (duration: 309.095224ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:36:49.078472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:36:48.761049Z","time spent":"317.417454ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-03T02:36:49.078624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:36:48.617405Z","time spent":"457.056482ms","remote":"127.0.0.1:58342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-03T02:36:53.477575Z","caller":"traceutil/trace.go:171","msg":"trace[272324596] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"128.433917ms","start":"2026-06-03T02:36:53.349108Z","end":"2026-06-03T02:36:53.477542Z","steps":["trace[272324596] 'read index received' (duration: 127.008813ms)","trace[272324596] 'applied index is now lower than readState.Index' (duration: 1.424114ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:36:53.477723Z","caller":"traceutil/trace.go:171","msg":"trace[2108810552] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"135.10842ms","start":"2026-06-03T02:36:53.34255Z","end":"2026-06-03T02:36:53.477659Z","steps":["trace[2108810552] 'process raft request' (duration: 134.086468ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:36:53.477782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.656554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.202\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-03T02:36:53.477832Z","caller":"traceutil/trace.go:171","msg":"trace[2027421190] range","detail":"{range_begin:/registry/masterleases/199.19.213.202; range_end:; response_count:1; response_revision:618; }","duration":"128.772357ms","start":"2026-06-03T02:36:53.349041Z","end":"2026-06-03T02:36:53.477814Z","steps":["trace[2027421190] 'agreement among raft nodes before linearized reading' (duration: 128.632072ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:36:55.598437Z","caller":"traceutil/trace.go:171","msg":"trace[635637780] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"478.503285ms","start":"2026-06-03T02:36:55.119821Z","end":"2026-06-03T02:36:55.598324Z","steps":["trace[635637780] 'read index received' (duration: 471.027206ms)","trace[635637780] 'applied index is now lower than readState.Index' (duration: 7.475269ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:36:55.598495Z","caller":"traceutil/trace.go:171","msg":"trace[1235585843] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"560.28377ms","start":"2026-06-03T02:36:55.038156Z","end":"2026-06-03T02:36:55.59844Z","steps":["trace[1235585843] 'process raft request' (duration: 556.124332ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:36:55.598723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.860496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-03T02:36:55.598792Z","caller":"traceutil/trace.go:171","msg":"trace[193278952] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:630; }","duration":"479.004621ms","start":"2026-06-03T02:36:55.119772Z","end":"2026-06-03T02:36:55.598777Z","steps":["trace[193278952] 'agreement among raft nodes before linearized reading' (duration: 478.783544ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:36:55.59884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:36:55.119747Z","time spent":"479.083333ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-03T02:36:55.599015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:36:55.038118Z","time spent":"560.64105ms","remote":"127.0.0.1:58386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T02:36:55.599195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.798709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qsg4w\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-03T02:36:55.599233Z","caller":"traceutil/trace.go:171","msg":"trace[1191230588] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qsg4w; range_end:; response_count:1; response_revision:630; }","duration":"263.879681ms","start":"2026-06-03T02:36:55.335345Z","end":"2026-06-03T02:36:55.599225Z","steps":["trace[1191230588] 'agreement among raft nodes before linearized reading' (duration: 263.780528ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:36:55.599316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.007811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-03T02:36:55.59938Z","caller":"traceutil/trace.go:171","msg":"trace[205172498] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:630; }","duration":"479.086853ms","start":"2026-06-03T02:36:55.120275Z","end":"2026-06-03T02:36:55.599362Z","steps":["trace[205172498] 'agreement among raft nodes before linearized reading' (duration: 478.824155ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:36:55.599419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:36:55.120267Z","time spent":"479.143175ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-03T02:36:56.090545Z","caller":"traceutil/trace.go:171","msg":"trace[489149877] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"477.102541ms","start":"2026-06-03T02:36:55.613422Z","end":"2026-06-03T02:36:56.090525Z","steps":["trace[489149877] 'process raft request' (duration: 476.846233ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:36:56.090684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:36:55.613415Z","time spent":"477.189084ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T02:36:56.09049Z","caller":"traceutil/trace.go:171","msg":"trace[921260118] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"468.695674ms","start":"2026-06-03T02:36:55.621778Z","end":"2026-06-03T02:36:56.090473Z","steps":["trace[921260118] 'process raft request' (duration: 468.619021ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:36:56.090829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:36:55.621746Z","time spent":"469.037704ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T02:36:57.378254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.082339359s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-03T02:36:57.378522Z","caller":"traceutil/trace.go:171","msg":"trace[1225796007] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:654; }","duration":"1.024276445s","start":"2026-06-03T02:36:56.354225Z","end":"2026-06-03T02:36:57.378502Z","steps":["trace[1225796007] 'read index received' (duration: 53.152µs)","trace[1225796007] 'applied index is now lower than readState.Index' (duration: 1.024222113s)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:36:57.378635Z","caller":"traceutil/trace.go:171","msg":"trace[515535252] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"1.137893417s","start":"2026-06-03T02:36:56.240722Z","end":"2026-06-03T02:36:57.378615Z","steps":["trace[515535252] 'process raft request' (duration: 1.137662039s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:36:57.378709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:36:56.240684Z","time spent":"1.137980429s","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T02:36:57.378916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024691389s","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-06-03T02:36:57.378941Z","caller":"traceutil/trace.go:171","msg":"trace[2029739235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:635; }","duration":"1.02475759s","start":"2026-06-03T02:36:56.354176Z","end":"2026-06-03T02:36:57.378934Z","steps":["trace[2029739235] 'agreement among raft nodes before linearized reading' (duration: 1.024640777s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:36:57.378961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:36:56.354153Z","time spent":"1.024803992s","remote":"127.0.0.1:58552","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":"info","ts":"2026-06-03T02:36:57.379066Z","caller":"traceutil/trace.go:171","msg":"trace[342896800] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"1.691477295s","start":"2026-06-03T02:36:55.687546Z","end":"2026-06-03T02:36:57.379023Z","steps":["trace[342896800] 'process raft request' (duration: 608.28981ms)","trace[342896800] 'compare' (duration: 1.082190195s)"],"step_count":2} {"level":"warn","ts":"2026-06-03T02:36:57.379241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024643106s","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-06-03T02:36:57.379211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.654614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-03T02:36:57.379275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:36:55.687496Z","time spent":"1.691695493s","remote":"127.0.0.1:58612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-03T02:36:57.379314Z","caller":"traceutil/trace.go:171","msg":"trace[116355374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:635; }","duration":"1.024748229s","start":"2026-06-03T02:36:56.354544Z","end":"2026-06-03T02:36:57.379293Z","steps":["trace[116355374] 'agreement among raft nodes before linearized reading' (duration: 1.024476661s)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:36:57.379331Z","caller":"traceutil/trace.go:171","msg":"trace[619481259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"554.83728ms","start":"2026-06-03T02:36:56.824466Z","end":"2026-06-03T02:36:57.379303Z","steps":["trace[619481259] 'agreement among raft nodes before linearized reading' (duration: 554.599802ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:36:57.37935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:36:56.354537Z","time spent":"1.024805491s","remote":"127.0.0.1:58552","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-06-03T02:36:57.379387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:36:56.824443Z","time spent":"554.933573ms","remote":"127.0.0.1:58278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-03T02:36:57.642603Z","caller":"traceutil/trace.go:171","msg":"trace[2018547954] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"258.15566ms","start":"2026-06-03T02:36:57.384413Z","end":"2026-06-03T02:36:57.642569Z","steps":["trace[2018547954] 'process raft request' (duration: 205.049703ms)","trace[2018547954] 'compare' (duration: 52.692474ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:36:57.646843Z","caller":"traceutil/trace.go:171","msg":"trace[1635710938] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:656; }","duration":"255.26437ms","start":"2026-06-03T02:36:57.391546Z","end":"2026-06-03T02:36:57.64681Z","steps":["trace[1635710938] 'read index received' (duration: 197.929992ms)","trace[1635710938] 'applied index is now lower than readState.Index' (duration: 57.332878ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:36:57.646907Z","caller":"traceutil/trace.go:171","msg":"trace[1476850857] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"261.07995ms","start":"2026-06-03T02:36:57.385794Z","end":"2026-06-03T02:36:57.646874Z","steps":["trace[1476850857] 'process raft request' (duration: 260.813672ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:36:57.647048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.461956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T02:36:57.647121Z","caller":"traceutil/trace.go:171","msg":"trace[483720647] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:637; }","duration":"255.5825ms","start":"2026-06-03T02:36:57.391518Z","end":"2026-06-03T02:36:57.647101Z","steps":["trace[483720647] 'agreement among raft nodes before linearized reading' (duration: 255.447946ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:36:58.181016Z","caller":"traceutil/trace.go:171","msg":"trace[56361598] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"114.213973ms","start":"2026-06-03T02:36:58.066751Z","end":"2026-06-03T02:36:58.180965Z","steps":["trace[56361598] 'process raft request' (duration: 112.768438ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:36:58.626284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.031232ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-06-03T02:37:13.476024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-06-03T02:37:13.476117Z","caller":"traceutil/trace.go:171","msg":"trace[1836477732] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:798; }","duration":"311.325916ms","start":"2026-06-03T02:37:13.164773Z","end":"2026-06-03T02:37:13.476099Z","steps":["trace[1836477732] 'range keys from in-memory index tree' (duration: 310.834461ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:37:13.476168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:37:13.164742Z","time spent":"311.413349ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-03T02:37:13.476198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.239823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.202\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-03T02:37:13.476327Z","caller":"traceutil/trace.go:171","msg":"trace[402712234] range","detail":"{range_begin:/registry/masterleases/199.19.213.202; range_end:; response_count:1; response_revision:798; }","duration":"126.4353ms","start":"2026-06-03T02:37:13.349869Z","end":"2026-06-03T02:37:13.476304Z","steps":["trace[402712234] 'range keys from in-memory index tree' (duration: 125.935674ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:37:43.086191Z","caller":"traceutil/trace.go:171","msg":"trace[2018443573] linearizableReadLoop","detail":"{readStateIndex:1225; appliedIndex:1224; }","duration":"206.988984ms","start":"2026-06-03T02:37:42.879171Z","end":"2026-06-03T02:37:43.08616Z","steps":["trace[2018443573] 'read index received' (duration: 175.588152ms)","trace[2018443573] 'applied index is now lower than readState.Index' (duration: 31.399092ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:37:43.086271Z","caller":"traceutil/trace.go:171","msg":"trace[905148512] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"210.028647ms","start":"2026-06-03T02:37:42.876197Z","end":"2026-06-03T02:37:43.086226Z","steps":["trace[905148512] 'process raft request' (duration: 178.545062ms)","trace[905148512] 'compare' (duration: 31.245697ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T02:37:43.086617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.517853ms","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-06-03T02:37:43.086701Z","caller":"traceutil/trace.go:171","msg":"trace[2113426216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1188; }","duration":"210.734711ms","start":"2026-06-03T02:37:42.875942Z","end":"2026-06-03T02:37:43.086677Z","steps":["trace[2113426216] 'agreement among raft nodes before linearized reading' (duration: 210.382329ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:37:43.122205Z","caller":"traceutil/trace.go:171","msg":"trace[1944335684] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"239.746563ms","start":"2026-06-03T02:37:42.882429Z","end":"2026-06-03T02:37:43.122176Z","steps":["trace[1944335684] 'process raft request' (duration: 239.433233ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:37:43.122247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.495442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T02:37:43.122359Z","caller":"traceutil/trace.go:171","msg":"trace[1369831031] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1190; }","duration":"102.700759ms","start":"2026-06-03T02:37:43.019633Z","end":"2026-06-03T02:37:43.122333Z","steps":["trace[1369831031] 'agreement among raft nodes before linearized reading' (duration: 102.493352ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:37:43.122323Z","caller":"traceutil/trace.go:171","msg":"trace[1935669180] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"239.761804ms","start":"2026-06-03T02:37:42.882511Z","end":"2026-06-03T02:37:43.122273Z","steps":["trace[1935669180] 'process raft request' (duration: 239.555337ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:37:53.67888Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10434733182931380715,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-03T02:37:53.862841Z","caller":"traceutil/trace.go:171","msg":"trace[1310263417] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"685.086482ms","start":"2026-06-03T02:37:53.177718Z","end":"2026-06-03T02:37:53.862804Z","steps":["trace[1310263417] 'process raft request' (duration: 684.810213ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:37:53.863092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:37:53.177678Z","time spent":"685.300249ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T02:37:53.863442Z","caller":"traceutil/trace.go:171","msg":"trace[361319315] linearizableReadLoop","detail":"{readStateIndex:1274; appliedIndex:1272; }","duration":"685.531157ms","start":"2026-06-03T02:37:53.17789Z","end":"2026-06-03T02:37:53.863421Z","steps":["trace[361319315] 'read index received' (duration: 684.676678ms)","trace[361319315] 'applied index is now lower than readState.Index' (duration: 852.969µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:37:53.863532Z","caller":"traceutil/trace.go:171","msg":"trace[846105301] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"682.043816ms","start":"2026-06-03T02:37:53.181474Z","end":"2026-06-03T02:37:53.863518Z","steps":["trace[846105301] 'process raft request' (duration: 681.786727ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:37:53.8636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:37:53.181452Z","time spent":"682.113708ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T02:37:53.863707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.787437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-06-03T02:37:53.863742Z","caller":"traceutil/trace.go:171","msg":"trace[719049298] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1235; }","duration":"685.90478ms","start":"2026-06-03T02:37:53.177829Z","end":"2026-06-03T02:37:53.863733Z","steps":["trace[719049298] 'agreement among raft nodes before linearized reading' (duration: 685.697883ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:37:53.863791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:37:53.177804Z","time spent":"685.979663ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-03T02:37:53.863841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.591122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.202\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-03T02:37:53.863923Z","caller":"traceutil/trace.go:171","msg":"trace[562017233] range","detail":"{range_begin:/registry/masterleases/199.19.213.202; range_end:; response_count:1; response_revision:1235; }","duration":"510.735597ms","start":"2026-06-03T02:37:53.353166Z","end":"2026-06-03T02:37:53.863901Z","steps":["trace[562017233] 'agreement among raft nodes before linearized reading' (duration: 510.5326ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:37:53.863976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:37:53.353143Z","time spent":"510.82406ms","remote":"127.0.0.1:58332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.202\" "} {"level":"warn","ts":"2026-06-03T02:38:10.142509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.210572ms","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-06-03T02:38:10.142679Z","caller":"traceutil/trace.go:171","msg":"trace[1081479475] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1368; }","duration":"171.433499ms","start":"2026-06-03T02:38:09.971222Z","end":"2026-06-03T02:38:10.142655Z","steps":["trace[1081479475] 'range keys from in-memory index tree' (duration: 171.017885ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:38:10.142722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.668179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-03T02:38:10.142742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.415006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-create-2gq9k\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-06-03T02:38:10.142825Z","caller":"traceutil/trace.go:171","msg":"trace[1681583747] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1368; }","duration":"177.855686ms","start":"2026-06-03T02:38:09.964944Z","end":"2026-06-03T02:38:10.1428Z","steps":["trace[1681583747] 'range keys from in-memory index tree' (duration: 177.413891ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:38:10.14287Z","caller":"traceutil/trace.go:171","msg":"trace[1181103852] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-create-2gq9k; range_end:; response_count:1; response_revision:1368; }","duration":"151.576921ms","start":"2026-06-03T02:38:09.991254Z","end":"2026-06-03T02:38:10.142831Z","steps":["trace[1181103852] 'range keys from in-memory index tree' (duration: 151.130305ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:38:10.142485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.041186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-03T02:38:10.142957Z","caller":"traceutil/trace.go:171","msg":"trace[787953669] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1368; }","duration":"175.587266ms","start":"2026-06-03T02:38:09.967355Z","end":"2026-06-03T02:38:10.142943Z","steps":["trace[787953669] 'range keys from in-memory index tree' (duration: 174.750306ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:38:18.515891Z","caller":"traceutil/trace.go:171","msg":"trace[696019440] transaction","detail":"{read_only:false; response_revision:1497; number_of_response:1; }","duration":"320.241442ms","start":"2026-06-03T02:38:18.195613Z","end":"2026-06-03T02:38:18.515855Z","steps":["trace[696019440] 'process raft request' (duration: 320.003103ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:38:18.516366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:38:18.195576Z","time spent":"320.427018ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T02:38:18.545677Z","caller":"traceutil/trace.go:171","msg":"trace[69962284] transaction","detail":"{read_only:false; response_revision:1499; number_of_response:1; }","duration":"346.913308ms","start":"2026-06-03T02:38:18.198734Z","end":"2026-06-03T02:38:18.545647Z","steps":["trace[69962284] 'process raft request' (duration: 346.848716ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:38:18.545755Z","caller":"traceutil/trace.go:171","msg":"trace[1701757701] transaction","detail":"{read_only:false; response_revision:1498; number_of_response:1; }","duration":"348.700392ms","start":"2026-06-03T02:38:18.197029Z","end":"2026-06-03T02:38:18.545729Z","steps":["trace[1701757701] 'process raft request' (duration: 348.403412ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:38:18.545875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:38:18.197011Z","time spent":"348.797186ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T02:38:18.545872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:38:18.198684Z","time spent":"347.079924ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T02:38:45.188187Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10434733182931382074,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-03T02:38:45.327201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.007061916s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3cc06a77-967e-40a6-bc97-a94a104720df\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-06-03T02:38:45.327341Z","caller":"traceutil/trace.go:171","msg":"trace[1212193627] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3cc06a77-967e-40a6-bc97-a94a104720df; range_end:; response_count:1; response_revision:1924; }","duration":"1.007262293s","start":"2026-06-03T02:38:44.320048Z","end":"2026-06-03T02:38:45.327311Z","steps":["trace[1212193627] 'range keys from in-memory index tree' (duration: 1.006923701s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:38:45.327399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:38:44.320027Z","time spent":"1.007357627s","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3767,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3cc06a77-967e-40a6-bc97-a94a104720df\" "} {"level":"warn","ts":"2026-06-03T02:38:45.328508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.737395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-03T02:38:45.328609Z","caller":"traceutil/trace.go:171","msg":"trace[654928998] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1924; }","duration":"786.88753ms","start":"2026-06-03T02:38:44.541695Z","end":"2026-06-03T02:38:45.328582Z","steps":["trace[654928998] 'range keys from in-memory index tree' (duration: 786.548518ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:38:45.328695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.880389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-03T02:38:45.328763Z","caller":"traceutil/trace.go:171","msg":"trace[544587449] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1924; }","duration":"725.978492ms","start":"2026-06-03T02:38:44.60277Z","end":"2026-06-03T02:38:45.328749Z","steps":["trace[544587449] 'range keys from in-memory index tree' (duration: 725.739004ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:38:45.32881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:38:44.602755Z","time spent":"726.040845ms","remote":"127.0.0.1:37970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-03T02:38:45.328709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:38:44.541672Z","time spent":"787.020525ms","remote":"127.0.0.1:37970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-06-03T02:38:45.439909Z","caller":"traceutil/trace.go:171","msg":"trace[746543394] linearizableReadLoop","detail":"{readStateIndex:2001; appliedIndex:2000; }","duration":"752.176089ms","start":"2026-06-03T02:38:44.687695Z","end":"2026-06-03T02:38:45.439871Z","steps":["trace[746543394] 'read index received' (duration: 751.840107ms)","trace[746543394] 'applied index is now lower than readState.Index' (duration: 334.372µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:38:45.440139Z","caller":"traceutil/trace.go:171","msg":"trace[188325502] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"836.646737ms","start":"2026-06-03T02:38:44.603479Z","end":"2026-06-03T02:38:45.440125Z","steps":["trace[188325502] 'process raft request' (duration: 836.143429ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:38:45.440438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.722229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-03T02:38:45.440541Z","caller":"traceutil/trace.go:171","msg":"trace[2145070580] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1925; }","duration":"752.892564ms","start":"2026-06-03T02:38:44.687625Z","end":"2026-06-03T02:38:45.440517Z","steps":["trace[2145070580] 'agreement among raft nodes before linearized reading' (duration: 752.574123ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:38:45.440619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:38:44.687594Z","time spent":"753.014869ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-03T02:38:45.440889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:38:44.603471Z","time spent":"836.857175ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T02:38:45.452017Z","caller":"traceutil/trace.go:171","msg":"trace[1846981542] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"237.492014ms","start":"2026-06-03T02:38:45.2145Z","end":"2026-06-03T02:38:45.451992Z","steps":["trace[1846981542] 'process raft request' (duration: 237.279776ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:38:45.452066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.038153ms","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-06-03T02:38:45.452096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.655738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-03T02:38:45.452126Z","caller":"traceutil/trace.go:171","msg":"trace[522455571] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1926; }","duration":"714.181218ms","start":"2026-06-03T02:38:44.73793Z","end":"2026-06-03T02:38:45.452112Z","steps":["trace[522455571] 'agreement among raft nodes before linearized reading' (duration: 713.982771ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:38:45.452143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.007567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-03T02:38:45.452162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:38:44.737898Z","time spent":"714.257591ms","remote":"127.0.0.1:58552","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":"info","ts":"2026-06-03T02:38:45.452157Z","caller":"traceutil/trace.go:171","msg":"trace[16647956] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1926; }","duration":"181.775252ms","start":"2026-06-03T02:38:45.270365Z","end":"2026-06-03T02:38:45.45214Z","steps":["trace[16647956] 'agreement among raft nodes before linearized reading' (duration: 181.564555ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:38:45.45219Z","caller":"traceutil/trace.go:171","msg":"trace[969869302] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1926; }","duration":"183.080149ms","start":"2026-06-03T02:38:45.269086Z","end":"2026-06-03T02:38:45.452166Z","steps":["trace[969869302] 'agreement among raft nodes before linearized reading' (duration: 182.996376ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:38:45.452462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.253783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-03T02:38:45.452511Z","caller":"traceutil/trace.go:171","msg":"trace[1909251027] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1926; }","duration":"609.354527ms","start":"2026-06-03T02:38:44.843148Z","end":"2026-06-03T02:38:45.452502Z","steps":["trace[1909251027] 'agreement among raft nodes before linearized reading' (duration: 609.14932ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:38:45.452531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:38:44.843122Z","time spent":"609.404819ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-03T02:38:45.620694Z","caller":"traceutil/trace.go:171","msg":"trace[173028362] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"163.770502ms","start":"2026-06-03T02:38:45.456878Z","end":"2026-06-03T02:38:45.620649Z","steps":["trace[173028362] 'process raft request' (duration: 145.191391ms)","trace[173028362] 'compare' (duration: 18.276981ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:38:45.621519Z","caller":"traceutil/trace.go:171","msg":"trace[706803396] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"164.376264ms","start":"2026-06-03T02:38:45.457114Z","end":"2026-06-03T02:38:45.62149Z","steps":["trace[706803396] 'process raft request' (duration: 163.456092ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:38:45.621718Z","caller":"traceutil/trace.go:171","msg":"trace[1301780176] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"161.136978ms","start":"2026-06-03T02:38:45.460561Z","end":"2026-06-03T02:38:45.621698Z","steps":["trace[1301780176] 'process raft request' (duration: 160.818047ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:38:45.621979Z","caller":"traceutil/trace.go:171","msg":"trace[1352024892] transaction","detail":"{read_only:false; response_revision:1931; number_of_response:1; }","duration":"160.97174ms","start":"2026-06-03T02:38:45.460994Z","end":"2026-06-03T02:38:45.621966Z","steps":["trace[1352024892] 'process raft request' (duration: 160.635288ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:38:45.863274Z","caller":"traceutil/trace.go:171","msg":"trace[707116617] transaction","detail":"{read_only:false; response_revision:1934; number_of_response:1; }","duration":"187.667336ms","start":"2026-06-03T02:38:45.675582Z","end":"2026-06-03T02:38:45.863249Z","steps":["trace[707116617] 'process raft request' (duration: 109.239154ms)","trace[707116617] 'compare' (duration: 78.221894ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:38:45.863448Z","caller":"traceutil/trace.go:171","msg":"trace[2020735707] transaction","detail":"{read_only:false; response_revision:1935; number_of_response:1; }","duration":"158.395989ms","start":"2026-06-03T02:38:45.705014Z","end":"2026-06-03T02:38:45.86341Z","steps":["trace[2020735707] 'process raft request' (duration: 158.178991ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:38:46.456854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.639642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-03T02:38:46.456971Z","caller":"traceutil/trace.go:171","msg":"trace[1590860399] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1935; }","duration":"119.815668ms","start":"2026-06-03T02:38:46.337129Z","end":"2026-06-03T02:38:46.456945Z","steps":["trace[1590860399] 'range keys from in-memory index tree' (duration: 119.386853ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:38:46.457121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.351025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3cc06a77-967e-40a6-bc97-a94a104720df\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-06-03T02:38:46.457235Z","caller":"traceutil/trace.go:171","msg":"trace[703280791] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3cc06a77-967e-40a6-bc97-a94a104720df; range_end:; response_count:1; response_revision:1935; }","duration":"118.561052ms","start":"2026-06-03T02:38:46.338643Z","end":"2026-06-03T02:38:46.457204Z","steps":["trace[703280791] 'range keys from in-memory index tree' (duration: 118.050304ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:38:51.776193Z","caller":"traceutil/trace.go:171","msg":"trace[1941507447] linearizableReadLoop","detail":"{readStateIndex:2061; appliedIndex:2060; }","duration":"125.549426ms","start":"2026-06-03T02:38:51.650625Z","end":"2026-06-03T02:38:51.776174Z","steps":["trace[1941507447] 'read index received' (duration: 125.470273ms)","trace[1941507447] 'applied index is now lower than readState.Index' (duration: 78.463µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:38:51.776257Z","caller":"traceutil/trace.go:171","msg":"trace[666699001] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"126.075715ms","start":"2026-06-03T02:38:51.650162Z","end":"2026-06-03T02:38:51.776237Z","steps":["trace[666699001] 'process raft request' (duration: 125.692601ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:38:51.776442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.794525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-03T02:38:51.776489Z","caller":"traceutil/trace.go:171","msg":"trace[866332866] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1983; }","duration":"125.894179ms","start":"2026-06-03T02:38:51.650583Z","end":"2026-06-03T02:38:51.776477Z","steps":["trace[866332866] 'agreement among raft nodes before linearized reading' (duration: 125.695432ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:38:51.798665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.677984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-03T02:38:51.798774Z","caller":"traceutil/trace.go:171","msg":"trace[763255372] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1986; }","duration":"140.838779ms","start":"2026-06-03T02:38:51.65791Z","end":"2026-06-03T02:38:51.798749Z","steps":["trace[763255372] 'agreement among raft nodes before linearized reading' (duration: 140.527038ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:38:58.748813Z","caller":"traceutil/trace.go:171","msg":"trace[27821706] linearizableReadLoop","detail":"{readStateIndex:2117; appliedIndex:2116; }","duration":"206.139317ms","start":"2026-06-03T02:38:58.542622Z","end":"2026-06-03T02:38:58.748761Z","steps":["trace[27821706] 'read index received' (duration: 205.963051ms)","trace[27821706] 'applied index is now lower than readState.Index' (duration: 174.586µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:38:58.748878Z","caller":"traceutil/trace.go:171","msg":"trace[180786948] transaction","detail":"{read_only:false; response_revision:2038; number_of_response:1; }","duration":"479.823906ms","start":"2026-06-03T02:38:58.269018Z","end":"2026-06-03T02:38:58.748842Z","steps":["trace[180786948] 'process raft request' (duration: 479.518485ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:38:58.749069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:38:58.269006Z","time spent":"479.954091ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5626,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T02:38:58.749129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.476369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-03T02:38:58.749186Z","caller":"traceutil/trace.go:171","msg":"trace[1530672206] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2038; }","duration":"206.599254ms","start":"2026-06-03T02:38:58.542568Z","end":"2026-06-03T02:38:58.749167Z","steps":["trace[1530672206] 'agreement among raft nodes before linearized reading' (duration: 206.340355ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:38:58.88354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.445133ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-03T02:38:59.078631Z","caller":"traceutil/trace.go:171","msg":"trace[669953384] linearizableReadLoop","detail":"{readStateIndex:2120; appliedIndex:2118; }","duration":"262.591439ms","start":"2026-06-03T02:38:58.816012Z","end":"2026-06-03T02:38:59.078604Z","steps":["trace[669953384] 'read index received' (duration: 262.197674ms)","trace[669953384] 'applied index is now lower than readState.Index' (duration: 392.625µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:38:59.079185Z","caller":"traceutil/trace.go:171","msg":"trace[348771751] transaction","detail":"{read_only:false; response_revision:2039; number_of_response:1; }","duration":"322.012127ms","start":"2026-06-03T02:38:58.757152Z","end":"2026-06-03T02:38:59.079164Z","steps":["trace[348771751] 'process raft request' (duration: 321.173427ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:38:59.079749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.071275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-ltx72\" ","response":"range_response_count:1 size:6147"} {"level":"info","ts":"2026-06-03T02:38:59.079821Z","caller":"traceutil/trace.go:171","msg":"trace[324798215] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-ltx72; range_end:; response_count:1; response_revision:2040; }","duration":"190.144257ms","start":"2026-06-03T02:38:58.889653Z","end":"2026-06-03T02:38:59.079797Z","steps":["trace[324798215] 'agreement among raft nodes before linearized reading' (duration: 189.94295ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:38:59.079972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:38:58.757127Z","time spent":"322.63364ms","remote":"127.0.0.1:58586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1712,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T02:38:59.079387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.350057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T02:38:59.080415Z","caller":"traceutil/trace.go:171","msg":"trace[678812352] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:2040; }","duration":"264.431345ms","start":"2026-06-03T02:38:58.815957Z","end":"2026-06-03T02:38:59.080389Z","steps":["trace[678812352] 'agreement among raft nodes before linearized reading' (duration: 263.340386ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:38:59.079518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.524482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T02:38:59.080702Z","caller":"traceutil/trace.go:171","msg":"trace[2108928926] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2040; }","duration":"256.84765ms","start":"2026-06-03T02:38:58.823843Z","end":"2026-06-03T02:38:59.08069Z","steps":["trace[2108928926] 'agreement among raft nodes before linearized reading' (duration: 255.537193ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:38:59.079622Z","caller":"traceutil/trace.go:171","msg":"trace[134562493] transaction","detail":"{read_only:false; response_revision:2040; number_of_response:1; }","duration":"320.72002ms","start":"2026-06-03T02:38:58.758883Z","end":"2026-06-03T02:38:59.079603Z","steps":["trace[134562493] 'process raft request' (duration: 319.645031ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:38:59.080924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:38:58.758872Z","time spent":"321.988476ms","remote":"127.0.0.1:58720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5636,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T02:38:59.341344Z","caller":"traceutil/trace.go:171","msg":"trace[568645383] transaction","detail":"{read_only:false; response_revision:2041; number_of_response:1; }","duration":"252.240005ms","start":"2026-06-03T02:38:59.089073Z","end":"2026-06-03T02:38:59.341313Z","steps":["trace[568645383] 'process raft request' (duration: 182.992479ms)","trace[568645383] 'compare' (duration: 69.0859ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:38:59.422221Z","caller":"traceutil/trace.go:171","msg":"trace[659038954] transaction","detail":"{read_only:false; response_revision:2042; number_of_response:1; }","duration":"331.766035ms","start":"2026-06-03T02:38:59.09042Z","end":"2026-06-03T02:38:59.422186Z","steps":["trace[659038954] 'process raft request' (duration: 331.200135ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:38:59.422418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:38:59.090405Z","time spent":"331.90378ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5918,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T02:38:59.581151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.029292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T02:38:59.581297Z","caller":"traceutil/trace.go:171","msg":"trace[406191301] transaction","detail":"{read_only:false; response_revision:2043; number_of_response:1; }","duration":"152.138198ms","start":"2026-06-03T02:38:59.429134Z","end":"2026-06-03T02:38:59.581272Z","steps":["trace[406191301] 'process raft request' (duration: 31.861887ms)","trace[406191301] 'compare' (duration: 119.869646ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:38:59.612943Z","caller":"traceutil/trace.go:171","msg":"trace[630291617] transaction","detail":"{read_only:false; response_revision:2045; number_of_response:1; }","duration":"183.616821ms","start":"2026-06-03T02:38:59.429307Z","end":"2026-06-03T02:38:59.612924Z","steps":["trace[630291617] 'process raft request' (duration: 183.472206ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:38:59.612946Z","caller":"traceutil/trace.go:171","msg":"trace[1245318240] transaction","detail":"{read_only:false; response_revision:2046; number_of_response:1; }","duration":"179.274564ms","start":"2026-06-03T02:38:59.433633Z","end":"2026-06-03T02:38:59.612908Z","steps":["trace[1245318240] 'process raft request' (duration: 179.207592ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:38:59.612933Z","caller":"traceutil/trace.go:171","msg":"trace[1649355046] transaction","detail":"{read_only:false; response_revision:2044; number_of_response:1; }","duration":"183.716065ms","start":"2026-06-03T02:38:59.429176Z","end":"2026-06-03T02:38:59.612892Z","steps":["trace[1649355046] 'process raft request' (duration: 183.468676ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:38:59.639706Z","caller":"traceutil/trace.go:171","msg":"trace[1238048537] linearizableReadLoop","detail":"{readStateIndex:2127; appliedIndex:2123; }","duration":"120.706395ms","start":"2026-06-03T02:38:59.518975Z","end":"2026-06-03T02:38:59.639681Z","steps":["trace[1238048537] 'read index received' (duration: 93.750716ms)","trace[1238048537] 'applied index is now lower than readState.Index' (duration: 26.954129ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:38:59.639823Z","caller":"traceutil/trace.go:171","msg":"trace[2137433088] transaction","detail":"{read_only:false; response_revision:2047; number_of_response:1; }","duration":"140.050108ms","start":"2026-06-03T02:38:59.499715Z","end":"2026-06-03T02:38:59.639765Z","steps":["trace[2137433088] 'process raft request' (duration: 139.795979ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:38:59.639949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.952805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-03T02:38:59.639996Z","caller":"traceutil/trace.go:171","msg":"trace[1604511503] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2047; }","duration":"121.061808ms","start":"2026-06-03T02:38:59.518921Z","end":"2026-06-03T02:38:59.639983Z","steps":["trace[1604511503] 'agreement among raft nodes before linearized reading' (duration: 120.851711ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:38:59.733623Z","caller":"traceutil/trace.go:171","msg":"trace[369363820] transaction","detail":"{read_only:false; response_revision:2048; number_of_response:1; }","duration":"110.739093ms","start":"2026-06-03T02:38:59.62285Z","end":"2026-06-03T02:38:59.733589Z","steps":["trace[369363820] 'process raft request' (duration: 94.641419ms)","trace[369363820] 'compare' (duration: 15.772182ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T02:39:04.298169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.436044ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T02:39:04.298335Z","caller":"traceutil/trace.go:171","msg":"trace[711999431] transaction","detail":"{read_only:false; response_revision:2079; number_of_response:1; }","duration":"366.952269ms","start":"2026-06-03T02:39:03.931369Z","end":"2026-06-03T02:39:04.298322Z","steps":["trace[711999431] 'process raft request' (duration: 366.921338ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:39:04.298387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:39:03.931352Z","time spent":"367.0078ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T02:39:04.298616Z","caller":"traceutil/trace.go:171","msg":"trace[766172911] transaction","detail":"{read_only:false; response_revision:2077; number_of_response:1; }","duration":"411.423997ms","start":"2026-06-03T02:39:03.887183Z","end":"2026-06-03T02:39:04.298607Z","steps":["trace[766172911] 'process raft request' (duration: 59.410162ms)","trace[766172911] 'compare' (duration: 351.106812ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T02:39:04.298655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:39:03.88717Z","time spent":"411.463158ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T02:39:04.298761Z","caller":"traceutil/trace.go:171","msg":"trace[1613640528] transaction","detail":"{read_only:false; response_revision:2078; number_of_response:1; }","duration":"410.085858ms","start":"2026-06-03T02:39:03.888626Z","end":"2026-06-03T02:39:04.298712Z","steps":["trace[1613640528] 'process raft request' (duration: 409.625741ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:39:04.299078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:39:03.888619Z","time spent":"410.276154ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T02:39:26.024064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.152379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-06-03T02:39:26.024423Z","caller":"traceutil/trace.go:171","msg":"trace[1252884527] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2195; }","duration":"117.974151ms","start":"2026-06-03T02:39:25.906421Z","end":"2026-06-03T02:39:26.024395Z","steps":["trace[1252884527] 'range keys from in-memory index tree' (duration: 116.830937ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:39:26.106512Z","caller":"traceutil/trace.go:171","msg":"trace[1362544395] linearizableReadLoop","detail":"{readStateIndex:2282; appliedIndex:2281; }","duration":"191.376141ms","start":"2026-06-03T02:39:25.915105Z","end":"2026-06-03T02:39:26.106481Z","steps":["trace[1362544395] 'read index received' (duration: 191.22702ms)","trace[1362544395] 'applied index is now lower than readState.Index' (duration: 147.071µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:39:26.106651Z","caller":"traceutil/trace.go:171","msg":"trace[1513283022] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"198.828034ms","start":"2026-06-03T02:39:25.90775Z","end":"2026-06-03T02:39:26.106578Z","steps":["trace[1513283022] 'process raft request' (duration: 198.556343ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:39:26.106792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.654501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-03T02:39:26.106823Z","caller":"traceutil/trace.go:171","msg":"trace[9924023] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2196; }","duration":"191.729081ms","start":"2026-06-03T02:39:25.915086Z","end":"2026-06-03T02:39:26.106815Z","steps":["trace[9924023] 'agreement among raft nodes before linearized reading' (duration: 191.537551ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:39:26.228708Z","caller":"traceutil/trace.go:171","msg":"trace[1848798410] linearizableReadLoop","detail":"{readStateIndex:2284; appliedIndex:2282; }","duration":"122.08092ms","start":"2026-06-03T02:39:26.106595Z","end":"2026-06-03T02:39:26.228676Z","steps":["trace[1848798410] 'read index received' (duration: 35.295106ms)","trace[1848798410] 'applied index is now lower than readState.Index' (duration: 86.784224ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:39:26.228857Z","caller":"traceutil/trace.go:171","msg":"trace[1704223472] transaction","detail":"{read_only:false; response_revision:2199; number_of_response:1; }","duration":"118.383505ms","start":"2026-06-03T02:39:26.110454Z","end":"2026-06-03T02:39:26.228837Z","steps":["trace[1704223472] 'process raft request' (duration: 118.180703ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:39:26.229034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.923903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-03T02:39:26.229074Z","caller":"traceutil/trace.go:171","msg":"trace[893073114] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2199; }","duration":"259.045383ms","start":"2026-06-03T02:39:25.970017Z","end":"2026-06-03T02:39:26.229063Z","steps":["trace[893073114] 'agreement among raft nodes before linearized reading' (duration: 258.780702ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:39:26.229391Z","caller":"traceutil/trace.go:171","msg":"trace[678678068] transaction","detail":"{read_only:false; response_revision:2198; number_of_response:1; }","duration":"197.365005ms","start":"2026-06-03T02:39:26.032017Z","end":"2026-06-03T02:39:26.229382Z","steps":["trace[678678068] 'process raft request' (duration: 196.551672ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:39:26.229529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.81483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-03T02:39:26.22955Z","caller":"traceutil/trace.go:171","msg":"trace[580695335] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2199; }","duration":"226.88232ms","start":"2026-06-03T02:39:26.002661Z","end":"2026-06-03T02:39:26.229544Z","steps":["trace[580695335] 'agreement among raft nodes before linearized reading' (duration: 226.81121ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:39:26.229634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.664303ms","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-06-03T02:39:26.229683Z","caller":"traceutil/trace.go:171","msg":"trace[1885800555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2199; }","duration":"240.740283ms","start":"2026-06-03T02:39:25.98891Z","end":"2026-06-03T02:39:26.229651Z","steps":["trace[1885800555] 'agreement among raft nodes before linearized reading' (duration: 240.686803ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:39:26.230375Z","caller":"traceutil/trace.go:171","msg":"trace[1879799540] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"300.723622ms","start":"2026-06-03T02:39:25.929585Z","end":"2026-06-03T02:39:26.230309Z","steps":["trace[1879799540] 'process raft request' (duration: 212.290334ms)","trace[1879799540] 'compare' (duration: 86.478614ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T02:39:26.235609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:39:25.929551Z","time spent":"305.913252ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T02:39:26.694049Z","caller":"traceutil/trace.go:171","msg":"trace[328299324] linearizableReadLoop","detail":"{readStateIndex:2288; appliedIndex:2287; }","duration":"188.853869ms","start":"2026-06-03T02:39:26.505171Z","end":"2026-06-03T02:39:26.694025Z","steps":["trace[328299324] 'read index received' (duration: 188.599037ms)","trace[328299324] 'applied index is now lower than readState.Index' (duration: 253.552µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:39:26.694567Z","caller":"traceutil/trace.go:171","msg":"trace[791402116] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"194.61521ms","start":"2026-06-03T02:39:26.499933Z","end":"2026-06-03T02:39:26.694548Z","steps":["trace[791402116] 'process raft request' (duration: 193.925629ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:39:26.694934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.74848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-03T02:39:26.69498Z","caller":"traceutil/trace.go:171","msg":"trace[1389318357] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2202; }","duration":"189.85766ms","start":"2026-06-03T02:39:26.505112Z","end":"2026-06-03T02:39:26.69497Z","steps":["trace[1389318357] 'agreement among raft nodes before linearized reading' (duration: 189.68413ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:39:26.696248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.320515ms","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-06-03T02:39:26.696352Z","caller":"traceutil/trace.go:171","msg":"trace[2029085370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2202; }","duration":"187.548595ms","start":"2026-06-03T02:39:26.508779Z","end":"2026-06-03T02:39:26.696327Z","steps":["trace[2029085370] 'agreement among raft nodes before linearized reading' (duration: 186.124053ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:39:27.376726Z","caller":"traceutil/trace.go:171","msg":"trace[2128504470] linearizableReadLoop","detail":"{readStateIndex:2291; appliedIndex:2290; }","duration":"138.903257ms","start":"2026-06-03T02:39:27.237779Z","end":"2026-06-03T02:39:27.376682Z","steps":["trace[2128504470] 'read index received' (duration: 80.728842ms)","trace[2128504470] 'applied index is now lower than readState.Index' (duration: 58.172795ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T02:39:27.377135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.316848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-03T02:39:27.377223Z","caller":"traceutil/trace.go:171","msg":"trace[1489098239] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2205; }","duration":"139.474388ms","start":"2026-06-03T02:39:27.237724Z","end":"2026-06-03T02:39:27.377198Z","steps":["trace[1489098239] 'agreement among raft nodes before linearized reading' (duration: 139.114837ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:39:27.377114Z","caller":"traceutil/trace.go:171","msg":"trace[596806790] transaction","detail":"{read_only:false; response_revision:2205; number_of_response:1; }","duration":"606.220959ms","start":"2026-06-03T02:39:26.770848Z","end":"2026-06-03T02:39:27.377069Z","steps":["trace[596806790] 'process raft request' (duration: 547.746045ms)","trace[596806790] 'compare' (duration: 57.203962ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T02:39:27.377499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:39:26.770793Z","time spent":"606.579331ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T02:39:27.888962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.010367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b571cce36138b0\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-06-03T02:39:27.889088Z","caller":"traceutil/trace.go:171","msg":"trace[1667441024] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18b571cce36138b0; range_end:; response_count:1; response_revision:2205; }","duration":"401.234097ms","start":"2026-06-03T02:39:27.487817Z","end":"2026-06-03T02:39:27.889051Z","steps":["trace[1667441024] 'range keys from in-memory index tree' (duration: 400.741227ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:39:27.88914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:39:27.487778Z","time spent":"401.350857ms","remote":"127.0.0.1:58362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b571cce36138b0\" "} {"level":"warn","ts":"2026-06-03T02:39:27.889591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.35088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T02:39:27.889697Z","caller":"traceutil/trace.go:171","msg":"trace[224968270] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2205; }","duration":"346.51158ms","start":"2026-06-03T02:39:27.54316Z","end":"2026-06-03T02:39:27.889671Z","steps":["trace[224968270] 'count revisions from in-memory index tree' (duration: 346.26724ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:39:27.889857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:39:27.54311Z","time spent":"346.721421ms","remote":"127.0.0.1:38920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-06-03T02:39:28.241219Z","caller":"traceutil/trace.go:171","msg":"trace[285684884] linearizableReadLoop","detail":"{readStateIndex:2293; appliedIndex:2292; }","duration":"342.693309ms","start":"2026-06-03T02:39:27.898489Z","end":"2026-06-03T02:39:28.241183Z","steps":["trace[285684884] 'read index received' (duration: 342.444639ms)","trace[285684884] 'applied index is now lower than readState.Index' (duration: 246.94µs)"],"step_count":2} {"level":"warn","ts":"2026-06-03T02:39:28.24141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.88636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T02:39:28.24164Z","caller":"traceutil/trace.go:171","msg":"trace[140384113] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2207; }","duration":"343.18082ms","start":"2026-06-03T02:39:27.898449Z","end":"2026-06-03T02:39:28.24163Z","steps":["trace[140384113] 'agreement among raft nodes before linearized reading' (duration: 342.87888ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:39:28.241689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:39:27.89844Z","time spent":"343.24009ms","remote":"127.0.0.1:58712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-06-03T02:39:28.241715Z","caller":"traceutil/trace.go:171","msg":"trace[801341883] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"348.199251ms","start":"2026-06-03T02:39:27.89348Z","end":"2026-06-03T02:39:28.241679Z","steps":["trace[801341883] 'process raft request' (duration: 347.49059ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:39:28.241896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:39:27.893446Z","time spent":"348.346121ms","remote":"127.0.0.1:58362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T02:39:29.018605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.923779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T02:39:29.018779Z","caller":"traceutil/trace.go:171","msg":"trace[1360534242] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"906.510389ms","start":"2026-06-03T02:39:28.112241Z","end":"2026-06-03T02:39:29.018751Z","steps":["trace[1360534242] 'process raft request' (duration: 512.297789ms)","trace[1360534242] 'compare' (duration: 393.513508ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T02:39:29.018859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:39:28.112188Z","time spent":"906.637909ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T02:39:29.040054Z","caller":"traceutil/trace.go:171","msg":"trace[2147076786] transaction","detail":"{read_only:false; response_revision:2209; number_of_response:1; }","duration":"798.894547ms","start":"2026-06-03T02:39:28.241135Z","end":"2026-06-03T02:39:29.040029Z","steps":["trace[2147076786] 'process raft request' (duration: 798.663037ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:39:29.040278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:39:28.241092Z","time spent":"799.058988ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T02:39:29.040487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.870481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-03T02:39:29.04053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.709422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T02:39:29.040674Z","caller":"traceutil/trace.go:171","msg":"trace[1977194296] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2209; }","duration":"918.960813ms","start":"2026-06-03T02:39:28.121678Z","end":"2026-06-03T02:39:29.040638Z","steps":["trace[1977194296] 'agreement among raft nodes before linearized reading' (duration: 918.700412ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:39:29.040553Z","caller":"traceutil/trace.go:171","msg":"trace[972961379] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2209; }","duration":"805.031232ms","start":"2026-06-03T02:39:28.23551Z","end":"2026-06-03T02:39:29.040541Z","steps":["trace[972961379] 'agreement among raft nodes before linearized reading' (duration: 804.634921ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:39:29.040622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.033723957s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T02:39:29.040819Z","caller":"traceutil/trace.go:171","msg":"trace[598771399] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:2209; }","duration":"1.034092697s","start":"2026-06-03T02:39:28.006697Z","end":"2026-06-03T02:39:29.040789Z","steps":["trace[598771399] 'agreement among raft nodes before linearized reading' (duration: 1.033743636s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:39:29.040945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:39:28.235486Z","time spent":"805.265312ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-03T02:39:29.040742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:39:28.121648Z","time spent":"919.082514ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-06-03T02:39:29.041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:39:28.006625Z","time spent":"1.034358798s","remote":"127.0.0.1:39086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"info","ts":"2026-06-03T02:39:29.040678Z","caller":"traceutil/trace.go:171","msg":"trace[823226360] linearizableReadLoop","detail":"{readStateIndex:2295; appliedIndex:2293; }","duration":"798.672256ms","start":"2026-06-03T02:39:28.241306Z","end":"2026-06-03T02:39:29.039979Z","steps":["trace[823226360] 'read index received' (duration: 383.244661ms)","trace[823226360] 'applied index is now lower than readState.Index' (duration: 415.426115ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T02:39:29.375324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.314508ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-03T02:39:29.376992Z","caller":"traceutil/trace.go:171","msg":"trace[350789396] linearizableReadLoop","detail":"{readStateIndex:2297; appliedIndex:2295; }","duration":"334.338115ms","start":"2026-06-03T02:39:29.042622Z","end":"2026-06-03T02:39:29.37696Z","steps":["trace[350789396] 'read index received' (duration: 1.285933ms)","trace[350789396] 'applied index is now lower than readState.Index' (duration: 333.050812ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:39:29.377066Z","caller":"traceutil/trace.go:171","msg":"trace[1538343883] transaction","detail":"{read_only:false; response_revision:2210; number_of_response:1; }","duration":"675.729225ms","start":"2026-06-03T02:39:28.701294Z","end":"2026-06-03T02:39:29.377023Z","steps":["trace[1538343883] 'process raft request' (duration: 674.229431ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:39:29.377268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:39:28.701249Z","time spent":"675.911135ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T02:39:29.378603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.427546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-06-03T02:39:29.378774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.049434ms","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-06-03T02:39:29.378885Z","caller":"traceutil/trace.go:171","msg":"trace[950025542] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2210; }","duration":"650.234045ms","start":"2026-06-03T02:39:28.72863Z","end":"2026-06-03T02:39:29.378864Z","steps":["trace[950025542] 'agreement among raft nodes before linearized reading' (duration: 648.5344ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:39:29.378942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:39:28.728592Z","time spent":"650.340835ms","remote":"127.0.0.1:58552","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":"info","ts":"2026-06-03T02:39:29.378839Z","caller":"traceutil/trace.go:171","msg":"trace[707182032] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2210; }","duration":"991.643436ms","start":"2026-06-03T02:39:28.387103Z","end":"2026-06-03T02:39:29.378747Z","steps":["trace[707182032] 'agreement among raft nodes before linearized reading' (duration: 990.180952ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:39:29.379099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:39:28.387091Z","time spent":"991.987737ms","remote":"127.0.0.1:37970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-03T02:39:29.379378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.954508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-03T02:39:29.379424Z","caller":"traceutil/trace.go:171","msg":"trace[841650024] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2210; }","duration":"664.054378ms","start":"2026-06-03T02:39:28.715358Z","end":"2026-06-03T02:39:29.379412Z","steps":["trace[841650024] 'agreement among raft nodes before linearized reading' (duration: 661.895122ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:39:29.379483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:39:28.715327Z","time spent":"664.143708ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-03T02:39:29.379614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.970549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T02:39:29.379726Z","caller":"traceutil/trace.go:171","msg":"trace[103183263] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2210; }","duration":"791.0814ms","start":"2026-06-03T02:39:28.588621Z","end":"2026-06-03T02:39:29.379702Z","steps":["trace[103183263] 'agreement among raft nodes before linearized reading' (duration: 788.647473ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:39:29.379887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:39:28.588502Z","time spent":"791.36244ms","remote":"127.0.0.1:38946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-06-03T02:39:29.380275Z","caller":"traceutil/trace.go:171","msg":"trace[322998973] transaction","detail":"{read_only:false; response_revision:2211; number_of_response:1; }","duration":"333.913763ms","start":"2026-06-03T02:39:29.046346Z","end":"2026-06-03T02:39:29.38026Z","steps":["trace[322998973] 'process raft request' (duration: 330.599415ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:39:29.37991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.116517633s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:489"} {"level":"warn","ts":"2026-06-03T02:39:29.380381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:39:29.046319Z","time spent":"333.999384ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T02:39:29.380365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.897751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-03T02:39:29.379753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.133226993s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T02:39:29.380474Z","caller":"traceutil/trace.go:171","msg":"trace[1828653533] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2210; }","duration":"1.117025245s","start":"2026-06-03T02:39:28.263366Z","end":"2026-06-03T02:39:29.380391Z","steps":["trace[1828653533] 'agreement among raft nodes before linearized reading' (duration: 1.113838356s)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:39:29.380512Z","caller":"traceutil/trace.go:171","msg":"trace[1453810635] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2210; }","duration":"1.134001535s","start":"2026-06-03T02:39:28.246495Z","end":"2026-06-03T02:39:29.380496Z","steps":["trace[1453810635] 'agreement among raft nodes before linearized reading' (duration: 1.130779246s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:39:29.380565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:39:28.246479Z","time spent":"1.134074765s","remote":"127.0.0.1:58490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-06-03T02:39:29.380563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:39:28.263358Z","time spent":"1.117190485s","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-03T02:39:29.380251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.125157014s","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-06-03T02:39:29.380708Z","caller":"traceutil/trace.go:171","msg":"trace[705708163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2210; }","duration":"1.125698916s","start":"2026-06-03T02:39:28.254996Z","end":"2026-06-03T02:39:29.380695Z","steps":["trace[705708163] 'agreement among raft nodes before linearized reading' (duration: 1.122261676s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:39:29.380747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:39:28.254985Z","time spent":"1.125749676s","remote":"127.0.0.1:58552","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":"info","ts":"2026-06-03T02:39:29.380446Z","caller":"traceutil/trace.go:171","msg":"trace[1473764997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2211; }","duration":"306.054031ms","start":"2026-06-03T02:39:29.074375Z","end":"2026-06-03T02:39:29.380429Z","steps":["trace[1473764997] 'agreement among raft nodes before linearized reading' (duration: 305.81923ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:39:29.382728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:39:29.074349Z","time spent":"308.338576ms","remote":"127.0.0.1:58278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-03T02:40:43.544972Z","caller":"traceutil/trace.go:171","msg":"trace[1565312198] transaction","detail":"{read_only:false; response_revision:2706; number_of_response:1; }","duration":"158.642312ms","start":"2026-06-03T02:40:43.386309Z","end":"2026-06-03T02:40:43.544952Z","steps":["trace[1565312198] 'process raft request' (duration: 158.440409ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:40:43.545186Z","caller":"traceutil/trace.go:171","msg":"trace[1060083314] transaction","detail":"{read_only:false; response_revision:2707; number_of_response:1; }","duration":"158.778234ms","start":"2026-06-03T02:40:43.38637Z","end":"2026-06-03T02:40:43.545148Z","steps":["trace[1060083314] 'process raft request' (duration: 158.539911ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:40:48.535137Z","caller":"traceutil/trace.go:171","msg":"trace[871275842] transaction","detail":"{read_only:false; response_revision:2734; number_of_response:1; }","duration":"336.899558ms","start":"2026-06-03T02:40:48.198201Z","end":"2026-06-03T02:40:48.5351Z","steps":["trace[871275842] 'process raft request' (duration: 336.703045ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:40:48.535313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:40:48.198165Z","time spent":"337.06612ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T02:41:11.44227Z","caller":"traceutil/trace.go:171","msg":"trace[976035595] linearizableReadLoop","detail":"{readStateIndex:2971; appliedIndex:2970; }","duration":"106.106221ms","start":"2026-06-03T02:41:11.336132Z","end":"2026-06-03T02:41:11.442238Z","steps":["trace[976035595] 'read index received' (duration: 105.821596ms)","trace[976035595] 'applied index is now lower than readState.Index' (duration: 283.395µs)"],"step_count":2} {"level":"warn","ts":"2026-06-03T02:41:11.442627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.400007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-06-03T02:41:11.442658Z","caller":"traceutil/trace.go:171","msg":"trace[3532446] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2862; }","duration":"106.547319ms","start":"2026-06-03T02:41:11.336102Z","end":"2026-06-03T02:41:11.442649Z","steps":["trace[3532446] 'agreement among raft nodes before linearized reading' (duration: 106.267304ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:41:11.443092Z","caller":"traceutil/trace.go:171","msg":"trace[323693993] transaction","detail":"{read_only:false; response_revision:2862; number_of_response:1; }","duration":"129.407229ms","start":"2026-06-03T02:41:11.313674Z","end":"2026-06-03T02:41:11.443081Z","steps":["trace[323693993] 'process raft request' (duration: 128.37897ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:41:23.475115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.656655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-06-03T02:41:23.475192Z","caller":"traceutil/trace.go:171","msg":"trace[569571154] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2926; }","duration":"138.794408ms","start":"2026-06-03T02:41:23.336381Z","end":"2026-06-03T02:41:23.475175Z","steps":["trace[569571154] 'range keys from in-memory index tree' (duration: 138.36476ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:41:23.476421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.46664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.202\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-03T02:41:23.47659Z","caller":"traceutil/trace.go:171","msg":"trace[1268766258] range","detail":"{range_begin:/registry/masterleases/199.19.213.202; range_end:; response_count:1; response_revision:2926; }","duration":"109.716835ms","start":"2026-06-03T02:41:23.36685Z","end":"2026-06-03T02:41:23.476566Z","steps":["trace[1268766258] 'range keys from in-memory index tree' (duration: 108.116742ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:41:23.611528Z","caller":"traceutil/trace.go:171","msg":"trace[2109164538] linearizableReadLoop","detail":"{readStateIndex:3038; appliedIndex:3037; }","duration":"132.368816ms","start":"2026-06-03T02:41:23.479129Z","end":"2026-06-03T02:41:23.611498Z","steps":["trace[2109164538] 'read index received' (duration: 132.209123ms)","trace[2109164538] 'applied index is now lower than readState.Index' (duration: 157.923µs)"],"step_count":2} {"level":"warn","ts":"2026-06-03T02:41:23.611765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.614071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1445"} {"level":"info","ts":"2026-06-03T02:41:23.61181Z","caller":"traceutil/trace.go:171","msg":"trace[1635758241] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2926; }","duration":"132.700913ms","start":"2026-06-03T02:41:23.479096Z","end":"2026-06-03T02:41:23.611797Z","steps":["trace[1635758241] 'agreement among raft nodes before linearized reading' (duration: 132.51557ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:45:09.299493Z","caller":"traceutil/trace.go:171","msg":"trace[1413155856] transaction","detail":"{read_only:false; response_revision:4302; number_of_response:1; }","duration":"103.684814ms","start":"2026-06-03T02:45:09.195778Z","end":"2026-06-03T02:45:09.299463Z","steps":["trace[1413155856] 'process raft request' (duration: 103.479298ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:45:17.088087Z","caller":"traceutil/trace.go:171","msg":"trace[688088439] linearizableReadLoop","detail":"{readStateIndex:4904; appliedIndex:4901; }","duration":"108.915457ms","start":"2026-06-03T02:45:16.979146Z","end":"2026-06-03T02:45:17.088061Z","steps":["trace[688088439] 'read index received' (duration: 63.258049ms)","trace[688088439] 'applied index is now lower than readState.Index' (duration: 45.656918ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:45:17.088183Z","caller":"traceutil/trace.go:171","msg":"trace[708528562] transaction","detail":"{read_only:false; response_revision:4715; number_of_response:1; }","duration":"112.544788ms","start":"2026-06-03T02:45:16.975591Z","end":"2026-06-03T02:45:17.088136Z","steps":["trace[708528562] 'process raft request' (duration: 66.724086ms)","trace[708528562] 'compare' (duration: 44.846382ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T02:45:17.088277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.110002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"info","ts":"2026-06-03T02:45:17.088341Z","caller":"traceutil/trace.go:171","msg":"trace[389214443] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4717; }","duration":"109.157734ms","start":"2026-06-03T02:45:16.979139Z","end":"2026-06-03T02:45:17.088297Z","steps":["trace[389214443] 'agreement among raft nodes before linearized reading' (duration: 109.012889ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:45:17.088263Z","caller":"traceutil/trace.go:171","msg":"trace[1596025544] transaction","detail":"{read_only:false; response_revision:4716; number_of_response:1; }","duration":"112.503167ms","start":"2026-06-03T02:45:16.975601Z","end":"2026-06-03T02:45:17.088104Z","steps":["trace[1596025544] 'process raft request' (duration: 111.707163ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:45:17.088567Z","caller":"traceutil/trace.go:171","msg":"trace[1422306823] transaction","detail":"{read_only:false; response_revision:4717; number_of_response:1; }","duration":"111.30154ms","start":"2026-06-03T02:45:16.977231Z","end":"2026-06-03T02:45:17.088533Z","steps":["trace[1422306823] 'process raft request' (duration: 110.763984ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:45:17.573529Z","caller":"traceutil/trace.go:171","msg":"trace[1507869498] transaction","detail":"{read_only:false; response_revision:4740; number_of_response:1; }","duration":"171.204788ms","start":"2026-06-03T02:45:17.402287Z","end":"2026-06-03T02:45:17.573492Z","steps":["trace[1507869498] 'process raft request' (duration: 138.574292ms)","trace[1507869498] 'compare' (duration: 32.466471ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:45:17.573524Z","caller":"traceutil/trace.go:171","msg":"trace[1255024614] linearizableReadLoop","detail":"{readStateIndex:4927; appliedIndex:4926; }","duration":"171.027043ms","start":"2026-06-03T02:45:17.402467Z","end":"2026-06-03T02:45:17.573494Z","steps":["trace[1255024614] 'read index received' (duration: 138.324635ms)","trace[1255024614] 'applied index is now lower than readState.Index' (duration: 32.700968ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T02:45:17.573986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.941634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T02:45:17.574064Z","caller":"traceutil/trace.go:171","msg":"trace[1952238232] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:0; response_revision:4740; }","duration":"129.044158ms","start":"2026-06-03T02:45:17.445Z","end":"2026-06-03T02:45:17.574044Z","steps":["trace[1952238232] 'agreement among raft nodes before linearized reading' (duration: 128.881452ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:17.574067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.518938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-06-03T02:45:17.574261Z","caller":"traceutil/trace.go:171","msg":"trace[1539361723] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4740; }","duration":"171.789496ms","start":"2026-06-03T02:45:17.40245Z","end":"2026-06-03T02:45:17.574239Z","steps":["trace[1539361723] 'agreement among raft nodes before linearized reading' (duration: 171.129725ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:45:18.277694Z","caller":"traceutil/trace.go:171","msg":"trace[1751668704] transaction","detail":"{read_only:false; response_revision:4776; number_of_response:1; }","duration":"138.533973ms","start":"2026-06-03T02:45:18.139112Z","end":"2026-06-03T02:45:18.277646Z","steps":["trace[1751668704] 'process raft request' (duration: 127.988428ms)","trace[1751668704] 'compare' (duration: 10.35921ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:45:20.919895Z","caller":"traceutil/trace.go:171","msg":"trace[1909664990] linearizableReadLoop","detail":"{readStateIndex:5009; appliedIndex:5007; }","duration":"127.259029ms","start":"2026-06-03T02:45:20.792614Z","end":"2026-06-03T02:45:20.919873Z","steps":["trace[1909664990] 'read index received' (duration: 47.001051ms)","trace[1909664990] 'applied index is now lower than readState.Index' (duration: 80.256658ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T02:45:20.920065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.432355ms","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-06-03T02:45:20.920307Z","caller":"traceutil/trace.go:171","msg":"trace[1888259747] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4821; }","duration":"127.691833ms","start":"2026-06-03T02:45:20.792598Z","end":"2026-06-03T02:45:20.92029Z","steps":["trace[1888259747] 'agreement among raft nodes before linearized reading' (duration: 127.380484ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:45:20.92047Z","caller":"traceutil/trace.go:171","msg":"trace[1415099015] transaction","detail":"{read_only:false; response_revision:4821; number_of_response:1; }","duration":"140.627031ms","start":"2026-06-03T02:45:20.779727Z","end":"2026-06-03T02:45:20.920354Z","steps":["trace[1415099015] 'process raft request' (duration: 140.093525ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:45:20.920589Z","caller":"traceutil/trace.go:171","msg":"trace[1797378421] transaction","detail":"{read_only:false; response_revision:4820; number_of_response:1; }","duration":"142.913823ms","start":"2026-06-03T02:45:20.77703Z","end":"2026-06-03T02:45:20.919944Z","steps":["trace[1797378421] 'process raft request' (duration: 142.067207ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:45:21.563666Z","caller":"traceutil/trace.go:171","msg":"trace[903752243] transaction","detail":"{read_only:false; response_revision:4824; number_of_response:1; }","duration":"185.132268ms","start":"2026-06-03T02:45:21.378509Z","end":"2026-06-03T02:45:21.563641Z","steps":["trace[903752243] 'process raft request' (duration: 184.548139ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:23.745804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.42478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-06-03T02:45:23.745821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.856073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-03T02:45:23.745889Z","caller":"traceutil/trace.go:171","msg":"trace[1938040563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4904; }","duration":"106.552874ms","start":"2026-06-03T02:45:23.639321Z","end":"2026-06-03T02:45:23.745874Z","steps":["trace[1938040563] 'agreement among raft nodes before linearized reading' (duration: 106.350728ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:45:23.745922Z","caller":"traceutil/trace.go:171","msg":"trace[73931396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4904; }","duration":"105.990937ms","start":"2026-06-03T02:45:23.639909Z","end":"2026-06-03T02:45:23.7459Z","steps":["trace[73931396] 'agreement among raft nodes before linearized reading' (duration: 105.674387ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:23.746048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.391904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-54lz5-tls-ghnxz\" ","response":"range_response_count:1 size:2625"} {"level":"info","ts":"2026-06-03T02:45:23.746077Z","caller":"traceutil/trace.go:171","msg":"trace[1698368763] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-54lz5-tls-ghnxz; range_end:; response_count:1; response_revision:4904; }","duration":"112.427666ms","start":"2026-06-03T02:45:23.633639Z","end":"2026-06-03T02:45:23.746067Z","steps":["trace[1698368763] 'agreement among raft nodes before linearized reading' (duration: 112.366884ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:45:23.746311Z","caller":"traceutil/trace.go:171","msg":"trace[1811087232] transaction","detail":"{read_only:false; response_revision:4904; number_of_response:1; }","duration":"111.715744ms","start":"2026-06-03T02:45:23.634581Z","end":"2026-06-03T02:45:23.746296Z","steps":["trace[1811087232] 'process raft request' (duration: 110.865187ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:45:23.746415Z","caller":"traceutil/trace.go:171","msg":"trace[1817724241] transaction","detail":"{read_only:false; response_revision:4903; number_of_response:1; }","duration":"113.709197ms","start":"2026-06-03T02:45:23.6327Z","end":"2026-06-03T02:45:23.74641Z","steps":["trace[1817724241] 'process raft request' (duration: 112.617662ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:23.968943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.003432ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T02:45:23.969221Z","caller":"traceutil/trace.go:171","msg":"trace[633977803] linearizableReadLoop","detail":"{readStateIndex:5101; appliedIndex:5099; }","duration":"213.061617ms","start":"2026-06-03T02:45:23.756138Z","end":"2026-06-03T02:45:23.9692Z","steps":["trace[633977803] 'read index received' (duration: 39.505041ms)","trace[633977803] 'applied index is now lower than readState.Index' (duration: 173.555326ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:45:23.969382Z","caller":"traceutil/trace.go:171","msg":"trace[494671067] transaction","detail":"{read_only:false; response_revision:4908; number_of_response:1; }","duration":"212.434417ms","start":"2026-06-03T02:45:23.756885Z","end":"2026-06-03T02:45:23.96932Z","steps":["trace[494671067] 'process raft request' (duration: 212.248641ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:23.969441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.298784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-06-03T02:45:23.969391Z","caller":"traceutil/trace.go:171","msg":"trace[1728556635] transaction","detail":"{read_only:false; response_revision:4907; number_of_response:1; }","duration":"213.376826ms","start":"2026-06-03T02:45:23.755965Z","end":"2026-06-03T02:45:23.969342Z","steps":["trace[1728556635] 'process raft request' (duration: 58.862079ms)","trace[1728556635] 'compare' (duration: 153.781865ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:45:23.969475Z","caller":"traceutil/trace.go:171","msg":"trace[721754610] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4908; }","duration":"213.355736ms","start":"2026-06-03T02:45:23.756106Z","end":"2026-06-03T02:45:23.969461Z","steps":["trace[721754610] 'agreement among raft nodes before linearized reading' (duration: 213.209882ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:23.969593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.309827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-54lz5-tls\" ","response":"range_response_count:1 size:2518"} {"level":"info","ts":"2026-06-03T02:45:23.969692Z","caller":"traceutil/trace.go:171","msg":"trace[1908130556] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-54lz5-tls; range_end:; response_count:1; response_revision:4908; }","duration":"162.451583ms","start":"2026-06-03T02:45:23.807219Z","end":"2026-06-03T02:45:23.969671Z","steps":["trace[1908130556] 'agreement among raft nodes before linearized reading' (duration: 162.177263ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:23.970044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.989342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T02:45:23.970122Z","caller":"traceutil/trace.go:171","msg":"trace[937432640] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4908; }","duration":"144.151368ms","start":"2026-06-03T02:45:23.825952Z","end":"2026-06-03T02:45:23.970103Z","steps":["trace[937432640] 'agreement among raft nodes before linearized reading' (duration: 143.990023ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:23.970512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.200917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206973"} {"level":"info","ts":"2026-06-03T02:45:23.970552Z","caller":"traceutil/trace.go:171","msg":"trace[1860652812] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4908; }","duration":"143.30421ms","start":"2026-06-03T02:45:23.827238Z","end":"2026-06-03T02:45:23.970543Z","steps":["trace[1860652812] 'agreement among raft nodes before linearized reading' (duration: 142.398823ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:45:24.103964Z","caller":"traceutil/trace.go:171","msg":"trace[1001193073] linearizableReadLoop","detail":"{readStateIndex:5103; appliedIndex:5102; }","duration":"120.247078ms","start":"2026-06-03T02:45:23.983681Z","end":"2026-06-03T02:45:24.103928Z","steps":["trace[1001193073] 'read index received' (duration: 88.735494ms)","trace[1001193073] 'applied index is now lower than readState.Index' (duration: 31.510164ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:45:24.104051Z","caller":"traceutil/trace.go:171","msg":"trace[483890402] transaction","detail":"{read_only:false; response_revision:4909; number_of_response:1; }","duration":"121.618391ms","start":"2026-06-03T02:45:23.982398Z","end":"2026-06-03T02:45:24.104017Z","steps":["trace[483890402] 'process raft request' (duration: 90.089126ms)","trace[483890402] 'compare' (duration: 31.280647ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T02:45:24.104844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.152504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-06-03T02:45:24.104924Z","caller":"traceutil/trace.go:171","msg":"trace[1122122918] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4909; }","duration":"106.264947ms","start":"2026-06-03T02:45:23.998644Z","end":"2026-06-03T02:45:24.104909Z","steps":["trace[1122122918] 'agreement among raft nodes before linearized reading' (duration: 106.148153ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:24.104299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.59099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-06-03T02:45:24.105035Z","caller":"traceutil/trace.go:171","msg":"trace[1180512582] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4909; }","duration":"121.329072ms","start":"2026-06-03T02:45:23.983669Z","end":"2026-06-03T02:45:24.104998Z","steps":["trace[1180512582] 'agreement among raft nodes before linearized reading' (duration: 120.489016ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:45:24.410518Z","caller":"traceutil/trace.go:171","msg":"trace[104207090] linearizableReadLoop","detail":"{readStateIndex:5104; appliedIndex:5103; }","duration":"203.736592ms","start":"2026-06-03T02:45:24.206759Z","end":"2026-06-03T02:45:24.410496Z","steps":["trace[104207090] 'read index received' (duration: 203.502855ms)","trace[104207090] 'applied index is now lower than readState.Index' (duration: 232.317µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:45:24.410641Z","caller":"traceutil/trace.go:171","msg":"trace[1188593695] transaction","detail":"{read_only:false; response_revision:4910; number_of_response:1; }","duration":"208.909232ms","start":"2026-06-03T02:45:24.201716Z","end":"2026-06-03T02:45:24.410625Z","steps":["trace[1188593695] 'process raft request' (duration: 208.581022ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:24.410818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.033751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T02:45:24.410862Z","caller":"traceutil/trace.go:171","msg":"trace[1332480511] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:4910; }","duration":"204.129484ms","start":"2026-06-03T02:45:24.206722Z","end":"2026-06-03T02:45:24.410851Z","steps":["trace[1332480511] 'agreement among raft nodes before linearized reading' (duration: 204.034641ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:24.421148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.649009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:60493"} {"level":"info","ts":"2026-06-03T02:45:24.421261Z","caller":"traceutil/trace.go:171","msg":"trace[1777399577] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4911; }","duration":"213.803314ms","start":"2026-06-03T02:45:24.207433Z","end":"2026-06-03T02:45:24.421237Z","steps":["trace[1777399577] 'agreement among raft nodes before linearized reading' (duration: 213.468484ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:24.421759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.56083ms","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:5"} {"level":"info","ts":"2026-06-03T02:45:24.421817Z","caller":"traceutil/trace.go:171","msg":"trace[1584114006] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:4911; }","duration":"153.671893ms","start":"2026-06-03T02:45:24.268133Z","end":"2026-06-03T02:45:24.421805Z","steps":["trace[1584114006] 'agreement among raft nodes before linearized reading' (duration: 153.57913ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:45:25.759912Z","caller":"traceutil/trace.go:171","msg":"trace[29533341] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"340.711793ms","start":"2026-06-03T02:45:25.41917Z","end":"2026-06-03T02:45:25.759882Z","steps":["trace[29533341] 'process raft request' (duration: 340.519206ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:25.760085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:45:25.419141Z","time spent":"340.852838ms","remote":"127.0.0.1:58362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-03T02:45:25.763605Z","caller":"traceutil/trace.go:171","msg":"trace[2020866777] linearizableReadLoop","detail":"{readStateIndex:5113; appliedIndex:5111; }","duration":"182.421046ms","start":"2026-06-03T02:45:25.581157Z","end":"2026-06-03T02:45:25.763578Z","steps":["trace[2020866777] 'read index received' (duration: 178.503384ms)","trace[2020866777] 'applied index is now lower than readState.Index' (duration: 3.916052ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:45:25.76367Z","caller":"traceutil/trace.go:171","msg":"trace[199699535] transaction","detail":"{read_only:false; response_revision:4919; number_of_response:1; }","duration":"333.933483ms","start":"2026-06-03T02:45:25.429702Z","end":"2026-06-03T02:45:25.763636Z","steps":["trace[199699535] 'process raft request' (duration: 333.632224ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:45:25.763766Z","caller":"traceutil/trace.go:171","msg":"trace[1107655555] transaction","detail":"{read_only:false; response_revision:4920; number_of_response:1; }","duration":"129.002852ms","start":"2026-06-03T02:45:25.634747Z","end":"2026-06-03T02:45:25.76375Z","steps":["trace[1107655555] 'process raft request' (duration: 128.764575ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:25.763838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:45:25.429669Z","time spent":"334.073348ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9856,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T02:45:25.763905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.467992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-03T02:45:25.763969Z","caller":"traceutil/trace.go:171","msg":"trace[166368372] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4920; }","duration":"145.578565ms","start":"2026-06-03T02:45:25.618377Z","end":"2026-06-03T02:45:25.763956Z","steps":["trace[166368372] 'agreement among raft nodes before linearized reading' (duration: 145.41043ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:25.764103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.961072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-06-03T02:45:25.764124Z","caller":"traceutil/trace.go:171","msg":"trace[1980631765] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4920; }","duration":"183.033114ms","start":"2026-06-03T02:45:25.581082Z","end":"2026-06-03T02:45:25.764115Z","steps":["trace[1980631765] 'agreement among raft nodes before linearized reading' (duration: 182.961182ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:25.764518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.330227ms","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-06-03T02:45:25.764558Z","caller":"traceutil/trace.go:171","msg":"trace[1975231237] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4920; }","duration":"112.437329ms","start":"2026-06-03T02:45:25.65211Z","end":"2026-06-03T02:45:25.764547Z","steps":["trace[1975231237] 'agreement among raft nodes before linearized reading' (duration: 112.065918ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:30.287677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.186701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-03T02:45:30.287788Z","caller":"traceutil/trace.go:171","msg":"trace[276639232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5001; }","duration":"290.323475ms","start":"2026-06-03T02:45:29.997439Z","end":"2026-06-03T02:45:30.287762Z","steps":["trace[276639232] 'range keys from in-memory index tree' (duration: 290.036077ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:30.287828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.76832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b9f97188-6984-47ce-854f-16b1e7c71eb1\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-06-03T02:45:30.28793Z","caller":"traceutil/trace.go:171","msg":"trace[1107708347] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b9f97188-6984-47ce-854f-16b1e7c71eb1; range_end:; response_count:1; response_revision:5001; }","duration":"271.891203ms","start":"2026-06-03T02:45:30.016011Z","end":"2026-06-03T02:45:30.287902Z","steps":["trace[1107708347] 'range keys from in-memory index tree' (duration: 271.550973ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:30.288483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.465551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-03T02:45:30.288522Z","caller":"traceutil/trace.go:171","msg":"trace[1360691150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5001; }","duration":"292.544814ms","start":"2026-06-03T02:45:29.995967Z","end":"2026-06-03T02:45:30.288512Z","steps":["trace[1360691150] 'range keys from in-memory index tree' (duration: 292.359298ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:45:30.817885Z","caller":"traceutil/trace.go:171","msg":"trace[1298765686] transaction","detail":"{read_only:false; response_revision:5002; number_of_response:1; }","duration":"524.045935ms","start":"2026-06-03T02:45:30.293809Z","end":"2026-06-03T02:45:30.817855Z","steps":["trace[1298765686] 'process raft request' (duration: 521.64133ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:30.818034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:45:30.293777Z","time spent":"524.181669ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T02:45:30.844796Z","caller":"traceutil/trace.go:171","msg":"trace[1447771933] linearizableReadLoop","detail":"{readStateIndex:5198; appliedIndex:5196; }","duration":"386.168229ms","start":"2026-06-03T02:45:30.458602Z","end":"2026-06-03T02:45:30.84477Z","steps":["trace[1447771933] 'read index received' (duration: 357.037024ms)","trace[1447771933] 'applied index is now lower than readState.Index' (duration: 29.130235ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:45:30.844919Z","caller":"traceutil/trace.go:171","msg":"trace[2003423715] transaction","detail":"{read_only:false; response_revision:5003; number_of_response:1; }","duration":"550.483346ms","start":"2026-06-03T02:45:30.294424Z","end":"2026-06-03T02:45:30.844907Z","steps":["trace[2003423715] 'process raft request' (duration: 550.227588ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:30.845011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:45:30.294417Z","time spent":"550.534637ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T02:45:30.845341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.746706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b9f97188-6984-47ce-854f-16b1e7c71eb1\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-06-03T02:45:30.845388Z","caller":"traceutil/trace.go:171","msg":"trace[1030576845] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b9f97188-6984-47ce-854f-16b1e7c71eb1; range_end:; response_count:1; response_revision:5003; }","duration":"386.831409ms","start":"2026-06-03T02:45:30.458545Z","end":"2026-06-03T02:45:30.845376Z","steps":["trace[1030576845] 'agreement among raft nodes before linearized reading' (duration: 386.685475ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:30.845422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:45:30.458521Z","time spent":"386.89132ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b9f97188-6984-47ce-854f-16b1e7c71eb1\" "} {"level":"warn","ts":"2026-06-03T02:45:30.845638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.030804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T02:45:30.845789Z","caller":"traceutil/trace.go:171","msg":"trace[1729529202] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:5003; }","duration":"140.200489ms","start":"2026-06-03T02:45:30.705558Z","end":"2026-06-03T02:45:30.845759Z","steps":["trace[1729529202] 'agreement among raft nodes before linearized reading' (duration: 139.984912ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:30.845991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.426218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T02:45:30.84612Z","caller":"traceutil/trace.go:171","msg":"trace[2028224362] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5003; }","duration":"113.628974ms","start":"2026-06-03T02:45:30.732466Z","end":"2026-06-03T02:45:30.846095Z","steps":["trace[2028224362] 'agreement among raft nodes before linearized reading' (duration: 113.386547ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:45:31.091285Z","caller":"traceutil/trace.go:171","msg":"trace[1547357381] linearizableReadLoop","detail":"{readStateIndex:5199; appliedIndex:5198; }","duration":"228.732566ms","start":"2026-06-03T02:45:30.862527Z","end":"2026-06-03T02:45:31.09126Z","steps":["trace[1547357381] 'read index received' (duration: 228.488989ms)","trace[1547357381] 'applied index is now lower than readState.Index' (duration: 241.827µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:45:31.091385Z","caller":"traceutil/trace.go:171","msg":"trace[868942147] transaction","detail":"{read_only:false; response_revision:5004; number_of_response:1; }","duration":"233.486264ms","start":"2026-06-03T02:45:30.857836Z","end":"2026-06-03T02:45:31.091322Z","steps":["trace[868942147] 'process raft request' (duration: 233.250117ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:31.091772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.227362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-06-03T02:45:31.091833Z","caller":"traceutil/trace.go:171","msg":"trace[1640309450] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5004; }","duration":"229.346415ms","start":"2026-06-03T02:45:30.862471Z","end":"2026-06-03T02:45:31.091817Z","steps":["trace[1640309450] 'agreement among raft nodes before linearized reading' (duration: 228.928562ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:45:31.126377Z","caller":"traceutil/trace.go:171","msg":"trace[1714309052] transaction","detail":"{read_only:false; response_revision:5008; number_of_response:1; }","duration":"152.962895ms","start":"2026-06-03T02:45:30.97338Z","end":"2026-06-03T02:45:31.126343Z","steps":["trace[1714309052] 'process raft request' (duration: 152.731478ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:45:31.126466Z","caller":"traceutil/trace.go:171","msg":"trace[1810657709] transaction","detail":"{read_only:false; response_revision:5006; number_of_response:1; }","duration":"175.427303ms","start":"2026-06-03T02:45:30.950991Z","end":"2026-06-03T02:45:31.126418Z","steps":["trace[1810657709] 'process raft request' (duration: 175.01416ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:31.126652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.009979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-06-03T02:45:31.1267Z","caller":"traceutil/trace.go:171","msg":"trace[142350346] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5008; }","duration":"135.117653ms","start":"2026-06-03T02:45:30.991572Z","end":"2026-06-03T02:45:31.12669Z","steps":["trace[142350346] 'agreement among raft nodes before linearized reading' (duration: 134.950048ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:45:31.126961Z","caller":"traceutil/trace.go:171","msg":"trace[770525456] transaction","detail":"{read_only:false; response_revision:5007; number_of_response:1; }","duration":"153.76116ms","start":"2026-06-03T02:45:30.97319Z","end":"2026-06-03T02:45:31.126951Z","steps":["trace[770525456] 'process raft request' (duration: 152.864703ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:45:31.129784Z","caller":"traceutil/trace.go:171","msg":"trace[1451862729] transaction","detail":"{read_only:false; response_revision:5005; number_of_response:1; }","duration":"247.082686ms","start":"2026-06-03T02:45:30.879641Z","end":"2026-06-03T02:45:31.126724Z","steps":["trace[1451862729] 'process raft request' (duration: 246.123487ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:33.529477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.790862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.202\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-03T02:45:33.529685Z","caller":"traceutil/trace.go:171","msg":"trace[844719745] range","detail":"{range_begin:/registry/masterleases/199.19.213.202; range_end:; response_count:1; response_revision:5033; }","duration":"150.06171ms","start":"2026-06-03T02:45:33.379599Z","end":"2026-06-03T02:45:33.529661Z","steps":["trace[844719745] 'range keys from in-memory index tree' (duration: 149.540525ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:45:35.712373Z","caller":"traceutil/trace.go:171","msg":"trace[89112445] linearizableReadLoop","detail":"{readStateIndex:5269; appliedIndex:5268; }","duration":"341.447595ms","start":"2026-06-03T02:45:35.370901Z","end":"2026-06-03T02:45:35.712349Z","steps":["trace[89112445] 'read index received' (duration: 341.127295ms)","trace[89112445] 'applied index is now lower than readState.Index' (duration: 319.14µs)"],"step_count":2} {"level":"warn","ts":"2026-06-03T02:45:35.712528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.60362ms","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-06-03T02:45:35.712563Z","caller":"traceutil/trace.go:171","msg":"trace[1204055346] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:5073; }","duration":"341.716693ms","start":"2026-06-03T02:45:35.370835Z","end":"2026-06-03T02:45:35.712552Z","steps":["trace[1204055346] 'agreement among raft nodes before linearized reading' (duration: 341.61837ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:45:35.712519Z","caller":"traceutil/trace.go:171","msg":"trace[1353411475] transaction","detail":"{read_only:false; response_revision:5073; number_of_response:1; }","duration":"392.377007ms","start":"2026-06-03T02:45:35.320093Z","end":"2026-06-03T02:45:35.71247Z","steps":["trace[1353411475] 'process raft request' (duration: 392.023537ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:35.712609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:45:35.370797Z","time spent":"341.801115ms","remote":"127.0.0.1:58564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":3,"response size":30,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-03T02:45:35.712732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:45:35.320078Z","time spent":"392.543993ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3948,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T02:45:46.479959Z","caller":"traceutil/trace.go:171","msg":"trace[1960290497] linearizableReadLoop","detail":"{readStateIndex:5403; appliedIndex:5402; }","duration":"315.033238ms","start":"2026-06-03T02:45:46.164893Z","end":"2026-06-03T02:45:46.479926Z","steps":["trace[1960290497] 'read index received' (duration: 314.721378ms)","trace[1960290497] 'applied index is now lower than readState.Index' (duration: 310.84µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:45:46.480348Z","caller":"traceutil/trace.go:171","msg":"trace[1550683910] transaction","detail":"{read_only:false; response_revision:5203; number_of_response:1; }","duration":"338.379718ms","start":"2026-06-03T02:45:46.141952Z","end":"2026-06-03T02:45:46.480332Z","steps":["trace[1550683910] 'process raft request' (duration: 337.80886ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:46.48053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.597756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-03T02:45:46.480475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:45:46.141922Z","time spent":"338.46008ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T02:45:46.48067Z","caller":"traceutil/trace.go:171","msg":"trace[357110704] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:5203; }","duration":"315.804132ms","start":"2026-06-03T02:45:46.164834Z","end":"2026-06-03T02:45:46.480638Z","steps":["trace[357110704] 'agreement among raft nodes before linearized reading' (duration: 315.543414ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:46.480828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:45:46.164749Z","time spent":"315.976078ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-06-03T02:45:46.48162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.299439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-06-03T02:45:46.481685Z","caller":"traceutil/trace.go:171","msg":"trace[709315620] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5203; }","duration":"294.57199ms","start":"2026-06-03T02:45:46.187097Z","end":"2026-06-03T02:45:46.481669Z","steps":["trace[709315620] 'agreement among raft nodes before linearized reading' (duration: 293.173036ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:47.002212Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10434733182931389935,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-03T02:45:47.223138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.487453ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T02:45:47.223391Z","caller":"traceutil/trace.go:171","msg":"trace[2079642823] linearizableReadLoop","detail":"{readStateIndex:5405; appliedIndex:5403; }","duration":"721.295377ms","start":"2026-06-03T02:45:46.502075Z","end":"2026-06-03T02:45:47.223371Z","steps":["trace[2079642823] 'read index received' (duration: 553.645339ms)","trace[2079642823] 'applied index is now lower than readState.Index' (duration: 167.648938ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:45:47.223633Z","caller":"traceutil/trace.go:171","msg":"trace[2119927696] transaction","detail":"{read_only:false; response_revision:5204; number_of_response:1; }","duration":"725.373644ms","start":"2026-06-03T02:45:46.498245Z","end":"2026-06-03T02:45:47.223618Z","steps":["trace[2119927696] 'process raft request' (duration: 557.263523ms)","trace[2119927696] 'compare' (duration: 167.071649ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T02:45:47.223703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:45:46.498222Z","time spent":"725.443497ms","remote":"127.0.0.1:58362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":731,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T02:45:47.224105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.00601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-06-03T02:45:47.224135Z","caller":"traceutil/trace.go:171","msg":"trace[679578830] transaction","detail":"{read_only:false; response_revision:5205; number_of_response:1; }","duration":"404.467435ms","start":"2026-06-03T02:45:46.819641Z","end":"2026-06-03T02:45:47.224109Z","steps":["trace[679578830] 'process raft request' (duration: 403.626688ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:45:47.224179Z","caller":"traceutil/trace.go:171","msg":"trace[175409840] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5205; }","duration":"722.101893ms","start":"2026-06-03T02:45:46.502059Z","end":"2026-06-03T02:45:47.224161Z","steps":["trace[175409840] 'agreement among raft nodes before linearized reading' (duration: 721.869355ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:47.224217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:45:46.502049Z","time spent":"722.159835ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-03T02:45:47.224305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:45:46.819618Z","time spent":"404.578868ms","remote":"127.0.0.1:58476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11703,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T02:45:47.243457Z","caller":"traceutil/trace.go:171","msg":"trace[1814982962] transaction","detail":"{read_only:false; response_revision:5206; number_of_response:1; }","duration":"100.255871ms","start":"2026-06-03T02:45:47.143168Z","end":"2026-06-03T02:45:47.243424Z","steps":["trace[1814982962] 'process raft request' (duration: 100.113157ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:47.244536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.959419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-03T02:45:47.244616Z","caller":"traceutil/trace.go:171","msg":"trace[849479837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5206; }","duration":"299.106664ms","start":"2026-06-03T02:45:46.94549Z","end":"2026-06-03T02:45:47.244597Z","steps":["trace[849479837] 'agreement among raft nodes before linearized reading' (duration: 298.841786ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:47.244637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.289232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-06-03T02:45:47.24468Z","caller":"traceutil/trace.go:171","msg":"trace[790976002] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5206; }","duration":"472.350544ms","start":"2026-06-03T02:45:46.772314Z","end":"2026-06-03T02:45:47.244665Z","steps":["trace[790976002] 'agreement among raft nodes before linearized reading' (duration: 472.159338ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:47.244708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:45:46.772144Z","time spent":"472.55536ms","remote":"127.0.0.1:58464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-06-03T02:45:47.244714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.417127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-06-03T02:45:47.244759Z","caller":"traceutil/trace.go:171","msg":"trace[16232328] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5206; }","duration":"742.51225ms","start":"2026-06-03T02:45:46.502232Z","end":"2026-06-03T02:45:47.244745Z","steps":["trace[16232328] 'agreement among raft nodes before linearized reading' (duration: 742.367275ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:47.244785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:45:46.502208Z","time spent":"742.568792ms","remote":"127.0.0.1:58714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":9662,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" "} {"level":"warn","ts":"2026-06-03T02:45:47.244799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.516181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-03T02:45:47.244853Z","caller":"traceutil/trace.go:171","msg":"trace[929792373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5206; }","duration":"294.598103ms","start":"2026-06-03T02:45:46.950242Z","end":"2026-06-03T02:45:47.24484Z","steps":["trace[929792373] 'agreement among raft nodes before linearized reading' (duration: 294.451128ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:47.24489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.206629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T02:45:47.244918Z","caller":"traceutil/trace.go:171","msg":"trace[1552162188] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5206; }","duration":"423.255781ms","start":"2026-06-03T02:45:46.821654Z","end":"2026-06-03T02:45:47.24491Z","steps":["trace[1552162188] 'agreement among raft nodes before linearized reading' (duration: 423.208519ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:47.244938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:45:46.821643Z","time spent":"423.290842ms","remote":"127.0.0.1:58278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-03T02:45:47.402923Z","caller":"traceutil/trace.go:171","msg":"trace[954158142] transaction","detail":"{read_only:false; response_revision:5209; number_of_response:1; }","duration":"153.380331ms","start":"2026-06-03T02:45:47.249511Z","end":"2026-06-03T02:45:47.402891Z","steps":["trace[954158142] 'process raft request' (duration: 153.276438ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:45:47.403265Z","caller":"traceutil/trace.go:171","msg":"trace[789981447] transaction","detail":"{read_only:false; response_revision:5210; number_of_response:1; }","duration":"151.999618ms","start":"2026-06-03T02:45:47.251239Z","end":"2026-06-03T02:45:47.403239Z","steps":["trace[789981447] 'process raft request' (duration: 151.594195ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:45:47.403279Z","caller":"traceutil/trace.go:171","msg":"trace[230760319] transaction","detail":"{read_only:false; response_revision:5208; number_of_response:1; }","duration":"154.405534ms","start":"2026-06-03T02:45:47.248836Z","end":"2026-06-03T02:45:47.403242Z","steps":["trace[230760319] 'process raft request' (duration: 90.332023ms)","trace[230760319] 'compare' (duration: 63.428671ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:45:47.403494Z","caller":"traceutil/trace.go:171","msg":"trace[868719423] transaction","detail":"{read_only:false; response_revision:5211; number_of_response:1; }","duration":"151.464501ms","start":"2026-06-03T02:45:47.252022Z","end":"2026-06-03T02:45:47.403487Z","steps":["trace[868719423] 'process raft request' (duration: 151.13474ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:45:47.403795Z","caller":"traceutil/trace.go:171","msg":"trace[1670553146] transaction","detail":"{read_only:false; response_revision:5212; number_of_response:1; }","duration":"151.355157ms","start":"2026-06-03T02:45:47.252432Z","end":"2026-06-03T02:45:47.403787Z","steps":["trace[1670553146] 'process raft request' (duration: 151.028267ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:45:47.404709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.716023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-b9f97188-6984-47ce-854f-16b1e7c71eb1\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-06-03T02:45:47.404759Z","caller":"traceutil/trace.go:171","msg":"trace[960061814] range","detail":"{range_begin:/registry/persistentvolumes/pvc-b9f97188-6984-47ce-854f-16b1e7c71eb1; range_end:; response_count:1; response_revision:5212; }","duration":"154.794135ms","start":"2026-06-03T02:45:47.249954Z","end":"2026-06-03T02:45:47.404749Z","steps":["trace[960061814] 'agreement among raft nodes before linearized reading' (duration: 154.577178ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:45:47.403755Z","caller":"traceutil/trace.go:171","msg":"trace[1032348224] linearizableReadLoop","detail":"{readStateIndex:5412; appliedIndex:5407; }","duration":"131.091565ms","start":"2026-06-03T02:45:47.272626Z","end":"2026-06-03T02:45:47.403717Z","steps":["trace[1032348224] 'read index received' (duration: 66.461096ms)","trace[1032348224] 'applied index is now lower than readState.Index' (duration: 64.627819ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T02:45:47.405564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.98346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-06-03T02:45:47.405642Z","caller":"traceutil/trace.go:171","msg":"trace[711098786] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5212; }","duration":"122.147125ms","start":"2026-06-03T02:45:47.283474Z","end":"2026-06-03T02:45:47.405621Z","steps":["trace[711098786] 'agreement among raft nodes before linearized reading' (duration: 121.792564ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:45:47.987448Z","caller":"traceutil/trace.go:171","msg":"trace[625044027] transaction","detail":"{read_only:false; response_revision:5215; number_of_response:1; }","duration":"174.027076ms","start":"2026-06-03T02:45:47.813395Z","end":"2026-06-03T02:45:47.987422Z","steps":["trace[625044027] 'process raft request' (duration: 115.162877ms)","trace[625044027] 'compare' (duration: 58.656933ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T02:45:48.255477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.651628ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T02:45:48.255709Z","caller":"traceutil/trace.go:171","msg":"trace[1063500273] transaction","detail":"{read_only:false; response_revision:5219; number_of_response:1; }","duration":"243.294084ms","start":"2026-06-03T02:45:48.012346Z","end":"2026-06-03T02:45:48.25564Z","steps":["trace[1063500273] 'process raft request' (duration: 125.359218ms)","trace[1063500273] 'compare' (duration: 117.294436ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:45:48.255854Z","caller":"traceutil/trace.go:171","msg":"trace[56410125] transaction","detail":"{read_only:false; response_revision:5220; number_of_response:1; }","duration":"243.373946ms","start":"2026-06-03T02:45:48.012436Z","end":"2026-06-03T02:45:48.25581Z","steps":["trace[56410125] 'process raft request' (duration: 243.145139ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:46:10.209134Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2860} {"level":"info","ts":"2026-06-03T02:46:10.284114Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2860,"took":"73.570887ms","hash":542649723,"current-db-size-bytes":51351552,"current-db-size":"51 MB","current-db-size-in-use-bytes":27611136,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-03T02:46:10.284176Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":542649723,"revision":2860,"compact-revision":-1} {"level":"info","ts":"2026-06-03T02:46:24.63315Z","caller":"traceutil/trace.go:171","msg":"trace[401548576] transaction","detail":"{read_only:false; response_revision:5535; number_of_response:1; }","duration":"164.642288ms","start":"2026-06-03T02:46:24.468484Z","end":"2026-06-03T02:46:24.633126Z","steps":["trace[401548576] 'process raft request' (duration: 164.500604ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:46:24.940389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.670112ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T02:46:24.940543Z","caller":"traceutil/trace.go:171","msg":"trace[1712222536] linearizableReadLoop","detail":"{readStateIndex:5746; appliedIndex:5745; }","duration":"117.152446ms","start":"2026-06-03T02:46:24.823367Z","end":"2026-06-03T02:46:24.940519Z","steps":["trace[1712222536] 'read index received' (duration: 57.142µs)","trace[1712222536] 'applied index is now lower than readState.Index' (duration: 117.093324ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T02:46:24.940616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.247369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T02:46:24.940641Z","caller":"traceutil/trace.go:171","msg":"trace[827712957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5536; }","duration":"117.319682ms","start":"2026-06-03T02:46:24.823311Z","end":"2026-06-03T02:46:24.940631Z","steps":["trace[827712957] 'agreement among raft nodes before linearized reading' (duration: 117.26706ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:46:24.941028Z","caller":"traceutil/trace.go:171","msg":"trace[1100022416] transaction","detail":"{read_only:false; response_revision:5536; number_of_response:1; }","duration":"472.145365ms","start":"2026-06-03T02:46:24.468843Z","end":"2026-06-03T02:46:24.940989Z","steps":["trace[1100022416] 'process raft request' (duration: 335.75868ms)","trace[1100022416] 'compare' (duration: 135.492897ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T02:46:24.941222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:46:24.468815Z","time spent":"472.295729ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T02:46:27.834629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.201197ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T02:46:27.835072Z","caller":"traceutil/trace.go:171","msg":"trace[2065266489] transaction","detail":"{read_only:false; response_revision:5552; number_of_response:1; }","duration":"198.253427ms","start":"2026-06-03T02:46:27.636793Z","end":"2026-06-03T02:46:27.835046Z","steps":["trace[2065266489] 'process raft request' (duration: 40.512293ms)","trace[2065266489] 'compare' (duration: 156.688381ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:46:44.901832Z","caller":"traceutil/trace.go:171","msg":"trace[434383633] transaction","detail":"{read_only:false; response_revision:5667; number_of_response:1; }","duration":"112.473636ms","start":"2026-06-03T02:46:44.789335Z","end":"2026-06-03T02:46:44.901809Z","steps":["trace[434383633] 'process raft request' (duration: 112.096455ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:49:32.118895Z","caller":"traceutil/trace.go:171","msg":"trace[1915111976] transaction","detail":"{read_only:false; response_revision:6827; number_of_response:1; }","duration":"127.845751ms","start":"2026-06-03T02:49:31.991006Z","end":"2026-06-03T02:49:32.118852Z","steps":["trace[1915111976] 'process raft request' (duration: 127.206281ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:50:10.844964Z","caller":"traceutil/trace.go:171","msg":"trace[499398831] transaction","detail":"{read_only:false; response_revision:7083; number_of_response:1; }","duration":"200.184334ms","start":"2026-06-03T02:50:10.644749Z","end":"2026-06-03T02:50:10.844934Z","steps":["trace[499398831] 'process raft request' (duration: 200.025961ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:50:11.054417Z","caller":"traceutil/trace.go:171","msg":"trace[1569510759] linearizableReadLoop","detail":"{readStateIndex:7349; appliedIndex:7346; }","duration":"226.55557ms","start":"2026-06-03T02:50:10.827824Z","end":"2026-06-03T02:50:11.054379Z","steps":["trace[1569510759] 'read index received' (duration: 17.001683ms)","trace[1569510759] 'applied index is now lower than readState.Index' (duration: 209.552717ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T02:50:11.054557Z","caller":"traceutil/trace.go:171","msg":"trace[936604409] transaction","detail":"{read_only:false; response_revision:7084; number_of_response:1; }","duration":"354.945553ms","start":"2026-06-03T02:50:10.699575Z","end":"2026-06-03T02:50:11.054521Z","steps":["trace[936604409] 'process raft request' (duration: 354.562935ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:50:11.054666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.824016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T02:50:11.054712Z","caller":"traceutil/trace.go:171","msg":"trace[932363683] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7085; }","duration":"226.946087ms","start":"2026-06-03T02:50:10.827755Z","end":"2026-06-03T02:50:11.054701Z","steps":["trace[932363683] 'agreement among raft nodes before linearized reading' (duration: 226.814505ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:50:11.054771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:50:10.699543Z","time spent":"355.118275ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T02:50:11.054618Z","caller":"traceutil/trace.go:171","msg":"trace[789235232] transaction","detail":"{read_only:false; response_revision:7085; number_of_response:1; }","duration":"354.25635ms","start":"2026-06-03T02:50:10.700325Z","end":"2026-06-03T02:50:11.054581Z","steps":["trace[789235232] 'process raft request' (duration: 354.007136ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T02:50:11.054958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T02:50:10.700305Z","time spent":"354.569815ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T02:50:11.077955Z","caller":"traceutil/trace.go:171","msg":"trace[666734585] transaction","detail":"{read_only:false; response_revision:7086; number_of_response:1; }","duration":"166.977576ms","start":"2026-06-03T02:50:10.910964Z","end":"2026-06-03T02:50:11.077942Z","steps":["trace[666734585] 'process raft request' (duration: 166.581198ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T02:51:10.225385Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5365} {"level":"info","ts":"2026-06-03T02:51:10.323593Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5365,"took":"93.700975ms","hash":4055952808,"current-db-size-bytes":51351552,"current-db-size":"51 MB","current-db-size-in-use-bytes":18821120,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-06-03T02:51:10.32368Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4055952808,"revision":5365,"compact-revision":2860}