{"level":"warn","ts":"2026-08-16T12:53:05.681243Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-16T12:53:05.681332Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.222:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.222:2380","--initial-cluster=instance=https://199.204.45.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.222:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-16T12:53:05.681399Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-16T12:53:05.681418Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-08-16T12:53:05.68145Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-16T12:53:05.682101Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"]} {"level":"info","ts":"2026-08-16T12:53:05.682174Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.222:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-16T12:53:05.684832Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.439378ms"} {"level":"info","ts":"2026-08-16T12:53:05.691752Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"380f6760faea5f9e","cluster-id":"b87e8aca83a17eab"} {"level":"info","ts":"2026-08-16T12:53:05.691834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=()"} {"level":"info","ts":"2026-08-16T12:53:05.691857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 0"} {"level":"info","ts":"2026-08-16T12:53:05.691868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 380f6760faea5f9e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-16T12:53:05.69189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 1"} {"level":"info","ts":"2026-08-16T12:53:05.691943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"warn","ts":"2026-08-16T12:53:05.7011Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-16T12:53:05.706523Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-16T12:53:05.708292Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-16T12:53:05.711097Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"380f6760faea5f9e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-16T12:53:05.711269Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"380f6760faea5f9e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-16T12:53:05.711336Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-16T12:53:05.711616Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-16T12:53:05.712075Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-16T12:53:05.712494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"info","ts":"2026-08-16T12:53:05.712594Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","added-peer-id":"380f6760faea5f9e","added-peer-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-08-16T12:53:05.714327Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-16T12:53:05.714553Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"380f6760faea5f9e","initial-advertise-peer-urls":["https://199.204.45.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-16T12:53:05.714588Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-16T12:53:05.714728Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-08-16T12:53:05.714755Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-08-16T12:53:06.692483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e is starting a new election at term 1"} {"level":"info","ts":"2026-08-16T12:53:06.692548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became pre-candidate at term 1"} {"level":"info","ts":"2026-08-16T12:53:06.692573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgPreVoteResp from 380f6760faea5f9e at term 1"} {"level":"info","ts":"2026-08-16T12:53:06.692593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became candidate at term 2"} {"level":"info","ts":"2026-08-16T12:53:06.692604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgVoteResp from 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-08-16T12:53:06.692672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became leader at term 2"} {"level":"info","ts":"2026-08-16T12:53:06.692687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 380f6760faea5f9e elected leader 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-08-16T12:53:06.694074Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:53:06.694928Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-16T12:53:06.69492Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"380f6760faea5f9e","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.222:2379]}","request-path":"/0/members/380f6760faea5f9e/attributes","cluster-id":"b87e8aca83a17eab","publish-timeout":"7s"} {"level":"info","ts":"2026-08-16T12:53:06.694989Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-16T12:53:06.695244Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-16T12:53:06.695305Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-16T12:53:06.695734Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:53:06.695827Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:53:06.695862Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:53:06.698656Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-16T12:53:06.698886Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.222:2379"} {"level":"info","ts":"2026-08-16T12:53:31.786857Z","caller":"traceutil/trace.go:171","msg":"trace[829448508] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"205.269717ms","start":"2026-08-16T12:53:31.581563Z","end":"2026-08-16T12:53:31.786832Z","steps":["trace[829448508] 'process raft request' (duration: 205.02335ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:53:51.58809Z","caller":"traceutil/trace.go:171","msg":"trace[26570917] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"112.491754ms","start":"2026-08-16T12:53:51.475574Z","end":"2026-08-16T12:53:51.588066Z","steps":["trace[26570917] 'process raft request' (duration: 112.34159ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:53:51.861097Z","caller":"traceutil/trace.go:171","msg":"trace[1313103290] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"127.885046ms","start":"2026-08-16T12:53:51.733156Z","end":"2026-08-16T12:53:51.861041Z","steps":["trace[1313103290] 'process raft request' (duration: 127.072323ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:52.568555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.78383ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:53:52.569184Z","caller":"traceutil/trace.go:171","msg":"trace[1354822675] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"646.447587ms","start":"2026-08-16T12:53:51.922695Z","end":"2026-08-16T12:53:52.569143Z","steps":["trace[1354822675] 'process raft request' (duration: 352.555486ms)","trace[1354822675] 'compare' (duration: 292.592935ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:53:52.56914Z","caller":"traceutil/trace.go:171","msg":"trace[473299108] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"613.759411ms","start":"2026-08-16T12:53:51.955334Z","end":"2026-08-16T12:53:52.569093Z","steps":["trace[473299108] 'read index received' (duration: 319.812268ms)","trace[473299108] 'applied index is now lower than readState.Index' (duration: 293.942723ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:53:52.569362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:51.922656Z","time spent":"646.626522ms","remote":"127.0.0.1:43252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-16T12:53:52.569912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.741716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-16T12:53:52.570015Z","caller":"traceutil/trace.go:171","msg":"trace[972424784] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"467.980793ms","start":"2026-08-16T12:53:52.102005Z","end":"2026-08-16T12:53:52.569986Z","steps":["trace[972424784] 'agreement among raft nodes before linearized reading' (duration: 467.474839ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:52.570068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:52.101977Z","time spent":"468.081526ms","remote":"127.0.0.1:43196","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-08-16T12:53:52.570146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.767439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-16T12:53:52.570276Z","caller":"traceutil/trace.go:171","msg":"trace[640491972] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:632; }","duration":"614.948614ms","start":"2026-08-16T12:53:51.955296Z","end":"2026-08-16T12:53:52.570245Z","steps":["trace[640491972] 'agreement among raft nodes before linearized reading' (duration: 613.927226ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:53:52.570332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:53:51.955285Z","time spent":"615.036016ms","remote":"127.0.0.1:43196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-16T12:54:08.771404Z","caller":"traceutil/trace.go:171","msg":"trace[264652836] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"102.316969ms","start":"2026-08-16T12:54:08.669054Z","end":"2026-08-16T12:54:08.771371Z","steps":["trace[264652836] 'process raft request' (duration: 102.116363ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:09.371479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.405173ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:54:09.371604Z","caller":"traceutil/trace.go:171","msg":"trace[601005461] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:828; }","duration":"666.99622ms","start":"2026-08-16T12:54:08.704587Z","end":"2026-08-16T12:54:09.371583Z","steps":["trace[601005461] 'read index received' (duration: 66.592558ms)","trace[601005461] 'applied index is now lower than readState.Index' (duration: 600.401832ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:54:09.371715Z","caller":"traceutil/trace.go:171","msg":"trace[1080315373] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"702.553247ms","start":"2026-08-16T12:54:08.669128Z","end":"2026-08-16T12:54:09.371681Z","steps":["trace[1080315373] 'process raft request' (duration: 428.622499ms)","trace[1080315373] 'compare' (duration: 273.28332ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:54:09.371785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.206736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-16T12:54:09.371818Z","caller":"traceutil/trace.go:171","msg":"trace[1086677989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:805; }","duration":"667.286439ms","start":"2026-08-16T12:54:08.704521Z","end":"2026-08-16T12:54:09.371807Z","steps":["trace[1086677989] 'agreement among raft nodes before linearized reading' (duration: 667.155375ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:09.371857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:08.704497Z","time spent":"667.35276ms","remote":"127.0.0.1:43196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-16T12:54:09.371855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.490948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-16T12:54:09.371866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.026943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-16T12:54:09.371912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.961689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-16T12:54:09.371956Z","caller":"traceutil/trace.go:171","msg":"trace[1598427550] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"517.660432ms","start":"2026-08-16T12:54:08.854272Z","end":"2026-08-16T12:54:09.371933Z","steps":["trace[1598427550] 'agreement among raft nodes before linearized reading' (duration: 517.491067ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:09.371948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.507965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-16T12:54:09.371983Z","caller":"traceutil/trace.go:171","msg":"trace[719371495] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:805; }","duration":"412.068472ms","start":"2026-08-16T12:54:08.959896Z","end":"2026-08-16T12:54:09.371965Z","steps":["trace[719371495] 'agreement among raft nodes before linearized reading' (duration: 411.884747ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:09.371975Z","caller":"traceutil/trace.go:171","msg":"trace[490939571] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:805; }","duration":"273.183737ms","start":"2026-08-16T12:54:09.098768Z","end":"2026-08-16T12:54:09.371952Z","steps":["trace[490939571] 'agreement among raft nodes before linearized reading' (duration: 273.002622ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:54:09.372025Z","caller":"traceutil/trace.go:171","msg":"trace[1691226555] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:805; }","duration":"253.644179ms","start":"2026-08-16T12:54:09.118364Z","end":"2026-08-16T12:54:09.372009Z","steps":["trace[1691226555] 'agreement among raft nodes before linearized reading' (duration: 253.459064ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:54:09.372061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:08.854245Z","time spent":"517.805516ms","remote":"127.0.0.1:42928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-16T12:54:09.372056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:08.669104Z","time spent":"702.706982ms","remote":"127.0.0.1:43196","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-08-16T12:54:09.372105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:54:08.959869Z","time spent":"412.147374ms","remote":"127.0.0.1:43196","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-08-16T12:54:36.215024Z","caller":"traceutil/trace.go:171","msg":"trace[388201589] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"109.116541ms","start":"2026-08-16T12:54:36.105876Z","end":"2026-08-16T12:54:36.214993Z","steps":["trace[388201589] 'process raft request' (duration: 108.849634ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:22.147014Z","caller":"traceutil/trace.go:171","msg":"trace[1891006910] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"180.743105ms","start":"2026-08-16T12:55:21.966235Z","end":"2026-08-16T12:55:22.146978Z","steps":["trace[1891006910] 'process raft request' (duration: 180.581551ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:56:01.401811Z","caller":"traceutil/trace.go:171","msg":"trace[1593343954] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"101.47524ms","start":"2026-08-16T12:56:01.300307Z","end":"2026-08-16T12:56:01.401782Z","steps":["trace[1593343954] 'process raft request' (duration: 71.253698ms)","trace[1593343954] 'compare' (duration: 29.990365ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:56:06.803089Z","caller":"traceutil/trace.go:171","msg":"trace[789971467] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"115.151821ms","start":"2026-08-16T12:56:06.687914Z","end":"2026-08-16T12:56:06.803066Z","steps":["trace[789971467] 'process raft request' (duration: 114.990837ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:56:12.285117Z","caller":"traceutil/trace.go:171","msg":"trace[91067984] linearizableReadLoop","detail":"{readStateIndex:2259; appliedIndex:2258; }","duration":"223.246021ms","start":"2026-08-16T12:56:12.061834Z","end":"2026-08-16T12:56:12.28508Z","steps":["trace[91067984] 'read index received' (duration: 223.002865ms)","trace[91067984] 'applied index is now lower than readState.Index' (duration: 241.416µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:56:12.285225Z","caller":"traceutil/trace.go:171","msg":"trace[122121159] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"243.971215ms","start":"2026-08-16T12:56:12.041216Z","end":"2026-08-16T12:56:12.285187Z","steps":["trace[122121159] 'process raft request' (duration: 243.656777ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:56:12.285505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.639852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-16T12:56:12.285535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.942233ms","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-08-16T12:56:12.285608Z","caller":"traceutil/trace.go:171","msg":"trace[2069305045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2181; }","duration":"223.813386ms","start":"2026-08-16T12:56:12.061772Z","end":"2026-08-16T12:56:12.285586Z","steps":["trace[2069305045] 'agreement among raft nodes before linearized reading' (duration: 223.487687ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:56:12.285613Z","caller":"traceutil/trace.go:171","msg":"trace[2122784029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2181; }","duration":"223.027225ms","start":"2026-08-16T12:56:12.062567Z","end":"2026-08-16T12:56:12.285595Z","steps":["trace[2122784029] 'agreement among raft nodes before linearized reading' (duration: 222.83733ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:57:36.005851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.956123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:57:36.005992Z","caller":"traceutil/trace.go:171","msg":"trace[1835661927] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2828; }","duration":"151.153579ms","start":"2026-08-16T12:57:35.854817Z","end":"2026-08-16T12:57:36.005971Z","steps":["trace[1835661927] 'range keys from in-memory index tree' (duration: 150.862621ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:57:36.005982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.004476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-16T12:57:36.006052Z","caller":"traceutil/trace.go:171","msg":"trace[2125099396] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2828; }","duration":"122.097839ms","start":"2026-08-16T12:57:35.883938Z","end":"2026-08-16T12:57:36.006036Z","steps":["trace[2125099396] 'range keys from in-memory index tree' (duration: 121.651806ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:57:36.473303Z","caller":"traceutil/trace.go:171","msg":"trace[2029216749] transaction","detail":"{read_only:false; response_revision:2830; number_of_response:1; }","duration":"138.556737ms","start":"2026-08-16T12:57:36.334688Z","end":"2026-08-16T12:57:36.473244Z","steps":["trace[2029216749] 'process raft request' (duration: 138.266549ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:00:24.98942Z","caller":"traceutil/trace.go:171","msg":"trace[2080214479] transaction","detail":"{read_only:false; response_revision:3923; number_of_response:1; }","duration":"589.872458ms","start":"2026-08-16T13:00:24.399522Z","end":"2026-08-16T13:00:24.989395Z","steps":["trace[2080214479] 'process raft request' (duration: 589.708693ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:00:24.989604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:00:24.399488Z","time spent":"590.024682ms","remote":"127.0.0.1:43196","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-08-16T13:00:26.864366Z","caller":"traceutil/trace.go:171","msg":"trace[1757770264] transaction","detail":"{read_only:false; response_revision:3929; number_of_response:1; }","duration":"525.205902ms","start":"2026-08-16T13:00:26.339137Z","end":"2026-08-16T13:00:26.864343Z","steps":["trace[1757770264] 'process raft request' (duration: 525.038427ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:00:26.864506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:00:26.339081Z","time spent":"525.351656ms","remote":"127.0.0.1:43196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:00:26.865726Z","caller":"traceutil/trace.go:171","msg":"trace[2061131995] transaction","detail":"{read_only:false; response_revision:3930; number_of_response:1; }","duration":"510.699479ms","start":"2026-08-16T13:00:26.354989Z","end":"2026-08-16T13:00:26.865689Z","steps":["trace[2061131995] 'process raft request' (duration: 510.050001ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:00:26.865921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:00:26.354953Z","time spent":"510.834743ms","remote":"127.0.0.1:43196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:00:26.866122Z","caller":"traceutil/trace.go:171","msg":"trace[559868628] transaction","detail":"{read_only:false; response_revision:3931; number_of_response:1; }","duration":"283.720383ms","start":"2026-08-16T13:00:26.582394Z","end":"2026-08-16T13:00:26.866114Z","steps":["trace[559868628] 'process raft request' (duration: 283.208339ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:00:26.866482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.600981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T13:00:26.866511Z","caller":"traceutil/trace.go:171","msg":"trace[1148021790] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:3931; }","duration":"115.691233ms","start":"2026-08-16T13:00:26.750813Z","end":"2026-08-16T13:00:26.866504Z","steps":["trace[1148021790] 'agreement among raft nodes before linearized reading' (duration: 115.58858ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:00:26.866033Z","caller":"traceutil/trace.go:171","msg":"trace[911141227] linearizableReadLoop","detail":"{readStateIndex:4073; appliedIndex:4071; }","duration":"115.078566ms","start":"2026-08-16T13:00:26.750882Z","end":"2026-08-16T13:00:26.865961Z","steps":["trace[911141227] 'read index received' (duration: 113.569194ms)","trace[911141227] 'applied index is now lower than readState.Index' (duration: 1.507322ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:01:37.218872Z","caller":"traceutil/trace.go:171","msg":"trace[1410075259] transaction","detail":"{read_only:false; response_revision:4836; number_of_response:1; }","duration":"112.097035ms","start":"2026-08-16T13:01:37.106746Z","end":"2026-08-16T13:01:37.218843Z","steps":["trace[1410075259] 'process raft request' (duration: 105.222404ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:01:39.386299Z","caller":"traceutil/trace.go:171","msg":"trace[3322804] transaction","detail":"{read_only:false; response_revision:4886; number_of_response:1; }","duration":"140.473205ms","start":"2026-08-16T13:01:39.245794Z","end":"2026-08-16T13:01:39.386268Z","steps":["trace[3322804] 'process raft request' (duration: 124.281135ms)","trace[3322804] 'compare' (duration: 16.038676ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:01:39.389511Z","caller":"traceutil/trace.go:171","msg":"trace[1203976399] transaction","detail":"{read_only:false; response_revision:4887; number_of_response:1; }","duration":"142.832991ms","start":"2026-08-16T13:01:39.246658Z","end":"2026-08-16T13:01:39.389491Z","steps":["trace[1203976399] 'process raft request' (duration: 142.693907ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:01:43.13543Z","caller":"traceutil/trace.go:171","msg":"trace[939368856] transaction","detail":"{read_only:false; response_revision:5065; number_of_response:1; }","duration":"151.217035ms","start":"2026-08-16T13:01:42.984183Z","end":"2026-08-16T13:01:43.1354Z","steps":["trace[939368856] 'process raft request' (duration: 151.06161ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:01:43.571069Z","caller":"traceutil/trace.go:171","msg":"trace[1590759523] transaction","detail":"{read_only:false; response_revision:5070; number_of_response:1; }","duration":"132.000449ms","start":"2026-08-16T13:01:43.439028Z","end":"2026-08-16T13:01:43.571029Z","steps":["trace[1590759523] 'process raft request' (duration: 58.456699ms)","trace[1590759523] 'compare' (duration: 73.081547ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:01:43.571202Z","caller":"traceutil/trace.go:171","msg":"trace[2140135580] linearizableReadLoop","detail":"{readStateIndex:5259; appliedIndex:5258; }","duration":"125.290662ms","start":"2026-08-16T13:01:43.445882Z","end":"2026-08-16T13:01:43.571173Z","steps":["trace[2140135580] 'read index received' (duration: 51.614989ms)","trace[2140135580] 'applied index is now lower than readState.Index' (duration: 73.674053ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:01:43.571547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.634891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-16T13:01:43.571581Z","caller":"traceutil/trace.go:171","msg":"trace[552181668] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5071; }","duration":"125.723534ms","start":"2026-08-16T13:01:43.445848Z","end":"2026-08-16T13:01:43.571572Z","steps":["trace[552181668] 'agreement among raft nodes before linearized reading' (duration: 125.529788ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:01:43.571695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.491648ms","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-08-16T13:01:43.571714Z","caller":"traceutil/trace.go:171","msg":"trace[300588783] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5071; }","duration":"106.538359ms","start":"2026-08-16T13:01:43.465169Z","end":"2026-08-16T13:01:43.571707Z","steps":["trace[300588783] 'agreement among raft nodes before linearized reading' (duration: 106.488377ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:01:43.57177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.100287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-16T13:01:43.571847Z","caller":"traceutil/trace.go:171","msg":"trace[1901954805] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5071; }","duration":"120.21076ms","start":"2026-08-16T13:01:43.451617Z","end":"2026-08-16T13:01:43.571828Z","steps":["trace[1901954805] 'agreement among raft nodes before linearized reading' (duration: 119.985934ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:01:43.571822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.775507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-16T13:01:43.571981Z","caller":"traceutil/trace.go:171","msg":"trace[183535531] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5071; }","duration":"115.938131ms","start":"2026-08-16T13:01:43.455987Z","end":"2026-08-16T13:01:43.571925Z","steps":["trace[183535531] 'agreement among raft nodes before linearized reading' (duration: 115.627552ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:01:43.572039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.764634ms","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-08-16T13:01:43.572118Z","caller":"traceutil/trace.go:171","msg":"trace[1411411448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5071; }","duration":"116.885408ms","start":"2026-08-16T13:01:43.455212Z","end":"2026-08-16T13:01:43.572098Z","steps":["trace[1411411448] 'agreement among raft nodes before linearized reading' (duration: 116.62543ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:01:49.985002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.651203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T13:01:49.985127Z","caller":"traceutil/trace.go:171","msg":"trace[1250954084] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5222; }","duration":"129.818458ms","start":"2026-08-16T13:01:49.855289Z","end":"2026-08-16T13:01:49.985107Z","steps":["trace[1250954084] 'range keys from in-memory index tree' (duration: 129.588731ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:01:54.886349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.200235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/memcached-metrics\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T13:01:54.886434Z","caller":"traceutil/trace.go:171","msg":"trace[895959332] range","detail":"{range_begin:/registry/services/specs/openstack/memcached-metrics; range_end:; response_count:0; response_revision:5295; }","duration":"132.332598ms","start":"2026-08-16T13:01:54.754083Z","end":"2026-08-16T13:01:54.886416Z","steps":["trace[895959332] 'range keys from in-memory index tree' (duration: 132.127412ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:01:55.099537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.65444ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-16T13:01:55.099896Z","caller":"traceutil/trace.go:171","msg":"trace[885354370] transaction","detail":"{read_only:false; response_revision:5297; number_of_response:1; }","duration":"115.744835ms","start":"2026-08-16T13:01:54.984128Z","end":"2026-08-16T13:01:55.099873Z","steps":["trace[885354370] 'process raft request' (duration: 115.607121ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:02:03.187728Z","caller":"traceutil/trace.go:171","msg":"trace[385099361] transaction","detail":"{read_only:false; response_revision:5495; number_of_response:1; }","duration":"111.007714ms","start":"2026-08-16T13:02:03.076682Z","end":"2026-08-16T13:02:03.18769Z","steps":["trace[385099361] 'process raft request' (duration: 55.585649ms)","trace[385099361] 'compare' (duration: 55.22842ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:02:03.361571Z","caller":"traceutil/trace.go:171","msg":"trace[1570600041] linearizableReadLoop","detail":"{readStateIndex:5697; appliedIndex:5696; }","duration":"111.374294ms","start":"2026-08-16T13:02:03.250147Z","end":"2026-08-16T13:02:03.361521Z","steps":["trace[1570600041] 'read index received' (duration: 106.859628ms)","trace[1570600041] 'applied index is now lower than readState.Index' (duration: 4.513406ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:02:03.36173Z","caller":"traceutil/trace.go:171","msg":"trace[1173586778] transaction","detail":"{read_only:false; response_revision:5499; number_of_response:1; }","duration":"128.854351ms","start":"2026-08-16T13:02:03.232861Z","end":"2026-08-16T13:02:03.361715Z","steps":["trace[1173586778] 'process raft request' (duration: 124.209471ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:02:03.3619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.742935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-08-16T13:02:03.361937Z","caller":"traceutil/trace.go:171","msg":"trace[332924805] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5499; }","duration":"111.809416ms","start":"2026-08-16T13:02:03.250117Z","end":"2026-08-16T13:02:03.361926Z","steps":["trace[332924805] 'agreement among raft nodes before linearized reading' (duration: 111.647681ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:02:03.533933Z","caller":"traceutil/trace.go:171","msg":"trace[2113296866] linearizableReadLoop","detail":"{readStateIndex:5698; appliedIndex:5697; }","duration":"153.343013ms","start":"2026-08-16T13:02:03.380563Z","end":"2026-08-16T13:02:03.533906Z","steps":["trace[2113296866] 'read index received' (duration: 93.532847ms)","trace[2113296866] 'applied index is now lower than readState.Index' (duration: 59.809016ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:02:03.534123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.529378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T13:02:03.534204Z","caller":"traceutil/trace.go:171","msg":"trace[1645947603] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:5500; }","duration":"153.642611ms","start":"2026-08-16T13:02:03.380543Z","end":"2026-08-16T13:02:03.534185Z","steps":["trace[1645947603] 'agreement among raft nodes before linearized reading' (duration: 153.446386ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:02:03.534029Z","caller":"traceutil/trace.go:171","msg":"trace[1530060023] transaction","detail":"{read_only:false; response_revision:5500; number_of_response:1; }","duration":"154.237078ms","start":"2026-08-16T13:02:03.379717Z","end":"2026-08-16T13:02:03.533954Z","steps":["trace[1530060023] 'process raft request' (duration: 94.430561ms)","trace[1530060023] 'compare' (duration: 59.155049ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:02:03.766073Z","caller":"traceutil/trace.go:171","msg":"trace[1710113873] transaction","detail":"{read_only:false; response_revision:5502; number_of_response:1; }","duration":"225.689369ms","start":"2026-08-16T13:02:03.540346Z","end":"2026-08-16T13:02:03.766036Z","steps":["trace[1710113873] 'process raft request' (duration: 225.410242ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:02:03.766157Z","caller":"traceutil/trace.go:171","msg":"trace[1646292579] transaction","detail":"{read_only:false; response_revision:5504; number_of_response:1; }","duration":"224.98441ms","start":"2026-08-16T13:02:03.54114Z","end":"2026-08-16T13:02:03.766125Z","steps":["trace[1646292579] 'process raft request' (duration: 224.837126ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:02:03.766161Z","caller":"traceutil/trace.go:171","msg":"trace[2092533854] transaction","detail":"{read_only:false; response_revision:5503; number_of_response:1; }","duration":"225.73424ms","start":"2026-08-16T13:02:03.540367Z","end":"2026-08-16T13:02:03.766101Z","steps":["trace[2092533854] 'process raft request' (duration: 225.573176ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:02:03.766201Z","caller":"traceutil/trace.go:171","msg":"trace[1072365361] linearizableReadLoop","detail":"{readStateIndex:5704; appliedIndex:5698; }","duration":"221.75705ms","start":"2026-08-16T13:02:03.544413Z","end":"2026-08-16T13:02:03.766171Z","steps":["trace[1072365361] 'read index received' (duration: 43.929245ms)","trace[1072365361] 'applied index is now lower than readState.Index' (duration: 177.826165ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:02:03.766327Z","caller":"traceutil/trace.go:171","msg":"trace[1776133566] transaction","detail":"{read_only:false; response_revision:5506; number_of_response:1; }","duration":"225.079083ms","start":"2026-08-16T13:02:03.541221Z","end":"2026-08-16T13:02:03.7663Z","steps":["trace[1776133566] 'process raft request' (duration: 224.827326ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:02:03.766494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.058758ms","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-08-16T13:02:03.766551Z","caller":"traceutil/trace.go:171","msg":"trace[860777290] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:5507; }","duration":"222.14195ms","start":"2026-08-16T13:02:03.544395Z","end":"2026-08-16T13:02:03.766537Z","steps":["trace[860777290] 'agreement among raft nodes before linearized reading' (duration: 221.955365ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:02:03.766258Z","caller":"traceutil/trace.go:171","msg":"trace[1380134946] transaction","detail":"{read_only:false; response_revision:5505; number_of_response:1; }","duration":"224.98022ms","start":"2026-08-16T13:02:03.541208Z","end":"2026-08-16T13:02:03.766188Z","steps":["trace[1380134946] 'process raft request' (duration: 224.807605ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:02:03.766822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.329466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bccd69b5-b13b-4b38-9441-e559002ed80a\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-16T13:02:03.766909Z","caller":"traceutil/trace.go:171","msg":"trace[1979822296] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bccd69b5-b13b-4b38-9441-e559002ed80a; range_end:; response_count:1; response_revision:5507; }","duration":"222.452509ms","start":"2026-08-16T13:02:03.544432Z","end":"2026-08-16T13:02:03.766885Z","steps":["trace[1979822296] 'agreement among raft nodes before linearized reading' (duration: 221.940905ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:02:03.766979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.65924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-08-16T13:02:03.767049Z","caller":"traceutil/trace.go:171","msg":"trace[641539911] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5507; }","duration":"201.756583ms","start":"2026-08-16T13:02:03.565272Z","end":"2026-08-16T13:02:03.767029Z","steps":["trace[641539911] 'agreement among raft nodes before linearized reading' (duration: 201.309331ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:02:03.76699Z","caller":"traceutil/trace.go:171","msg":"trace[644964288] transaction","detail":"{read_only:false; response_revision:5507; number_of_response:1; }","duration":"214.9562ms","start":"2026-08-16T13:02:03.551323Z","end":"2026-08-16T13:02:03.76628Z","steps":["trace[644964288] 'process raft request' (duration: 214.768845ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:02:03.767089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.209933ms","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-08-16T13:02:03.7674Z","caller":"traceutil/trace.go:171","msg":"trace[2025505584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5507; }","duration":"189.686936ms","start":"2026-08-16T13:02:03.577576Z","end":"2026-08-16T13:02:03.767263Z","steps":["trace[2025505584] 'agreement among raft nodes before linearized reading' (duration: 188.917144ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:02:03.767558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.339846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:80618"} {"level":"info","ts":"2026-08-16T13:02:03.767605Z","caller":"traceutil/trace.go:171","msg":"trace[1224857257] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5507; }","duration":"222.400788ms","start":"2026-08-16T13:02:03.545194Z","end":"2026-08-16T13:02:03.767595Z","steps":["trace[1224857257] 'agreement among raft nodes before linearized reading' (duration: 221.42091ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:02:13.994078Z","caller":"traceutil/trace.go:171","msg":"trace[1472913907] transaction","detail":"{read_only:false; response_revision:5633; number_of_response:1; }","duration":"104.032939ms","start":"2026-08-16T13:02:13.890026Z","end":"2026-08-16T13:02:13.994059Z","steps":["trace[1472913907] 'process raft request' (duration: 103.983428ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:02:13.994111Z","caller":"traceutil/trace.go:171","msg":"trace[1650975180] transaction","detail":"{read_only:false; response_revision:5631; number_of_response:1; }","duration":"104.496582ms","start":"2026-08-16T13:02:13.889579Z","end":"2026-08-16T13:02:13.994076Z","steps":["trace[1650975180] 'process raft request' (duration: 91.915321ms)","trace[1650975180] 'compare' (duration: 12.362115ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:02:13.994142Z","caller":"traceutil/trace.go:171","msg":"trace[2015816001] transaction","detail":"{read_only:false; response_revision:5632; number_of_response:1; }","duration":"104.505003ms","start":"2026-08-16T13:02:13.889607Z","end":"2026-08-16T13:02:13.994112Z","steps":["trace[2015816001] 'process raft request' (duration: 104.369169ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:02:13.994442Z","caller":"traceutil/trace.go:171","msg":"trace[1593665534] transaction","detail":"{read_only:false; response_revision:5634; number_of_response:1; }","duration":"104.127172ms","start":"2026-08-16T13:02:13.890303Z","end":"2026-08-16T13:02:13.99443Z","steps":["trace[1593665534] 'process raft request' (duration: 103.72957ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:02:13.994426Z","caller":"traceutil/trace.go:171","msg":"trace[1527284589] transaction","detail":"{read_only:false; response_revision:5635; number_of_response:1; }","duration":"103.207136ms","start":"2026-08-16T13:02:13.891198Z","end":"2026-08-16T13:02:13.994405Z","steps":["trace[1527284589] 'process raft request' (duration: 103.086723ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:03:06.726473Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2996} {"level":"info","ts":"2026-08-16T13:03:06.797909Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2996,"took":"69.502917ms","hash":253262743,"current-db-size-bytes":58212352,"current-db-size":"58 MB","current-db-size-in-use-bytes":33783808,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-16T13:03:06.798046Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":253262743,"revision":2996,"compact-revision":-1} {"level":"info","ts":"2026-08-16T13:08:06.748656Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6067} {"level":"info","ts":"2026-08-16T13:08:06.950071Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6067,"took":"199.375728ms","hash":3960093800,"current-db-size-bytes":58212352,"current-db-size":"58 MB","current-db-size-in-use-bytes":22667264,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-16T13:08:06.950153Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3960093800,"revision":6067,"compact-revision":2996} {"level":"info","ts":"2026-08-16T13:08:11.880131Z","caller":"traceutil/trace.go:171","msg":"trace[1508936418] transaction","detail":"{read_only:false; response_revision:8319; number_of_response:1; }","duration":"192.652094ms","start":"2026-08-16T13:08:11.687432Z","end":"2026-08-16T13:08:11.880084Z","steps":["trace[1508936418] 'process raft request' (duration: 192.371266ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:08:12.008109Z","caller":"traceutil/trace.go:171","msg":"trace[651211672] transaction","detail":"{read_only:false; response_revision:8320; number_of_response:1; }","duration":"111.88651ms","start":"2026-08-16T13:08:11.896188Z","end":"2026-08-16T13:08:12.008075Z","steps":["trace[651211672] 'process raft request' (duration: 111.19312ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:08:22.273367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.139862ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T13:08:22.273632Z","caller":"traceutil/trace.go:171","msg":"trace[1273016273] transaction","detail":"{read_only:false; response_revision:8378; number_of_response:1; }","duration":"117.576398ms","start":"2026-08-16T13:08:22.156037Z","end":"2026-08-16T13:08:22.273613Z","steps":["trace[1273016273] 'process raft request' (duration: 12.061695ms)","trace[1273016273] 'compare' (duration: 104.934967ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:08:22.79654Z","caller":"traceutil/trace.go:171","msg":"trace[1164637301] transaction","detail":"{read_only:false; response_revision:8379; number_of_response:1; }","duration":"171.561928ms","start":"2026-08-16T13:08:22.62495Z","end":"2026-08-16T13:08:22.796512Z","steps":["trace[1164637301] 'process raft request' (duration: 171.423244ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:08:22.816708Z","caller":"traceutil/trace.go:171","msg":"trace[1585642628] transaction","detail":"{read_only:false; response_revision:8380; number_of_response:1; }","duration":"181.487815ms","start":"2026-08-16T13:08:22.635183Z","end":"2026-08-16T13:08:22.816671Z","steps":["trace[1585642628] 'process raft request' (duration: 181.255808ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:08:54.337367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.976864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-16T13:08:54.337426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.26808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-16T13:08:54.337443Z","caller":"traceutil/trace.go:171","msg":"trace[1514675312] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8596; }","duration":"107.095267ms","start":"2026-08-16T13:08:54.230332Z","end":"2026-08-16T13:08:54.337428Z","steps":["trace[1514675312] 'range keys from in-memory index tree' (duration: 106.776348ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:08:54.33747Z","caller":"traceutil/trace.go:171","msg":"trace[2140365166] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8596; }","duration":"103.355003ms","start":"2026-08-16T13:08:54.234101Z","end":"2026-08-16T13:08:54.337456Z","steps":["trace[2140365166] 'range keys from in-memory index tree' (duration: 103.152897ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:08:54.337576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.947666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-16T13:08:54.337635Z","caller":"traceutil/trace.go:171","msg":"trace[777248467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8596; }","duration":"105.052249ms","start":"2026-08-16T13:08:54.232569Z","end":"2026-08-16T13:08:54.337621Z","steps":["trace[777248467] 'range keys from in-memory index tree' (duration: 104.72201ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:09:04.49229Z","caller":"traceutil/trace.go:171","msg":"trace[2000573270] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8695; }","duration":"156.016595ms","start":"2026-08-16T13:09:04.336244Z","end":"2026-08-16T13:09:04.492261Z","steps":["trace[2000573270] 'process raft request' (duration: 64.988966ms)","trace[2000573270] 'compare' (duration: 90.798823ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:09:04.492425Z","caller":"traceutil/trace.go:171","msg":"trace[1800214314] linearizableReadLoop","detail":"{readStateIndex:9002; appliedIndex:9001; }","duration":"148.039384ms","start":"2026-08-16T13:09:04.344358Z","end":"2026-08-16T13:09:04.492397Z","steps":["trace[1800214314] 'read index received' (duration: 56.816629ms)","trace[1800214314] 'applied index is now lower than readState.Index' (duration: 91.221005ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:09:04.492765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.391094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-bh8sq\" ","response":"range_response_count:1 size:4650"} {"level":"info","ts":"2026-08-16T13:09:04.492811Z","caller":"traceutil/trace.go:171","msg":"trace[1911848829] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-bh8sq; range_end:; response_count:1; response_revision:8696; }","duration":"148.466746ms","start":"2026-08-16T13:09:04.344333Z","end":"2026-08-16T13:09:04.492799Z","steps":["trace[1911848829] 'agreement among raft nodes before linearized reading' (duration: 148.216369ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:09:05.068911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.236024ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-08-16T13:09:05.341708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.720941ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T13:09:05.341955Z","caller":"traceutil/trace.go:171","msg":"trace[1020080079] transaction","detail":"{read_only:false; response_revision:8703; number_of_response:1; }","duration":"350.200442ms","start":"2026-08-16T13:09:04.991723Z","end":"2026-08-16T13:09:05.341924Z","steps":["trace[1020080079] 'process raft request' (duration: 150.181064ms)","trace[1020080079] 'compare' (duration: 199.466663ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:09:05.342106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:09:04.991679Z","time spent":"350.348026ms","remote":"127.0.0.1:43196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:09:05.342118Z","caller":"traceutil/trace.go:171","msg":"trace[1022887169] transaction","detail":"{read_only:false; response_revision:8705; number_of_response:1; }","duration":"235.428863ms","start":"2026-08-16T13:09:05.10667Z","end":"2026-08-16T13:09:05.342099Z","steps":["trace[1022887169] 'process raft request' (duration: 235.272149ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:09:05.342324Z","caller":"traceutil/trace.go:171","msg":"trace[1809076280] transaction","detail":"{read_only:false; response_revision:8704; number_of_response:1; }","duration":"240.435992ms","start":"2026-08-16T13:09:05.101853Z","end":"2026-08-16T13:09:05.342289Z","steps":["trace[1809076280] 'process raft request' (duration: 240.01958ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:09:05.342531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.774742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-bh8sq\" ","response":"range_response_count:1 size:4650"} {"level":"info","ts":"2026-08-16T13:09:05.342595Z","caller":"traceutil/trace.go:171","msg":"trace[1735493231] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-bh8sq; range_end:; response_count:1; response_revision:8705; }","duration":"240.885005ms","start":"2026-08-16T13:09:05.101695Z","end":"2026-08-16T13:09:05.34258Z","steps":["trace[1735493231] 'agreement among raft nodes before linearized reading' (duration: 240.666539ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:09:05.342822Z","caller":"traceutil/trace.go:171","msg":"trace[775191335] linearizableReadLoop","detail":"{readStateIndex:9011; appliedIndex:9010; }","duration":"240.323899ms","start":"2026-08-16T13:09:05.101732Z","end":"2026-08-16T13:09:05.342056Z","steps":["trace[775191335] 'read index received' (duration: 40.194317ms)","trace[775191335] 'applied index is now lower than readState.Index' (duration: 200.127072ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:09:05.343038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.214025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-16T13:09:05.343093Z","caller":"traceutil/trace.go:171","msg":"trace[762077311] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:8705; }","duration":"236.305758ms","start":"2026-08-16T13:09:05.106773Z","end":"2026-08-16T13:09:05.343079Z","steps":["trace[762077311] 'agreement among raft nodes before linearized reading' (duration: 236.181014ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:09:05.343179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.357315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T13:09:05.343301Z","caller":"traceutil/trace.go:171","msg":"trace[1434296980] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:8705; }","duration":"129.55194ms","start":"2026-08-16T13:09:05.213717Z","end":"2026-08-16T13:09:05.343269Z","steps":["trace[1434296980] 'agreement among raft nodes before linearized reading' (duration: 129.304573ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:09:21.35502Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002400.wal"} {"level":"info","ts":"2026-08-16T13:10:51.710014Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"380f6760faea5f9e","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-16T13:10:51.717503Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-16T13:10:51.717675Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-16T13:13:06.755086Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8180} {"level":"info","ts":"2026-08-16T13:13:06.822927Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8180,"took":"64.760292ms","hash":1264558424,"current-db-size-bytes":58212352,"current-db-size":"58 MB","current-db-size-in-use-bytes":29675520,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-16T13:13:06.822987Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1264558424,"revision":8180,"compact-revision":6067} {"level":"info","ts":"2026-08-16T13:18:06.760656Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10782} {"level":"info","ts":"2026-08-16T13:18:06.846144Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10782,"took":"82.95939ms","hash":3480160840,"current-db-size-bytes":58212352,"current-db-size":"58 MB","current-db-size-in-use-bytes":27500544,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-16T13:18:06.846244Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3480160840,"revision":10782,"compact-revision":8180} {"level":"info","ts":"2026-08-16T13:20:12.115721Z","caller":"traceutil/trace.go:171","msg":"trace[724356289] transaction","detail":"{read_only:false; response_revision:13782; number_of_response:1; }","duration":"101.046524ms","start":"2026-08-16T13:20:12.014647Z","end":"2026-08-16T13:20:12.115693Z","steps":["trace[724356289] 'process raft request' (duration: 98.73594ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:20:12.115776Z","caller":"traceutil/trace.go:171","msg":"trace[245559695] transaction","detail":"{read_only:false; response_revision:13783; number_of_response:1; }","duration":"100.981112ms","start":"2026-08-16T13:20:12.014761Z","end":"2026-08-16T13:20:12.115742Z","steps":["trace[245559695] 'process raft request' (duration: 100.876059ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:20:28.499344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.761563ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T13:20:28.499581Z","caller":"traceutil/trace.go:171","msg":"trace[1878393795] transaction","detail":"{read_only:false; response_revision:13881; number_of_response:1; }","duration":"295.447972ms","start":"2026-08-16T13:20:28.204108Z","end":"2026-08-16T13:20:28.499556Z","steps":["trace[1878393795] 'process raft request' (duration: 295.35041ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:20:28.499846Z","caller":"traceutil/trace.go:171","msg":"trace[540921423] transaction","detail":"{read_only:false; response_revision:13880; number_of_response:1; }","duration":"295.73534ms","start":"2026-08-16T13:20:28.204064Z","end":"2026-08-16T13:20:28.499799Z","steps":["trace[540921423] 'process raft request' (duration: 14.428412ms)","trace[540921423] 'compare' (duration: 280.64184ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:20:50.667178Z","caller":"traceutil/trace.go:171","msg":"trace[160970511] linearizableReadLoop","detail":"{readStateIndex:14676; appliedIndex:14675; }","duration":"305.264706ms","start":"2026-08-16T13:20:50.361877Z","end":"2026-08-16T13:20:50.667141Z","steps":["trace[160970511] 'read index received' (duration: 296.652046ms)","trace[160970511] 'applied index is now lower than readState.Index' (duration: 8.61048ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:20:50.667501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.575814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-08-16T13:20:50.667616Z","caller":"traceutil/trace.go:171","msg":"trace[1080858197] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:14189; }","duration":"305.761049ms","start":"2026-08-16T13:20:50.361829Z","end":"2026-08-16T13:20:50.66759Z","steps":["trace[1080858197] 'agreement among raft nodes before linearized reading' (duration: 305.41604ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:20:50.667682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:20:50.361809Z","time spent":"305.857903ms","remote":"127.0.0.1:43040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":379,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"warn","ts":"2026-08-16T13:20:50.667719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.056863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-16T13:20:50.667746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.922496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T13:20:50.667823Z","caller":"traceutil/trace.go:171","msg":"trace[1973097422] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:14189; }","duration":"294.206227ms","start":"2026-08-16T13:20:50.373597Z","end":"2026-08-16T13:20:50.667803Z","steps":["trace[1973097422] 'agreement among raft nodes before linearized reading' (duration: 294.024482ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:20:50.667842Z","caller":"traceutil/trace.go:171","msg":"trace[894577700] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:14189; }","duration":"104.0754ms","start":"2026-08-16T13:20:50.563741Z","end":"2026-08-16T13:20:50.667816Z","steps":["trace[894577700] 'agreement among raft nodes before linearized reading' (duration: 103.890775ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:20:50.667908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.458191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1d19263a-1ed5-44c5-807e-acf592321666\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-08-16T13:20:50.668029Z","caller":"traceutil/trace.go:171","msg":"trace[1172130988] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1d19263a-1ed5-44c5-807e-acf592321666; range_end:; response_count:1; response_revision:14189; }","duration":"258.646817ms","start":"2026-08-16T13:20:50.409355Z","end":"2026-08-16T13:20:50.668002Z","steps":["trace[1172130988] 'agreement among raft nodes before linearized reading' (duration: 258.291267ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:20:50.667219Z","caller":"traceutil/trace.go:171","msg":"trace[1843938622] transaction","detail":"{read_only:false; response_revision:14189; number_of_response:1; }","duration":"307.261721ms","start":"2026-08-16T13:20:50.359883Z","end":"2026-08-16T13:20:50.667145Z","steps":["trace[1843938622] 'process raft request' (duration: 298.731433ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:20:50.668347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:20:50.359858Z","time spent":"308.399343ms","remote":"127.0.0.1:43196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:22:16.469368Z","caller":"traceutil/trace.go:171","msg":"trace[457854961] linearizableReadLoop","detail":"{readStateIndex:15505; appliedIndex:15504; }","duration":"157.014856ms","start":"2026-08-16T13:22:16.312323Z","end":"2026-08-16T13:22:16.469337Z","steps":["trace[457854961] 'read index received' (duration: 124.587945ms)","trace[457854961] 'applied index is now lower than readState.Index' (duration: 32.425071ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:22:16.469499Z","caller":"traceutil/trace.go:171","msg":"trace[1922278489] transaction","detail":"{read_only:false; response_revision:14996; number_of_response:1; }","duration":"201.330558ms","start":"2026-08-16T13:22:16.26813Z","end":"2026-08-16T13:22:16.46946Z","steps":["trace[1922278489] 'process raft request' (duration: 168.791683ms)","trace[1922278489] 'compare' (duration: 32.194745ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:22:16.469702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.347545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-5rtcx\" ","response":"range_response_count:1 size:22131"} {"level":"info","ts":"2026-08-16T13:22:16.469769Z","caller":"traceutil/trace.go:171","msg":"trace[1430606330] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-5rtcx; range_end:; response_count:1; response_revision:14996; }","duration":"157.419507ms","start":"2026-08-16T13:22:16.312314Z","end":"2026-08-16T13:22:16.469734Z","steps":["trace[1430606330] 'agreement among raft nodes before linearized reading' (duration: 157.214052ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:22:16.469773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.713524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-5rtcx\" ","response":"range_response_count:1 size:22131"} {"level":"info","ts":"2026-08-16T13:22:16.46984Z","caller":"traceutil/trace.go:171","msg":"trace[135969993] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-5rtcx; range_end:; response_count:1; response_revision:14996; }","duration":"144.810117ms","start":"2026-08-16T13:22:16.325009Z","end":"2026-08-16T13:22:16.469819Z","steps":["trace[135969993] 'agreement among raft nodes before linearized reading' (duration: 144.616531ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:22:16.47016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.695166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-16T13:22:16.470267Z","caller":"traceutil/trace.go:171","msg":"trace[546442574] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:14996; }","duration":"120.86399ms","start":"2026-08-16T13:22:16.349377Z","end":"2026-08-16T13:22:16.470241Z","steps":["trace[546442574] 'agreement among raft nodes before linearized reading' (duration: 120.611983ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:22:16.978414Z","caller":"traceutil/trace.go:171","msg":"trace[104277676] linearizableReadLoop","detail":"{readStateIndex:15508; appliedIndex:15507; }","duration":"122.035623ms","start":"2026-08-16T13:22:16.856335Z","end":"2026-08-16T13:22:16.97837Z","steps":["trace[104277676] 'read index received' (duration: 94.294942ms)","trace[104277676] 'applied index is now lower than readState.Index' (duration: 27.738381ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:22:16.978506Z","caller":"traceutil/trace.go:171","msg":"trace[1403655152] transaction","detail":"{read_only:false; response_revision:14999; number_of_response:1; }","duration":"148.932021ms","start":"2026-08-16T13:22:16.82953Z","end":"2026-08-16T13:22:16.978462Z","steps":["trace[1403655152] 'process raft request' (duration: 121.116107ms)","trace[1403655152] 'compare' (duration: 27.512536ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:22:16.978627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.26854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T13:22:16.978703Z","caller":"traceutil/trace.go:171","msg":"trace[1476036277] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14999; }","duration":"122.419414ms","start":"2026-08-16T13:22:16.856265Z","end":"2026-08-16T13:22:16.978684Z","steps":["trace[1476036277] 'agreement among raft nodes before linearized reading' (duration: 122.26794ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:23:06.767407Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12853} {"level":"info","ts":"2026-08-16T13:23:06.846595Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12853,"took":"76.135487ms","hash":68734296,"current-db-size-bytes":58212352,"current-db-size":"58 MB","current-db-size-in-use-bytes":30281728,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-16T13:23:06.846789Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":68734296,"revision":12853,"compact-revision":10782} {"level":"info","ts":"2026-08-16T13:27:57.127317Z","caller":"traceutil/trace.go:171","msg":"trace[1220491407] transaction","detail":"{read_only:false; response_revision:17538; number_of_response:1; }","duration":"260.774111ms","start":"2026-08-16T13:27:56.866506Z","end":"2026-08-16T13:27:57.12728Z","steps":["trace[1220491407] 'process raft request' (duration: 256.120592ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:27:57.190388Z","caller":"traceutil/trace.go:171","msg":"trace[57862067] linearizableReadLoop","detail":"{readStateIndex:18135; appliedIndex:18131; }","duration":"160.173574ms","start":"2026-08-16T13:27:57.030184Z","end":"2026-08-16T13:27:57.190357Z","steps":["trace[57862067] 'read index received' (duration: 92.496082ms)","trace[57862067] 'applied index is now lower than readState.Index' (duration: 67.676582ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:27:57.190503Z","caller":"traceutil/trace.go:171","msg":"trace[625891813] transaction","detail":"{read_only:false; response_revision:17540; number_of_response:1; }","duration":"323.749762ms","start":"2026-08-16T13:27:56.866736Z","end":"2026-08-16T13:27:57.190485Z","steps":["trace[625891813] 'process raft request' (duration: 323.494835ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:27:57.190621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:27:56.866713Z","time spent":"323.827774ms","remote":"127.0.0.1:43196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T13:27:57.190649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.470511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-16T13:27:57.190692Z","caller":"traceutil/trace.go:171","msg":"trace[1009747156] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17541; }","duration":"160.570475ms","start":"2026-08-16T13:27:57.030105Z","end":"2026-08-16T13:27:57.190676Z","steps":["trace[1009747156] 'agreement among raft nodes before linearized reading' (duration: 160.372459ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:27:57.190894Z","caller":"traceutil/trace.go:171","msg":"trace[2106644408] transaction","detail":"{read_only:false; response_revision:17539; number_of_response:1; }","duration":"324.162754ms","start":"2026-08-16T13:27:56.866722Z","end":"2026-08-16T13:27:57.190885Z","steps":["trace[2106644408] 'process raft request' (duration: 323.336311ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:27:57.190942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:27:56.866691Z","time spent":"324.227625ms","remote":"127.0.0.1:43196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:27:57.190972Z","caller":"traceutil/trace.go:171","msg":"trace[950432] transaction","detail":"{read_only:false; response_revision:17541; number_of_response:1; }","duration":"313.053494ms","start":"2026-08-16T13:27:56.8779Z","end":"2026-08-16T13:27:57.190953Z","steps":["trace[950432] 'process raft request' (duration: 312.390246ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:27:57.191069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:27:56.877875Z","time spent":"313.146787ms","remote":"127.0.0.1:43112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20877,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:27:57.516835Z","caller":"traceutil/trace.go:171","msg":"trace[702038554] transaction","detail":"{read_only:false; response_revision:17542; number_of_response:1; }","duration":"318.362271ms","start":"2026-08-16T13:27:57.198434Z","end":"2026-08-16T13:27:57.516797Z","steps":["trace[702038554] 'process raft request' (duration: 228.865133ms)","trace[702038554] 'compare' (duration: 89.18579ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:27:57.516999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:27:57.198415Z","time spent":"318.503546ms","remote":"127.0.0.1:43196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:27:57.517022Z","caller":"traceutil/trace.go:171","msg":"trace[396114487] linearizableReadLoop","detail":"{readStateIndex:18136; appliedIndex:18135; }","duration":"263.922678ms","start":"2026-08-16T13:27:57.253068Z","end":"2026-08-16T13:27:57.51699Z","steps":["trace[396114487] 'read index received' (duration: 174.339197ms)","trace[396114487] 'applied index is now lower than readState.Index' (duration: 89.581371ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:27:57.517338Z","caller":"traceutil/trace.go:171","msg":"trace[1062502479] transaction","detail":"{read_only:false; response_revision:17543; number_of_response:1; }","duration":"203.641872ms","start":"2026-08-16T13:27:57.313686Z","end":"2026-08-16T13:27:57.517328Z","steps":["trace[1062502479] 'process raft request' (duration: 203.064296ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:27:57.517404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.341909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-zbr6p\" ","response":"range_response_count:1 size:9800"} {"level":"info","ts":"2026-08-16T13:27:57.517494Z","caller":"traceutil/trace.go:171","msg":"trace[1377812411] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-zbr6p; range_end:; response_count:1; response_revision:17542; }","duration":"264.510164ms","start":"2026-08-16T13:27:57.252969Z","end":"2026-08-16T13:27:57.517479Z","steps":["trace[1377812411] 'agreement among raft nodes before linearized reading' (duration: 264.134304ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:28:06.387286Z","caller":"traceutil/trace.go:171","msg":"trace[1312357184] transaction","detail":"{read_only:false; response_revision:17675; number_of_response:1; }","duration":"133.385849ms","start":"2026-08-16T13:28:06.253872Z","end":"2026-08-16T13:28:06.387258Z","steps":["trace[1312357184] 'process raft request' (duration: 132.768062ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:28:06.782462Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15320} {"level":"info","ts":"2026-08-16T13:28:06.876837Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15320,"took":"91.427613ms","hash":3286348535,"current-db-size-bytes":58212352,"current-db-size":"58 MB","current-db-size-in-use-bytes":32382976,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-16T13:28:06.876896Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3286348535,"revision":15320,"compact-revision":12853} {"level":"info","ts":"2026-08-16T13:31:33.680186Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"380f6760faea5f9e","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-16T13:31:33.686309Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-16T13:31:33.686416Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-16T13:33:06.789483Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17718} {"level":"info","ts":"2026-08-16T13:33:06.890309Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17718,"took":"94.692565ms","hash":1517630007,"current-db-size-bytes":58212352,"current-db-size":"58 MB","current-db-size-in-use-bytes":33927168,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-16T13:33:06.890413Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1517630007,"revision":17718,"compact-revision":15320} {"level":"warn","ts":"2026-08-16T13:33:55.150573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.443812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-16T13:33:55.150679Z","caller":"traceutil/trace.go:171","msg":"trace[2051763902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20283; }","duration":"119.612556ms","start":"2026-08-16T13:33:55.031045Z","end":"2026-08-16T13:33:55.150658Z","steps":["trace[2051763902] 'range keys from in-memory index tree' (duration: 119.142023ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:33:55.150583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.67757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-08-16T13:33:55.150831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.802561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-16T13:33:55.150893Z","caller":"traceutil/trace.go:171","msg":"trace[1523881593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20283; }","duration":"119.916885ms","start":"2026-08-16T13:33:55.030959Z","end":"2026-08-16T13:33:55.150876Z","steps":["trace[1523881593] 'range keys from in-memory index tree' (duration: 119.645477ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:33:55.150902Z","caller":"traceutil/trace.go:171","msg":"trace[1969335740] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20283; }","duration":"118.982719ms","start":"2026-08-16T13:33:55.031885Z","end":"2026-08-16T13:33:55.150868Z","steps":["trace[1969335740] 'range keys from in-memory index tree' (duration: 118.518676ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:33:55.415531Z","caller":"traceutil/trace.go:171","msg":"trace[2098361130] transaction","detail":"{read_only:false; response_revision:20286; number_of_response:1; }","duration":"258.263489ms","start":"2026-08-16T13:33:55.157225Z","end":"2026-08-16T13:33:55.415489Z","steps":["trace[2098361130] 'process raft request' (duration: 257.917009ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:33:55.415872Z","caller":"traceutil/trace.go:171","msg":"trace[158093055] transaction","detail":"{read_only:false; response_revision:20287; number_of_response:1; }","duration":"258.231438ms","start":"2026-08-16T13:33:55.157563Z","end":"2026-08-16T13:33:55.415795Z","steps":["trace[158093055] 'process raft request' (duration: 257.872608ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:33:55.629473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.690865ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-16T13:33:55.629581Z","caller":"traceutil/trace.go:171","msg":"trace[1600847821] linearizableReadLoop","detail":"{readStateIndex:20973; appliedIndex:20972; }","duration":"176.318377ms","start":"2026-08-16T13:33:55.453245Z","end":"2026-08-16T13:33:55.629564Z","steps":["trace[1600847821] 'read index received' (duration: 37.424087ms)","trace[1600847821] 'applied index is now lower than readState.Index' (duration: 138.89296ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:33:55.629793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.522823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-16T13:33:55.629898Z","caller":"traceutil/trace.go:171","msg":"trace[1877853429] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:20287; }","duration":"176.701749ms","start":"2026-08-16T13:33:55.45317Z","end":"2026-08-16T13:33:55.629872Z","steps":["trace[1877853429] 'agreement among raft nodes before linearized reading' (duration: 176.489183ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:33:55.630026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.244533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-16T13:33:55.630141Z","caller":"traceutil/trace.go:171","msg":"trace[1107797909] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20287; }","duration":"116.419567ms","start":"2026-08-16T13:33:55.513689Z","end":"2026-08-16T13:33:55.630109Z","steps":["trace[1107797909] 'agreement among raft nodes before linearized reading' (duration: 116.019906ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:37:38.732596Z","caller":"traceutil/trace.go:171","msg":"trace[2000821268] transaction","detail":"{read_only:false; response_revision:22210; number_of_response:1; }","duration":"139.008834ms","start":"2026-08-16T13:37:38.593557Z","end":"2026-08-16T13:37:38.732566Z","steps":["trace[2000821268] 'process raft request' (duration: 138.603463ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:37:38.742128Z","caller":"traceutil/trace.go:171","msg":"trace[486938549] transaction","detail":"{read_only:false; response_revision:22211; number_of_response:1; }","duration":"129.319755ms","start":"2026-08-16T13:37:38.612778Z","end":"2026-08-16T13:37:38.742097Z","steps":["trace[486938549] 'process raft request' (duration: 128.42337ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:37:48.664335Z","caller":"traceutil/trace.go:171","msg":"trace[303936751] transaction","detail":"{read_only:false; response_revision:22716; number_of_response:1; }","duration":"102.369698ms","start":"2026-08-16T13:37:48.56194Z","end":"2026-08-16T13:37:48.66431Z","steps":["trace[303936751] 'process raft request' (duration: 102.214633ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:38:06.795882Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20036} {"level":"info","ts":"2026-08-16T13:38:06.905933Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20036,"took":"105.826663ms","hash":3976999836,"current-db-size-bytes":58212352,"current-db-size":"58 MB","current-db-size-in-use-bytes":41877504,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-08-16T13:38:06.906009Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3976999836,"revision":20036,"compact-revision":17718} {"level":"info","ts":"2026-08-16T13:43:06.802566Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22884} {"level":"info","ts":"2026-08-16T13:43:06.932261Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22884,"took":"125.398629ms","hash":3804503895,"current-db-size-bytes":58212352,"current-db-size":"58 MB","current-db-size-in-use-bytes":31936512,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-16T13:43:06.93236Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3804503895,"revision":22884,"compact-revision":20036}