{"level":"warn","ts":"2026-08-12T11:02:31.275466Z","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-12T11:02:31.275713Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.228:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.228:2380","--initial-cluster=instance=https://199.19.213.228:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.228:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.228: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-12T11:02:31.275845Z","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-12T11:02:31.275906Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.228:2380"]} {"level":"info","ts":"2026-08-12T11:02:31.275969Z","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-12T11:02:31.279284Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.228:2379"]} {"level":"info","ts":"2026-08-12T11:02:31.281739Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.228:2380"],"listen-peer-urls":["https://199.19.213.228:2380"],"advertise-client-urls":["https://199.19.213.228:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.228:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.228: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-12T11:02:31.289457Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.475606ms"} {"level":"info","ts":"2026-08-12T11:02:31.300881Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"db79120667ce701f","cluster-id":"793c581eb68f3646"} {"level":"info","ts":"2026-08-12T11:02:31.30097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f switched to configuration voters=()"} {"level":"info","ts":"2026-08-12T11:02:31.300997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became follower at term 0"} {"level":"info","ts":"2026-08-12T11:02:31.301022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft db79120667ce701f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-12T11:02:31.301047Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became follower at term 1"} {"level":"info","ts":"2026-08-12T11:02:31.3011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f switched to configuration voters=(15814691385208893471)"} {"level":"warn","ts":"2026-08-12T11:02:31.31275Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-12T11:02:31.314043Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-12T11:02:31.315013Z","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-12T11:02:31.318621Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"db79120667ce701f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-12T11:02:31.318896Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"db79120667ce701f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-12T11:02:31.319041Z","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-12T11:02:31.31915Z","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-12T11:02:31.319166Z","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-12T11:02:31.327867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f switched to configuration voters=(15814691385208893471)"} {"level":"info","ts":"2026-08-12T11:02:31.328095Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"793c581eb68f3646","local-member-id":"db79120667ce701f","added-peer-id":"db79120667ce701f","added-peer-peer-urls":["https://199.19.213.228:2380"]} {"level":"info","ts":"2026-08-12T11:02:31.328582Z","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-12T11:02:31.32881Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"db79120667ce701f","initial-advertise-peer-urls":["https://199.19.213.228:2380"],"listen-peer-urls":["https://199.19.213.228:2380"],"advertise-client-urls":["https://199.19.213.228:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.228:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-12T11:02:31.328836Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-12T11:02:31.329014Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.228:2380"} {"level":"info","ts":"2026-08-12T11:02:31.329029Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.228:2380"} {"level":"info","ts":"2026-08-12T11:02:31.701485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f is starting a new election at term 1"} {"level":"info","ts":"2026-08-12T11:02:31.701538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became pre-candidate at term 1"} {"level":"info","ts":"2026-08-12T11:02:31.701562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f received MsgPreVoteResp from db79120667ce701f at term 1"} {"level":"info","ts":"2026-08-12T11:02:31.701576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became candidate at term 2"} {"level":"info","ts":"2026-08-12T11:02:31.701583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f received MsgVoteResp from db79120667ce701f at term 2"} {"level":"info","ts":"2026-08-12T11:02:31.701593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db79120667ce701f became leader at term 2"} {"level":"info","ts":"2026-08-12T11:02:31.701601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: db79120667ce701f elected leader db79120667ce701f at term 2"} {"level":"info","ts":"2026-08-12T11:02:31.702583Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T11:02:31.703153Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"db79120667ce701f","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.228:2379]}","request-path":"/0/members/db79120667ce701f/attributes","cluster-id":"793c581eb68f3646","publish-timeout":"7s"} {"level":"info","ts":"2026-08-12T11:02:31.703242Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-12T11:02:31.703334Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-12T11:02:31.703756Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-12T11:02:31.703796Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-12T11:02:31.703877Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"793c581eb68f3646","local-member-id":"db79120667ce701f","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T11:02:31.704075Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T11:02:31.704148Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T11:02:31.705524Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.228:2379"} {"level":"info","ts":"2026-08-12T11:02:31.7065Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-12T11:02:44.748844Z","caller":"traceutil/trace.go:171","msg":"trace[193268520] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"262.475962ms","start":"2026-08-12T11:02:44.486338Z","end":"2026-08-12T11:02:44.748814Z","steps":["trace[193268520] 'process raft request' (duration: 262.271395ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:02:50.399837Z","caller":"traceutil/trace.go:171","msg":"trace[823299249] linearizableReadLoop","detail":"{readStateIndex:389; appliedIndex:388; }","duration":"124.860013ms","start":"2026-08-12T11:02:50.274957Z","end":"2026-08-12T11:02:50.399817Z","steps":["trace[823299249] 'read index received' (duration: 124.681946ms)","trace[823299249] 'applied index is now lower than readState.Index' (duration: 177.537µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:02:50.399957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.980217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-12T11:02:50.400016Z","caller":"traceutil/trace.go:171","msg":"trace[1419829691] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:378; }","duration":"125.083211ms","start":"2026-08-12T11:02:50.274925Z","end":"2026-08-12T11:02:50.400008Z","steps":["trace[1419829691] 'agreement among raft nodes before linearized reading' (duration: 124.979417ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:02:50.400006Z","caller":"traceutil/trace.go:171","msg":"trace[558962551] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"193.225462ms","start":"2026-08-12T11:02:50.206739Z","end":"2026-08-12T11:02:50.399964Z","steps":["trace[558962551] 'process raft request' (duration: 192.973312ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:02:50.62651Z","caller":"traceutil/trace.go:171","msg":"trace[1035702805] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"223.099148ms","start":"2026-08-12T11:02:50.403378Z","end":"2026-08-12T11:02:50.626477Z","steps":["trace[1035702805] 'process raft request' (duration: 156.641194ms)","trace[1035702805] 'compare' (duration: 66.296368ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:03:14.418133Z","caller":"traceutil/trace.go:171","msg":"trace[469429808] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"207.328334ms","start":"2026-08-12T11:03:14.210785Z","end":"2026-08-12T11:03:14.418114Z","steps":["trace[469429808] 'process raft request' (duration: 207.078814ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:03:14.929715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.639633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T11:03:14.929765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.868991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.228\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-12T11:03:14.929831Z","caller":"traceutil/trace.go:171","msg":"trace[158079691] range","detail":"{range_begin:/registry/masterleases/199.19.213.228; range_end:; response_count:1; response_revision:592; }","duration":"193.958294ms","start":"2026-08-12T11:03:14.735855Z","end":"2026-08-12T11:03:14.929813Z","steps":["trace[158079691] 'range keys from in-memory index tree' (duration: 193.775697ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:03:14.929833Z","caller":"traceutil/trace.go:171","msg":"trace[459741149] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"131.798708ms","start":"2026-08-12T11:03:14.798007Z","end":"2026-08-12T11:03:14.929805Z","steps":["trace[459741149] 'range keys from in-memory index tree' (duration: 131.57209ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:03:14.930062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.244544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-12T11:03:14.930129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.30266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zgpqp\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-12T11:03:14.930137Z","caller":"traceutil/trace.go:171","msg":"trace[395886064] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:592; }","duration":"380.348198ms","start":"2026-08-12T11:03:14.549767Z","end":"2026-08-12T11:03:14.930116Z","steps":["trace[395886064] 'range keys from in-memory index tree' (duration: 380.058907ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:03:14.930178Z","caller":"traceutil/trace.go:171","msg":"trace[387875557] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zgpqp; range_end:; response_count:1; response_revision:592; }","duration":"370.375272ms","start":"2026-08-12T11:03:14.559789Z","end":"2026-08-12T11:03:14.930164Z","steps":["trace[387875557] 'range keys from in-memory index tree' (duration: 370.168785ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:03:14.930187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:03:14.549749Z","time spent":"380.426051ms","remote":"127.0.0.1:39778","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-12T11:03:14.930249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:03:14.559772Z","time spent":"370.467077ms","remote":"127.0.0.1:39704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-zgpqp\" "} {"level":"warn","ts":"2026-08-12T11:03:14.929731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.475434ms","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-12T11:03:14.930509Z","caller":"traceutil/trace.go:171","msg":"trace[859875480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:592; }","duration":"197.273356ms","start":"2026-08-12T11:03:14.733188Z","end":"2026-08-12T11:03:14.930461Z","steps":["trace[859875480] 'range keys from in-memory index tree' (duration: 196.317038ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:03:29.875817Z","caller":"traceutil/trace.go:171","msg":"trace[1060928816] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:782; }","duration":"187.979723ms","start":"2026-08-12T11:03:29.687814Z","end":"2026-08-12T11:03:29.875794Z","steps":["trace[1060928816] 'read index received' (duration: 187.791994ms)","trace[1060928816] 'applied index is now lower than readState.Index' (duration: 186.769µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:03:29.876011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.199011ms","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-12T11:03:29.87605Z","caller":"traceutil/trace.go:171","msg":"trace[1268406169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:756; }","duration":"188.289706ms","start":"2026-08-12T11:03:29.68775Z","end":"2026-08-12T11:03:29.876039Z","steps":["trace[1268406169] 'agreement among raft nodes before linearized reading' (duration: 188.14292ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:03:29.876086Z","caller":"traceutil/trace.go:171","msg":"trace[2089828273] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"224.469512ms","start":"2026-08-12T11:03:29.651596Z","end":"2026-08-12T11:03:29.876065Z","steps":["trace[2089828273] 'process raft request' (duration: 224.062925ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:04:08.449392Z","caller":"traceutil/trace.go:171","msg":"trace[535330487] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"108.182306ms","start":"2026-08-12T11:04:08.34118Z","end":"2026-08-12T11:04:08.449362Z","steps":["trace[535330487] 'process raft request' (duration: 107.889944ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:04:52.840256Z","caller":"traceutil/trace.go:171","msg":"trace[909620810] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"149.29934ms","start":"2026-08-12T11:04:52.690922Z","end":"2026-08-12T11:04:52.840222Z","steps":["trace[909620810] 'process raft request' (duration: 98.897606ms)","trace[909620810] 'compare' (duration: 50.231841ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:04:53.182042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.421215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-08-12T11:04:53.182136Z","caller":"traceutil/trace.go:171","msg":"trace[2019122940] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1836; }","duration":"144.555757ms","start":"2026-08-12T11:04:53.037558Z","end":"2026-08-12T11:04:53.182114Z","steps":["trace[2019122940] 'range keys from in-memory index tree' (duration: 144.252492ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:53.182428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.962311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9cacc8be-a184-48a3-a50f-198567972804\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-12T11:04:53.182618Z","caller":"traceutil/trace.go:171","msg":"trace[1954511957] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9cacc8be-a184-48a3-a50f-198567972804; range_end:; response_count:1; response_revision:1836; }","duration":"189.223655ms","start":"2026-08-12T11:04:52.993293Z","end":"2026-08-12T11:04:53.182517Z","steps":["trace[1954511957] 'range keys from in-memory index tree' (duration: 188.644206ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:04:53.218098Z","caller":"traceutil/trace.go:171","msg":"trace[1885879195] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"179.695403ms","start":"2026-08-12T11:04:53.038382Z","end":"2026-08-12T11:04:53.218077Z","steps":["trace[1885879195] 'process raft request' (duration: 179.49537ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:04:53.369228Z","caller":"traceutil/trace.go:171","msg":"trace[2096926137] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"176.757016ms","start":"2026-08-12T11:04:53.192447Z","end":"2026-08-12T11:04:53.369204Z","steps":["trace[2096926137] 'process raft request' (duration: 176.593684ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:05:35.312765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.81968ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T11:05:35.31286Z","caller":"traceutil/trace.go:171","msg":"trace[1029617704] linearizableReadLoop","detail":"{readStateIndex:2243; appliedIndex:2242; }","duration":"602.94223ms","start":"2026-08-12T11:05:34.709903Z","end":"2026-08-12T11:05:35.312845Z","steps":["trace[1029617704] 'read index received' (duration: 69.980183ms)","trace[1029617704] 'applied index is now lower than readState.Index' (duration: 532.960467ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:05:35.313018Z","caller":"traceutil/trace.go:171","msg":"trace[2043418815] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"613.734395ms","start":"2026-08-12T11:05:34.699271Z","end":"2026-08-12T11:05:35.313006Z","steps":["trace[2043418815] 'process raft request' (duration: 80.704888ms)","trace[2043418815] 'compare' (duration: 531.526944ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:05:35.313082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:05:34.699251Z","time spent":"613.800766ms","remote":"127.0.0.1:39778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:05:35.313599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.706264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.228\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-12T11:05:35.313701Z","caller":"traceutil/trace.go:171","msg":"trace[665534240] range","detail":"{range_begin:/registry/masterleases/199.19.213.228; range_end:; response_count:1; response_revision:2171; }","duration":"568.825257ms","start":"2026-08-12T11:05:34.744845Z","end":"2026-08-12T11:05:35.31367Z","steps":["trace[665534240] 'agreement among raft nodes before linearized reading' (duration: 568.594652ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:05:35.313768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:05:34.744831Z","time spent":"568.928089ms","remote":"127.0.0.1:39544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.228\" "} {"level":"warn","ts":"2026-08-12T11:05:35.314016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.116506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:05:35.314068Z","caller":"traceutil/trace.go:171","msg":"trace[1757379788] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:2171; }","duration":"604.208608ms","start":"2026-08-12T11:05:34.709851Z","end":"2026-08-12T11:05:35.31406Z","steps":["trace[1757379788] 'agreement among raft nodes before linearized reading' (duration: 604.100585ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:05:35.314093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:05:34.709831Z","time spent":"604.254079ms","remote":"127.0.0.1:39818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-08-12T11:05:35.314277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.875014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-08-12T11:05:35.314324Z","caller":"traceutil/trace.go:171","msg":"trace[650310119] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2171; }","duration":"532.940905ms","start":"2026-08-12T11:05:34.781363Z","end":"2026-08-12T11:05:35.314304Z","steps":["trace[650310119] 'agreement among raft nodes before linearized reading' (duration: 532.820342ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:05:35.314354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:05:34.781346Z","time spent":"533.000737ms","remote":"127.0.0.1:39630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":667,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-12T11:05:35.314573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.885919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-12T11:05:35.314617Z","caller":"traceutil/trace.go:171","msg":"trace[1033263096] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2171; }","duration":"290.983143ms","start":"2026-08-12T11:05:35.023621Z","end":"2026-08-12T11:05:35.314604Z","steps":["trace[1033263096] 'agreement among raft nodes before linearized reading' (duration: 290.87913ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:05:35.314712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.16972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:05:35.31482Z","caller":"traceutil/trace.go:171","msg":"trace[1206431501] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2171; }","duration":"516.323744ms","start":"2026-08-12T11:05:34.798472Z","end":"2026-08-12T11:05:35.314796Z","steps":["trace[1206431501] 'agreement among raft nodes before linearized reading' (duration: 516.13548ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:05:35.314882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:05:34.798456Z","time spent":"516.415115ms","remote":"127.0.0.1:39528","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-12T11:05:35.614252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.716099ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T11:05:35.614342Z","caller":"traceutil/trace.go:171","msg":"trace[1064351898] linearizableReadLoop","detail":"{readStateIndex:2245; appliedIndex:2244; }","duration":"274.064701ms","start":"2026-08-12T11:05:35.340266Z","end":"2026-08-12T11:05:35.61433Z","steps":["trace[1064351898] 'read index received' (duration: 145.327102ms)","trace[1064351898] 'applied index is now lower than readState.Index' (duration: 128.736379ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:05:35.614379Z","caller":"traceutil/trace.go:171","msg":"trace[1629533988] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"294.386047ms","start":"2026-08-12T11:05:35.319966Z","end":"2026-08-12T11:05:35.614352Z","steps":["trace[1629533988] 'process raft request' (duration: 165.509794ms)","trace[1629533988] 'compare' (duration: 128.597957ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:05:35.614478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.379498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-08-12T11:05:35.614505Z","caller":"traceutil/trace.go:171","msg":"trace[1388286238] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2172; }","duration":"295.43145ms","start":"2026-08-12T11:05:35.319065Z","end":"2026-08-12T11:05:35.614497Z","steps":["trace[1388286238] 'agreement among raft nodes before linearized reading' (duration: 295.315787ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:05:35.620239Z","caller":"traceutil/trace.go:171","msg":"trace[16711191] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"278.634221ms","start":"2026-08-12T11:05:35.341583Z","end":"2026-08-12T11:05:35.620217Z","steps":["trace[16711191] 'process raft request' (duration: 278.457737ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:05:35.620256Z","caller":"traceutil/trace.go:171","msg":"trace[1752492489] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"260.587517ms","start":"2026-08-12T11:05:35.359641Z","end":"2026-08-12T11:05:35.620228Z","steps":["trace[1752492489] 'process raft request' (duration: 260.493575ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:05:35.620475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.86595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-08-12T11:05:35.620544Z","caller":"traceutil/trace.go:171","msg":"trace[1885990793] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2174; }","duration":"153.982153ms","start":"2026-08-12T11:05:35.466539Z","end":"2026-08-12T11:05:35.620521Z","steps":["trace[1885990793] 'agreement among raft nodes before linearized reading' (duration: 153.698536ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:05:36.048949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.377841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T11:05:36.049084Z","caller":"traceutil/trace.go:171","msg":"trace[1244829541] linearizableReadLoop","detail":"{readStateIndex:2249; appliedIndex:2248; }","duration":"410.22448ms","start":"2026-08-12T11:05:35.63883Z","end":"2026-08-12T11:05:36.049054Z","steps":["trace[1244829541] 'read index received' (duration: 179.518021ms)","trace[1244829541] 'applied index is now lower than readState.Index' (duration: 230.704399ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:05:36.049405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.659658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-08-12T11:05:36.049426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.665773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:05:36.049474Z","caller":"traceutil/trace.go:171","msg":"trace[2101651193] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2176; }","duration":"249.753424ms","start":"2026-08-12T11:05:35.799705Z","end":"2026-08-12T11:05:36.049458Z","steps":["trace[2101651193] 'agreement among raft nodes before linearized reading' (duration: 249.684243ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:05:36.049468Z","caller":"traceutil/trace.go:171","msg":"trace[1130662276] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:2176; }","duration":"409.746639ms","start":"2026-08-12T11:05:35.639705Z","end":"2026-08-12T11:05:36.049451Z","steps":["trace[1130662276] 'agreement among raft nodes before linearized reading' (duration: 409.562235ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:05:36.049457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.454724ms","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-12T11:05:36.049521Z","caller":"traceutil/trace.go:171","msg":"trace[1399230662] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:2176; }","duration":"104.556125ms","start":"2026-08-12T11:05:35.94495Z","end":"2026-08-12T11:05:36.049507Z","steps":["trace[1399230662] 'agreement among raft nodes before linearized reading' (duration: 104.420082ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:05:36.049515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:05:35.639692Z","time spent":"409.81044ms","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-08-12T11:05:36.049684Z","caller":"traceutil/trace.go:171","msg":"trace[1936465501] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"410.640369ms","start":"2026-08-12T11:05:35.638793Z","end":"2026-08-12T11:05:36.049434Z","steps":["trace[1936465501] 'process raft request' (duration: 179.658625ms)","trace[1936465501] 'compare' (duration: 230.249547ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:05:36.049901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.066358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-08-12T11:05:36.049943Z","caller":"traceutil/trace.go:171","msg":"trace[1040287593] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2176; }","duration":"411.182241ms","start":"2026-08-12T11:05:35.638749Z","end":"2026-08-12T11:05:36.049932Z","steps":["trace[1040287593] 'agreement among raft nodes before linearized reading' (duration: 410.457515ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:05:36.049975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:05:35.638731Z","time spent":"411.232501ms","remote":"127.0.0.1:39778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":575,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-12T11:05:36.049921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:05:35.638771Z","time spent":"411.087798ms","remote":"127.0.0.1:39778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:05:36.050262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.323904ms","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-12T11:05:36.050374Z","caller":"traceutil/trace.go:171","msg":"trace[1231549536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2176; }","duration":"385.745754ms","start":"2026-08-12T11:05:35.664604Z","end":"2026-08-12T11:05:36.05035Z","steps":["trace[1231549536] 'agreement among raft nodes before linearized reading' (duration: 384.863425ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:05:36.050487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:05:35.664561Z","time spent":"385.912307ms","remote":"127.0.0.1:39778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-12T11:06:53.911793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.200258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:06:53.911926Z","caller":"traceutil/trace.go:171","msg":"trace[610945087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2812; }","duration":"113.391544ms","start":"2026-08-12T11:06:53.798509Z","end":"2026-08-12T11:06:53.911901Z","steps":["trace[610945087] 'range keys from in-memory index tree' (duration: 113.104216ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:06:59.425766Z","caller":"traceutil/trace.go:171","msg":"trace[331941116] transaction","detail":"{read_only:false; response_revision:2850; number_of_response:1; }","duration":"108.738821ms","start":"2026-08-12T11:06:59.316987Z","end":"2026-08-12T11:06:59.425726Z","steps":["trace[331941116] 'process raft request' (duration: 108.517295ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:08:40.640582Z","caller":"traceutil/trace.go:171","msg":"trace[1829070644] transaction","detail":"{read_only:false; response_revision:3694; number_of_response:1; }","duration":"117.000034ms","start":"2026-08-12T11:08:40.523562Z","end":"2026-08-12T11:08:40.640562Z","steps":["trace[1829070644] 'process raft request' (duration: 101.126526ms)","trace[1829070644] 'compare' (duration: 15.733023ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:09:09.692907Z","caller":"traceutil/trace.go:171","msg":"trace[405593590] transaction","detail":"{read_only:false; response_revision:4022; number_of_response:1; }","duration":"160.585517ms","start":"2026-08-12T11:09:09.53227Z","end":"2026-08-12T11:09:09.692855Z","steps":["trace[405593590] 'process raft request' (duration: 102.7223ms)","trace[405593590] 'compare' (duration: 57.64346ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:09:09.700677Z","caller":"traceutil/trace.go:171","msg":"trace[78933036] transaction","detail":"{read_only:false; response_revision:4028; number_of_response:1; }","duration":"125.934411ms","start":"2026-08-12T11:09:09.574721Z","end":"2026-08-12T11:09:09.700656Z","steps":["trace[78933036] 'process raft request' (duration: 125.890739ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:09:09.700811Z","caller":"traceutil/trace.go:171","msg":"trace[374912936] transaction","detail":"{read_only:false; response_revision:4025; number_of_response:1; }","duration":"132.760373ms","start":"2026-08-12T11:09:09.568036Z","end":"2026-08-12T11:09:09.700796Z","steps":["trace[374912936] 'process raft request' (duration: 132.457593ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:09:09.700951Z","caller":"traceutil/trace.go:171","msg":"trace[2143157381] transaction","detail":"{read_only:false; response_revision:4023; number_of_response:1; }","duration":"136.658175ms","start":"2026-08-12T11:09:09.564279Z","end":"2026-08-12T11:09:09.700937Z","steps":["trace[2143157381] 'process raft request' (duration: 136.053815ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:09:09.700971Z","caller":"traceutil/trace.go:171","msg":"trace[978747195] transaction","detail":"{read_only:false; response_revision:4026; number_of_response:1; }","duration":"128.111915ms","start":"2026-08-12T11:09:09.572849Z","end":"2026-08-12T11:09:09.700961Z","steps":["trace[978747195] 'process raft request' (duration: 127.693121ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:09:09.701119Z","caller":"traceutil/trace.go:171","msg":"trace[863573163] transaction","detail":"{read_only:false; response_revision:4027; number_of_response:1; }","duration":"127.515035ms","start":"2026-08-12T11:09:09.573593Z","end":"2026-08-12T11:09:09.701108Z","steps":["trace[863573163] 'process raft request' (duration: 126.991727ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:09:09.701365Z","caller":"traceutil/trace.go:171","msg":"trace[1031893506] transaction","detail":"{read_only:false; response_revision:4024; number_of_response:1; }","duration":"136.624114ms","start":"2026-08-12T11:09:09.564347Z","end":"2026-08-12T11:09:09.700972Z","steps":["trace[1031893506] 'process raft request' (duration: 136.113957ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:09:19.018079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.392818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T11:09:19.018231Z","caller":"traceutil/trace.go:171","msg":"trace[168363118] transaction","detail":"{read_only:false; response_revision:4439; number_of_response:1; }","duration":"242.791118ms","start":"2026-08-12T11:09:18.7754Z","end":"2026-08-12T11:09:19.018191Z","steps":["trace[168363118] 'process raft request' (duration: 95.174422ms)","trace[168363118] 'compare' (duration: 147.252814ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:09:19.151271Z","caller":"traceutil/trace.go:171","msg":"trace[1664720673] linearizableReadLoop","detail":"{readStateIndex:4631; appliedIndex:4628; }","duration":"366.81784ms","start":"2026-08-12T11:09:18.784433Z","end":"2026-08-12T11:09:19.151251Z","steps":["trace[1664720673] 'read index received' (duration: 86.155295ms)","trace[1664720673] 'applied index is now lower than readState.Index' (duration: 280.661515ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:09:19.15139Z","caller":"traceutil/trace.go:171","msg":"trace[971516517] transaction","detail":"{read_only:false; response_revision:4440; number_of_response:1; }","duration":"374.134939ms","start":"2026-08-12T11:09:18.777238Z","end":"2026-08-12T11:09:19.151373Z","steps":["trace[971516517] 'process raft request' (duration: 362.710218ms)","trace[971516517] 'compare' (duration: 11.132951ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:09:19.151563Z","caller":"traceutil/trace.go:171","msg":"trace[1196062907] transaction","detail":"{read_only:false; response_revision:4441; number_of_response:1; }","duration":"374.109878ms","start":"2026-08-12T11:09:18.777392Z","end":"2026-08-12T11:09:19.151502Z","steps":["trace[1196062907] 'process raft request' (duration: 373.800588ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:09:19.151673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:09:18.77737Z","time spent":"374.241133ms","remote":"127.0.0.1:39616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:09:19.151804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.364878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-08-12T11:09:19.151847Z","caller":"traceutil/trace.go:171","msg":"trace[243241163] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4441; }","duration":"367.44005ms","start":"2026-08-12T11:09:18.784394Z","end":"2026-08-12T11:09:19.151834Z","steps":["trace[243241163] 'agreement among raft nodes before linearized reading' (duration: 367.294796ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:09:19.151876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:09:18.784376Z","time spent":"367.491374ms","remote":"127.0.0.1:39714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":1308,"request content":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" "} {"level":"warn","ts":"2026-08-12T11:09:19.152554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:09:18.777231Z","time spent":"374.269223ms","remote":"127.0.0.1:39598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:09:19.185011Z","caller":"traceutil/trace.go:171","msg":"trace[1953656145] transaction","detail":"{read_only:false; response_revision:4442; number_of_response:1; }","duration":"162.560199ms","start":"2026-08-12T11:09:19.022428Z","end":"2026-08-12T11:09:19.184988Z","steps":["trace[1953656145] 'process raft request' (duration: 162.435325ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:09:19.185199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.494267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-08-12T11:09:19.185292Z","caller":"traceutil/trace.go:171","msg":"trace[829460453] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4442; }","duration":"253.631331ms","start":"2026-08-12T11:09:18.931641Z","end":"2026-08-12T11:09:19.185272Z","steps":["trace[829460453] 'agreement among raft nodes before linearized reading' (duration: 253.412374ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:09:19.185371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.150909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-25d0fe05-c5e9-4973-bf44-5a29033451c3\" ","response":"range_response_count:1 size:4065"} {"level":"info","ts":"2026-08-12T11:09:19.185419Z","caller":"traceutil/trace.go:171","msg":"trace[830771581] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-25d0fe05-c5e9-4973-bf44-5a29033451c3; range_end:; response_count:1; response_revision:4442; }","duration":"333.202611ms","start":"2026-08-12T11:09:18.852205Z","end":"2026-08-12T11:09:19.185407Z","steps":["trace[830771581] 'agreement among raft nodes before linearized reading' (duration: 333.078476ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:09:19.185383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.562302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-12T11:09:19.185448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:09:18.85215Z","time spent":"333.288634ms","remote":"127.0.0.1:39704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":4088,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-25d0fe05-c5e9-4973-bf44-5a29033451c3\" "} {"level":"info","ts":"2026-08-12T11:09:19.18551Z","caller":"traceutil/trace.go:171","msg":"trace[664989250] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4442; }","duration":"311.7928ms","start":"2026-08-12T11:09:18.873699Z","end":"2026-08-12T11:09:19.185491Z","steps":["trace[664989250] 'agreement among raft nodes before linearized reading' (duration: 311.424967ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:09:19.185592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:09:18.873674Z","time spent":"311.904804ms","remote":"127.0.0.1:39778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-12T11:09:19.186011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.69164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3465"} {"level":"info","ts":"2026-08-12T11:09:19.186111Z","caller":"traceutil/trace.go:171","msg":"trace[318890050] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4442; }","duration":"398.801984ms","start":"2026-08-12T11:09:18.787277Z","end":"2026-08-12T11:09:19.186079Z","steps":["trace[318890050] 'agreement among raft nodes before linearized reading' (duration: 397.722337ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:09:19.186196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:09:18.787269Z","time spent":"398.888817ms","remote":"127.0.0.1:39616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3488,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"warn","ts":"2026-08-12T11:09:23.426814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.981301ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-12T11:09:23.427038Z","caller":"traceutil/trace.go:171","msg":"trace[1460721584] transaction","detail":"{read_only:false; response_revision:4523; number_of_response:1; }","duration":"150.960775ms","start":"2026-08-12T11:09:23.276061Z","end":"2026-08-12T11:09:23.427022Z","steps":["trace[1460721584] 'process raft request' (duration: 14.666263ms)","trace[1460721584] 'compare' (duration: 135.856986ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:09:23.427313Z","caller":"traceutil/trace.go:171","msg":"trace[1865991153] transaction","detail":"{read_only:false; response_revision:4524; number_of_response:1; }","duration":"141.616454ms","start":"2026-08-12T11:09:23.285689Z","end":"2026-08-12T11:09:23.427306Z","steps":["trace[1865991153] 'process raft request' (duration: 141.231711ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:09:26.786521Z","caller":"traceutil/trace.go:171","msg":"trace[1287402315] transaction","detail":"{read_only:false; response_revision:4605; number_of_response:1; }","duration":"134.55937ms","start":"2026-08-12T11:09:26.651942Z","end":"2026-08-12T11:09:26.786501Z","steps":["trace[1287402315] 'process raft request' (duration: 134.399315ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:09:39.674831Z","caller":"traceutil/trace.go:171","msg":"trace[861094449] linearizableReadLoop","detail":"{readStateIndex:4985; appliedIndex:4984; }","duration":"271.584247ms","start":"2026-08-12T11:09:39.403217Z","end":"2026-08-12T11:09:39.674801Z","steps":["trace[861094449] 'read index received' (duration: 254.01256ms)","trace[861094449] 'applied index is now lower than readState.Index' (duration: 17.570257ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:09:39.675071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.848108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10516"} {"level":"info","ts":"2026-08-12T11:09:39.675111Z","caller":"traceutil/trace.go:171","msg":"trace[525645299] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4781; }","duration":"271.944902ms","start":"2026-08-12T11:09:39.403156Z","end":"2026-08-12T11:09:39.675101Z","steps":["trace[525645299] 'agreement among raft nodes before linearized reading' (duration: 271.786037ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:09:39.675421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.255107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-08-12T11:09:39.675485Z","caller":"traceutil/trace.go:171","msg":"trace[43764884] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4781; }","duration":"216.34729ms","start":"2026-08-12T11:09:39.459124Z","end":"2026-08-12T11:09:39.675472Z","steps":["trace[43764884] 'agreement among raft nodes before linearized reading' (duration: 216.223626ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:09:39.675541Z","caller":"traceutil/trace.go:171","msg":"trace[402434905] transaction","detail":"{read_only:false; response_revision:4781; number_of_response:1; }","duration":"294.051995ms","start":"2026-08-12T11:09:39.380886Z","end":"2026-08-12T11:09:39.674938Z","steps":["trace[402434905] 'process raft request' (duration: 276.404994ms)","trace[402434905] 'compare' (duration: 17.37382ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:09:39.675669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.998615ms","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-12T11:09:39.675743Z","caller":"traceutil/trace.go:171","msg":"trace[2034892830] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4781; }","duration":"117.15534ms","start":"2026-08-12T11:09:39.558567Z","end":"2026-08-12T11:09:39.675723Z","steps":["trace[2034892830] 'agreement among raft nodes before linearized reading' (duration: 116.868691ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:09:39.811511Z","caller":"traceutil/trace.go:171","msg":"trace[31890063] linearizableReadLoop","detail":"{readStateIndex:4987; appliedIndex:4985; }","duration":"129.131794ms","start":"2026-08-12T11:09:39.682361Z","end":"2026-08-12T11:09:39.811493Z","steps":["trace[31890063] 'read index received' (duration: 70.172275ms)","trace[31890063] 'applied index is now lower than readState.Index' (duration: 58.958639ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:09:39.811576Z","caller":"traceutil/trace.go:171","msg":"trace[675937426] transaction","detail":"{read_only:false; response_revision:4784; number_of_response:1; }","duration":"128.966499ms","start":"2026-08-12T11:09:39.682603Z","end":"2026-08-12T11:09:39.81157Z","steps":["trace[675937426] 'process raft request' (duration: 128.766342ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:09:39.811634Z","caller":"traceutil/trace.go:171","msg":"trace[1814588746] transaction","detail":"{read_only:false; response_revision:4785; number_of_response:1; }","duration":"124.046618ms","start":"2026-08-12T11:09:39.687575Z","end":"2026-08-12T11:09:39.811622Z","steps":["trace[1814588746] 'process raft request' (duration: 123.874162ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:09:39.811515Z","caller":"traceutil/trace.go:171","msg":"trace[2035906980] transaction","detail":"{read_only:false; response_revision:4783; number_of_response:1; }","duration":"130.778711ms","start":"2026-08-12T11:09:39.680708Z","end":"2026-08-12T11:09:39.811486Z","steps":["trace[2035906980] 'process raft request' (duration: 130.532912ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:09:39.811971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.592161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-08-12T11:09:39.812008Z","caller":"traceutil/trace.go:171","msg":"trace[591208447] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4785; }","duration":"129.655672ms","start":"2026-08-12T11:09:39.682343Z","end":"2026-08-12T11:09:39.811998Z","steps":["trace[591208447] 'agreement among raft nodes before linearized reading' (duration: 129.506027ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:09:39.812136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.042525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-08-12T11:09:39.812248Z","caller":"traceutil/trace.go:171","msg":"trace[1807179176] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4785; }","duration":"106.207442ms","start":"2026-08-12T11:09:39.706021Z","end":"2026-08-12T11:09:39.812228Z","steps":["trace[1807179176] 'agreement among raft nodes before linearized reading' (duration: 105.87788ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:09:39.99663Z","caller":"traceutil/trace.go:171","msg":"trace[1328993873] transaction","detail":"{read_only:false; response_revision:4792; number_of_response:1; }","duration":"138.066772ms","start":"2026-08-12T11:09:39.858545Z","end":"2026-08-12T11:09:39.996612Z","steps":["trace[1328993873] 'process raft request' (duration: 137.962539ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:12:31.790233Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3151} {"level":"info","ts":"2026-08-12T11:12:31.904617Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3151,"took":"112.838111ms","hash":4131002878,"current-db-size-bytes":57552896,"current-db-size":"58 MB","current-db-size-in-use-bytes":31178752,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-12T11:12:31.904672Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4131002878,"revision":3151,"compact-revision":-1} {"level":"info","ts":"2026-08-12T11:13:46.790288Z","caller":"traceutil/trace.go:171","msg":"trace[1958464971] transaction","detail":"{read_only:false; response_revision:6933; number_of_response:1; }","duration":"124.643178ms","start":"2026-08-12T11:13:46.66562Z","end":"2026-08-12T11:13:46.790263Z","steps":["trace[1958464971] 'process raft request' (duration: 124.511616ms)"],"step_count":1}