{"level":"warn","ts":"2026-08-04T15:21:03.752092Z","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-04T15:21:03.75218Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.62: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.62:2380","--initial-cluster=instance=https://199.19.213.62:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.62:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.62: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-04T15:21:03.752362Z","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-04T15:21:03.752391Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.62:2380"]} {"level":"info","ts":"2026-08-04T15:21:03.752475Z","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-04T15:21:03.754005Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.62:2379"]} {"level":"info","ts":"2026-08-04T15:21:03.754223Z","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.62:2380"],"listen-peer-urls":["https://199.19.213.62:2380"],"advertise-client-urls":["https://199.19.213.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.62: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-04T15:21:03.758874Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.358244ms"} {"level":"info","ts":"2026-08-04T15:21:03.766141Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2451868e402db19e","cluster-id":"1953d1daf63e3757"} {"level":"info","ts":"2026-08-04T15:21:03.766222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e switched to configuration voters=()"} {"level":"info","ts":"2026-08-04T15:21:03.766241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e became follower at term 0"} {"level":"info","ts":"2026-08-04T15:21:03.766255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2451868e402db19e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-04T15:21:03.766264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e became follower at term 1"} {"level":"info","ts":"2026-08-04T15:21:03.766295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e switched to configuration voters=(2617020803999183262)"} {"level":"warn","ts":"2026-08-04T15:21:03.77568Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-04T15:21:03.77765Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-04T15:21:03.781445Z","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-04T15:21:03.784391Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2451868e402db19e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-04T15:21:03.784619Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2451868e402db19e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-04T15:21:03.784817Z","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-04T15:21:03.784922Z","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-04T15:21:03.784973Z","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-04T15:21:03.790324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e switched to configuration voters=(2617020803999183262)"} {"level":"info","ts":"2026-08-04T15:21:03.790784Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"1953d1daf63e3757","local-member-id":"2451868e402db19e","added-peer-id":"2451868e402db19e","added-peer-peer-urls":["https://199.19.213.62:2380"]} {"level":"info","ts":"2026-08-04T15:21:03.793174Z","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-04T15:21:03.793265Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.62:2380"} {"level":"info","ts":"2026-08-04T15:21:03.793306Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.62:2380"} {"level":"info","ts":"2026-08-04T15:21:03.793358Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2451868e402db19e","initial-advertise-peer-urls":["https://199.19.213.62:2380"],"listen-peer-urls":["https://199.19.213.62:2380"],"advertise-client-urls":["https://199.19.213.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-04T15:21:03.793395Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-04T15:21:04.466778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e is starting a new election at term 1"} {"level":"info","ts":"2026-08-04T15:21:04.466843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e became pre-candidate at term 1"} {"level":"info","ts":"2026-08-04T15:21:04.466867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e received MsgPreVoteResp from 2451868e402db19e at term 1"} {"level":"info","ts":"2026-08-04T15:21:04.46689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e became candidate at term 2"} {"level":"info","ts":"2026-08-04T15:21:04.466907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e received MsgVoteResp from 2451868e402db19e at term 2"} {"level":"info","ts":"2026-08-04T15:21:04.466931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2451868e402db19e became leader at term 2"} {"level":"info","ts":"2026-08-04T15:21:04.466947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2451868e402db19e elected leader 2451868e402db19e at term 2"} {"level":"info","ts":"2026-08-04T15:21:04.468005Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2451868e402db19e","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.62:2379]}","request-path":"/0/members/2451868e402db19e/attributes","cluster-id":"1953d1daf63e3757","publish-timeout":"7s"} {"level":"info","ts":"2026-08-04T15:21:04.468395Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:21:04.468804Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-04T15:21:04.469042Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-04T15:21:04.469134Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-04T15:21:04.469563Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"1953d1daf63e3757","local-member-id":"2451868e402db19e","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:21:04.469704Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:21:04.469758Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:21:04.470992Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-04T15:21:04.471135Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-04T15:21:04.487366Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.62:2379"} {"level":"info","ts":"2026-08-04T15:21:07.29061Z","caller":"traceutil/trace.go:171","msg":"trace[2062929158] transaction","detail":"{read_only:false; response_revision:157; number_of_response:1; }","duration":"108.281996ms","start":"2026-08-04T15:21:07.182302Z","end":"2026-08-04T15:21:07.290584Z","steps":["trace[2062929158] 'process raft request' (duration: 48.23884ms)","trace[2062929158] 'compare' (duration: 59.935319ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:21:14.473974Z","caller":"traceutil/trace.go:171","msg":"trace[1200456826] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"124.896511ms","start":"2026-08-04T15:21:14.34905Z","end":"2026-08-04T15:21:14.473946Z","steps":["trace[1200456826] 'process raft request' (duration: 124.735381ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:21:14.689196Z","caller":"traceutil/trace.go:171","msg":"trace[573381871] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"208.377216ms","start":"2026-08-04T15:21:14.480305Z","end":"2026-08-04T15:21:14.688682Z","steps":["trace[573381871] 'process raft request' (duration: 208.210225ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:21:14.850675Z","caller":"traceutil/trace.go:171","msg":"trace[304855953] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"145.538505ms","start":"2026-08-04T15:21:14.705113Z","end":"2026-08-04T15:21:14.850651Z","steps":["trace[304855953] 'process raft request' (duration: 143.446644ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:21:42.89272Z","caller":"traceutil/trace.go:171","msg":"trace[1715844697] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"106.886485ms","start":"2026-08-04T15:21:42.785749Z","end":"2026-08-04T15:21:42.892635Z","steps":["trace[1715844697] 'process raft request' (duration: 71.629491ms)","trace[1715844697] 'compare' (duration: 34.512185ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:21:42.898133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.447845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:21:42.898238Z","caller":"traceutil/trace.go:171","msg":"trace[717564347] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:557; }","duration":"100.574034ms","start":"2026-08-04T15:21:42.797642Z","end":"2026-08-04T15:21:42.898216Z","steps":["trace[717564347] 'agreement among raft nodes before linearized reading' (duration: 100.422954ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:42.898448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.857762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:21:42.898484Z","caller":"traceutil/trace.go:171","msg":"trace[679732508] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:557; }","duration":"100.914997ms","start":"2026-08-04T15:21:42.79756Z","end":"2026-08-04T15:21:42.898475Z","steps":["trace[679732508] 'agreement among raft nodes before linearized reading' (duration: 95.305442ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:42.898884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.097679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:21:42.898969Z","caller":"traceutil/trace.go:171","msg":"trace[569271028] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:557; }","duration":"101.209485ms","start":"2026-08-04T15:21:42.797741Z","end":"2026-08-04T15:21:42.898951Z","steps":["trace[569271028] 'agreement among raft nodes before linearized reading' (duration: 101.075166ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:48.305442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.91058ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:21:48.305545Z","caller":"traceutil/trace.go:171","msg":"trace[1119710282] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"209.154519ms","start":"2026-08-04T15:21:48.096374Z","end":"2026-08-04T15:21:48.305528Z","steps":["trace[1119710282] 'process raft request' (duration: 106.624227ms)","trace[1119710282] 'compare' (duration: 101.795523ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:21:48.999949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.94445ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:21:49.000194Z","caller":"traceutil/trace.go:171","msg":"trace[1906180079] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"509.24392ms","start":"2026-08-04T15:21:48.490915Z","end":"2026-08-04T15:21:49.000159Z","steps":["trace[1906180079] 'process raft request' (duration: 320.983999ms)","trace[1906180079] 'compare' (duration: 187.820341ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:21:49.000278Z","caller":"traceutil/trace.go:171","msg":"trace[1175620264] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"446.081882ms","start":"2026-08-04T15:21:48.554169Z","end":"2026-08-04T15:21:49.00025Z","steps":["trace[1175620264] 'read index received' (duration: 257.708173ms)","trace[1175620264] 'applied index is now lower than readState.Index' (duration: 188.370979ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:21:49.00035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:21:48.490883Z","time spent":"509.380309ms","remote":"127.0.0.1:52150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:21:49.000399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.20825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:21:49.000445Z","caller":"traceutil/trace.go:171","msg":"trace[921238568] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:600; }","duration":"446.291826ms","start":"2026-08-04T15:21:48.55414Z","end":"2026-08-04T15:21:49.000432Z","steps":["trace[921238568] 'agreement among raft nodes before linearized reading' (duration: 446.20125ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:49.000497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:21:48.554124Z","time spent":"446.362031ms","remote":"127.0.0.1:52010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-04T15:21:49.00092Z","caller":"traceutil/trace.go:171","msg":"trace[146830917] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"416.508677ms","start":"2026-08-04T15:21:48.584394Z","end":"2026-08-04T15:21:49.000902Z","steps":["trace[146830917] 'process raft request' (duration: 415.734166ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:21:49.001063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:21:48.584369Z","time spent":"416.607613ms","remote":"127.0.0.1:52206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-04T15:22:03.114752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.450917ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-04T15:23:29.931759Z","caller":"traceutil/trace.go:171","msg":"trace[1671984444] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"136.075352ms","start":"2026-08-04T15:23:29.795655Z","end":"2026-08-04T15:23:29.931731Z","steps":["trace[1671984444] 'process raft request' (duration: 135.822447ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:23:29.951886Z","caller":"traceutil/trace.go:171","msg":"trace[2132197642] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"155.469314ms","start":"2026-08-04T15:23:29.796389Z","end":"2026-08-04T15:23:29.951859Z","steps":["trace[2132197642] 'process raft request' (duration: 155.231928ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:24:18.083659Z","caller":"traceutil/trace.go:171","msg":"trace[819371371] linearizableReadLoop","detail":"{readStateIndex:2307; appliedIndex:2306; }","duration":"104.99832ms","start":"2026-08-04T15:24:17.978633Z","end":"2026-08-04T15:24:18.083632Z","steps":["trace[819371371] 'read index received' (duration: 104.787609ms)","trace[819371371] 'applied index is now lower than readState.Index' (duration: 209.701µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:24:18.083714Z","caller":"traceutil/trace.go:171","msg":"trace[911917052] transaction","detail":"{read_only:false; response_revision:2234; number_of_response:1; }","duration":"141.08447ms","start":"2026-08-04T15:24:17.942581Z","end":"2026-08-04T15:24:18.083665Z","steps":["trace[911917052] 'process raft request' (duration: 140.899598ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:18.083937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.224442ms","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-04T15:24:18.08395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.289373ms","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-04T15:24:18.084057Z","caller":"traceutil/trace.go:171","msg":"trace[1598325340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2234; }","duration":"105.427104ms","start":"2026-08-04T15:24:17.978605Z","end":"2026-08-04T15:24:18.084033Z","steps":["trace[1598325340] 'agreement among raft nodes before linearized reading' (duration: 105.142831ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:24:18.083985Z","caller":"traceutil/trace.go:171","msg":"trace[847310638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2234; }","duration":"105.305773ms","start":"2026-08-04T15:24:17.978667Z","end":"2026-08-04T15:24:18.083972Z","steps":["trace[847310638] 'agreement among raft nodes before linearized reading' (duration: 105.109842ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:24:18.255716Z","caller":"traceutil/trace.go:171","msg":"trace[1570435987] transaction","detail":"{read_only:false; response_revision:2235; number_of_response:1; }","duration":"167.657744ms","start":"2026-08-04T15:24:18.088022Z","end":"2026-08-04T15:24:18.255679Z","steps":["trace[1570435987] 'process raft request' (duration: 119.55816ms)","trace[1570435987] 'compare' (duration: 47.930693ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:24:18.265385Z","caller":"traceutil/trace.go:171","msg":"trace[698143121] transaction","detail":"{read_only:false; response_revision:2236; number_of_response:1; }","duration":"176.901961ms","start":"2026-08-04T15:24:18.08847Z","end":"2026-08-04T15:24:18.265372Z","steps":["trace[698143121] 'process raft request' (duration: 176.82568ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:25:03.134544Z","caller":"traceutil/trace.go:171","msg":"trace[325793647] linearizableReadLoop","detail":"{readStateIndex:2739; appliedIndex:2738; }","duration":"180.07794ms","start":"2026-08-04T15:25:02.954431Z","end":"2026-08-04T15:25:03.134509Z","steps":["trace[325793647] 'read index received' (duration: 179.772752ms)","trace[325793647] 'applied index is now lower than readState.Index' (duration: 304.098µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:25:03.134728Z","caller":"traceutil/trace.go:171","msg":"trace[1018050248] transaction","detail":"{read_only:false; response_revision:2645; number_of_response:1; }","duration":"182.66605ms","start":"2026-08-04T15:25:02.952049Z","end":"2026-08-04T15:25:03.134715Z","steps":["trace[1018050248] 'process raft request' (duration: 182.194149ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:03.139662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.207163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-08-04T15:25:03.140003Z","caller":"traceutil/trace.go:171","msg":"trace[1995039356] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2645; }","duration":"185.580813ms","start":"2026-08-04T15:25:02.954405Z","end":"2026-08-04T15:25:03.139986Z","steps":["trace[1995039356] 'agreement among raft nodes before linearized reading' (duration: 180.534831ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:25:30.637858Z","caller":"traceutil/trace.go:171","msg":"trace[2121472782] transaction","detail":"{read_only:false; number_of_response:0; response_revision:2836; }","duration":"209.979892ms","start":"2026-08-04T15:25:30.427854Z","end":"2026-08-04T15:25:30.637834Z","steps":["trace[2121472782] 'process raft request' (duration: 193.787835ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:25:35.870423Z","caller":"traceutil/trace.go:171","msg":"trace[2028164053] transaction","detail":"{read_only:false; response_revision:2876; number_of_response:1; }","duration":"132.632627ms","start":"2026-08-04T15:25:35.737764Z","end":"2026-08-04T15:25:35.870397Z","steps":["trace[2028164053] 'process raft request' (duration: 131.879043ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:36.181328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.644551ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-08-04T15:25:36.181462Z","caller":"traceutil/trace.go:171","msg":"trace[2010806394] linearizableReadLoop","detail":"{readStateIndex:2985; appliedIndex:2984; }","duration":"300.498878ms","start":"2026-08-04T15:25:35.880941Z","end":"2026-08-04T15:25:36.18144Z","steps":["trace[2010806394] 'read index received' (duration: 173.746874ms)","trace[2010806394] 'applied index is now lower than readState.Index' (duration: 126.750494ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:25:36.181659Z","caller":"traceutil/trace.go:171","msg":"trace[57594139] transaction","detail":"{read_only:false; number_of_response:0; response_revision:2876; }","duration":"302.782613ms","start":"2026-08-04T15:25:35.878859Z","end":"2026-08-04T15:25:36.181641Z","steps":["trace[57594139] 'process raft request' (duration: 175.752899ms)","trace[57594139] 'compare' (duration: 126.59703ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:25:36.181785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:25:35.878844Z","time spent":"302.862556ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-04T15:25:36.182517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.367098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:5906"} {"level":"info","ts":"2026-08-04T15:25:36.182608Z","caller":"traceutil/trace.go:171","msg":"trace[1034047719] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:2876; }","duration":"301.683108ms","start":"2026-08-04T15:25:35.880904Z","end":"2026-08-04T15:25:36.182587Z","steps":["trace[1034047719] 'agreement among raft nodes before linearized reading' (duration: 301.10927ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:36.182657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:25:35.88089Z","time spent":"301.75723ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":5928,"request content":"key:\"/registry/statefulsets/auth-system/keycloak\" "} {"level":"info","ts":"2026-08-04T15:27:51.163218Z","caller":"traceutil/trace.go:171","msg":"trace[550269018] linearizableReadLoop","detail":"{readStateIndex:4603; appliedIndex:4601; }","duration":"111.285309ms","start":"2026-08-04T15:27:51.051911Z","end":"2026-08-04T15:27:51.163196Z","steps":["trace[550269018] 'read index received' (duration: 48.351237ms)","trace[550269018] 'applied index is now lower than readState.Index' (duration: 62.933272ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:27:51.163326Z","caller":"traceutil/trace.go:171","msg":"trace[1357070409] transaction","detail":"{read_only:false; response_revision:4417; number_of_response:1; }","duration":"133.503898ms","start":"2026-08-04T15:27:51.029813Z","end":"2026-08-04T15:27:51.163317Z","steps":["trace[1357070409] 'process raft request' (duration: 132.721937ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:27:51.163572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.649674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f0bf60f4-a6d8-4cf6-9f7f-4823be8fc7c0\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-08-04T15:27:51.163633Z","caller":"traceutil/trace.go:171","msg":"trace[32601834] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f0bf60f4-a6d8-4cf6-9f7f-4823be8fc7c0; range_end:; response_count:1; response_revision:4417; }","duration":"102.736498ms","start":"2026-08-04T15:27:51.060883Z","end":"2026-08-04T15:27:51.16362Z","steps":["trace[32601834] 'agreement among raft nodes before linearized reading' (duration: 102.590161ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:27:51.165481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.550956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2360973"} {"level":"info","ts":"2026-08-04T15:27:51.165535Z","caller":"traceutil/trace.go:171","msg":"trace[223747208] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4417; }","duration":"113.651311ms","start":"2026-08-04T15:27:51.051871Z","end":"2026-08-04T15:27:51.165523Z","steps":["trace[223747208] 'agreement among raft nodes before linearized reading' (duration: 111.804846ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:28:02.303581Z","caller":"traceutil/trace.go:171","msg":"trace[229451360] transaction","detail":"{read_only:false; response_revision:4654; number_of_response:1; }","duration":"143.542076ms","start":"2026-08-04T15:28:02.160014Z","end":"2026-08-04T15:28:02.303557Z","steps":["trace[229451360] 'process raft request' (duration: 143.403959ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:28:02.323837Z","caller":"traceutil/trace.go:171","msg":"trace[1825865801] transaction","detail":"{read_only:false; response_revision:4655; number_of_response:1; }","duration":"161.502848ms","start":"2026-08-04T15:28:02.162311Z","end":"2026-08-04T15:28:02.323813Z","steps":["trace[1825865801] 'process raft request' (duration: 161.34579ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:28:02.324006Z","caller":"traceutil/trace.go:171","msg":"trace[989657916] linearizableReadLoop","detail":"{readStateIndex:4850; appliedIndex:4848; }","duration":"146.926803ms","start":"2026-08-04T15:28:02.177067Z","end":"2026-08-04T15:28:02.323994Z","steps":["trace[989657916] 'read index received' (duration: 126.354893ms)","trace[989657916] 'applied index is now lower than readState.Index' (duration: 20.570819ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:28:02.324074Z","caller":"traceutil/trace.go:171","msg":"trace[168017338] transaction","detail":"{read_only:false; response_revision:4656; number_of_response:1; }","duration":"141.15946ms","start":"2026-08-04T15:28:02.182906Z","end":"2026-08-04T15:28:02.324066Z","steps":["trace[168017338] 'process raft request' (duration: 140.861485ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:28:02.324372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.294033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-04T15:28:02.324443Z","caller":"traceutil/trace.go:171","msg":"trace[2029247909] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4656; }","duration":"147.404768ms","start":"2026-08-04T15:28:02.177028Z","end":"2026-08-04T15:28:02.324433Z","steps":["trace[2029247909] 'agreement among raft nodes before linearized reading' (duration: 147.202618ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:28:02.32561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.316516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-787b46cf47-pfq5m\" ","response":"range_response_count:1 size:11256"} {"level":"info","ts":"2026-08-04T15:28:02.325677Z","caller":"traceutil/trace.go:171","msg":"trace[345419778] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-787b46cf47-pfq5m; range_end:; response_count:1; response_revision:4656; }","duration":"144.431562ms","start":"2026-08-04T15:28:02.181229Z","end":"2026-08-04T15:28:02.32566Z","steps":["trace[345419778] 'agreement among raft nodes before linearized reading' (duration: 143.065081ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:28:02.583094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.579831ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:28:02.583236Z","caller":"traceutil/trace.go:171","msg":"trace[1921748248] transaction","detail":"{read_only:false; response_revision:4657; number_of_response:1; }","duration":"252.554068ms","start":"2026-08-04T15:28:02.330658Z","end":"2026-08-04T15:28:02.583212Z","steps":["trace[1921748248] 'process raft request' (duration: 151.740776ms)","trace[1921748248] 'compare' (duration: 100.179839ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:28:02.671097Z","caller":"traceutil/trace.go:171","msg":"trace[204846433] transaction","detail":"{read_only:false; response_revision:4658; number_of_response:1; }","duration":"333.438684ms","start":"2026-08-04T15:28:02.337632Z","end":"2026-08-04T15:28:02.67107Z","steps":["trace[204846433] 'process raft request' (duration: 333.312658ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:28:02.671237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:28:02.337621Z","time spent":"333.54487ms","remote":"127.0.0.1:52150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11829,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:28:02.671904Z","caller":"traceutil/trace.go:171","msg":"trace[1713358478] linearizableReadLoop","detail":"{readStateIndex:4853; appliedIndex:4853; }","duration":"119.290233ms","start":"2026-08-04T15:28:02.552582Z","end":"2026-08-04T15:28:02.671872Z","steps":["trace[1713358478] 'read index received' (duration: 119.284973ms)","trace[1713358478] 'applied index is now lower than readState.Index' (duration: 4.44µs)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:28:02.671984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.398198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:28:02.672014Z","caller":"traceutil/trace.go:171","msg":"trace[635710323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4658; }","duration":"119.461622ms","start":"2026-08-04T15:28:02.552543Z","end":"2026-08-04T15:28:02.672004Z","steps":["trace[635710323] 'agreement among raft nodes before linearized reading' (duration: 119.407199ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:28:02.784188Z","caller":"traceutil/trace.go:171","msg":"trace[210229154] transaction","detail":"{read_only:false; response_revision:4660; number_of_response:1; }","duration":"103.475732ms","start":"2026-08-04T15:28:02.680685Z","end":"2026-08-04T15:28:02.784161Z","steps":["trace[210229154] 'process raft request' (duration: 103.248811ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:28:03.233542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.60607ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-04T15:28:03.234725Z","caller":"traceutil/trace.go:171","msg":"trace[1931063988] transaction","detail":"{read_only:false; response_revision:4663; number_of_response:1; }","duration":"405.41229ms","start":"2026-08-04T15:28:02.829279Z","end":"2026-08-04T15:28:03.234692Z","steps":["trace[1931063988] 'process raft request' (duration: 404.370055ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:28:03.23511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:28:02.829254Z","time spent":"405.567828ms","remote":"127.0.0.1:52138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:28:03.295367Z","caller":"traceutil/trace.go:171","msg":"trace[212532578] transaction","detail":"{read_only:false; response_revision:4664; number_of_response:1; }","duration":"465.947322ms","start":"2026-08-04T15:28:02.829399Z","end":"2026-08-04T15:28:03.295347Z","steps":["trace[212532578] 'process raft request' (duration: 405.272633ms)","trace[212532578] 'compare' (duration: 60.472849ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:28:03.296894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:28:02.829368Z","time spent":"467.44162ms","remote":"127.0.0.1:52138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1282,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:28:03.295408Z","caller":"traceutil/trace.go:171","msg":"trace[695580058] transaction","detail":"{read_only:false; response_revision:4665; number_of_response:1; }","duration":"465.34967ms","start":"2026-08-04T15:28:02.830041Z","end":"2026-08-04T15:28:03.295391Z","steps":["trace[695580058] 'process raft request' (duration: 465.240665ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:28:03.297433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:28:02.830022Z","time spent":"467.23799ms","remote":"127.0.0.1:52248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1868,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:28:03.295575Z","caller":"traceutil/trace.go:171","msg":"trace[2065960057] linearizableReadLoop","detail":"{readStateIndex:4862; appliedIndex:4857; }","duration":"105.552158ms","start":"2026-08-04T15:28:03.189993Z","end":"2026-08-04T15:28:03.295545Z","steps":["trace[2065960057] 'read index received' (duration: 77.785µs)","trace[2065960057] 'applied index is now lower than readState.Index' (duration: 105.472453ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:28:03.295698Z","caller":"traceutil/trace.go:171","msg":"trace[813827834] transaction","detail":"{read_only:false; response_revision:4666; number_of_response:1; }","duration":"465.255446ms","start":"2026-08-04T15:28:02.830426Z","end":"2026-08-04T15:28:03.295682Z","steps":["trace[813827834] 'process raft request' (duration: 464.921418ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:28:03.29771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:28:02.830414Z","time spent":"467.23228ms","remote":"127.0.0.1:52248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1454,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:28:03.295748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.737618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-787b46cf47-pfq5m.18c8a3bc7bdd3a9c\" ","response":"range_response_count:1 size:896"} {"level":"info","ts":"2026-08-04T15:28:03.298002Z","caller":"traceutil/trace.go:171","msg":"trace[1445900894] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-787b46cf47-pfq5m.18c8a3bc7bdd3a9c; range_end:; response_count:1; response_revision:4666; }","duration":"108.005607ms","start":"2026-08-04T15:28:03.18995Z","end":"2026-08-04T15:28:03.297955Z","steps":["trace[1445900894] 'agreement among raft nodes before linearized reading' (duration: 105.678935ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:28:03.527465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.957703ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:28:03.527813Z","caller":"traceutil/trace.go:171","msg":"trace[591506447] transaction","detail":"{read_only:false; response_revision:4669; number_of_response:1; }","duration":"145.193753ms","start":"2026-08-04T15:28:03.382597Z","end":"2026-08-04T15:28:03.527791Z","steps":["trace[591506447] 'process raft request' (duration: 17.829828ms)","trace[591506447] 'compare' (duration: 126.69521ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:28:03.52785Z","caller":"traceutil/trace.go:171","msg":"trace[1090373065] transaction","detail":"{read_only:false; response_revision:4670; number_of_response:1; }","duration":"145.229974ms","start":"2026-08-04T15:28:03.382588Z","end":"2026-08-04T15:28:03.527818Z","steps":["trace[1090373065] 'process raft request' (duration: 144.976301ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:28:09.792651Z","caller":"traceutil/trace.go:171","msg":"trace[1115313720] linearizableReadLoop","detail":"{readStateIndex:4929; appliedIndex:4928; }","duration":"131.116037ms","start":"2026-08-04T15:28:09.661518Z","end":"2026-08-04T15:28:09.792634Z","steps":["trace[1115313720] 'read index received' (duration: 112.138342ms)","trace[1115313720] 'applied index is now lower than readState.Index' (duration: 18.976835ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:28:09.79269Z","caller":"traceutil/trace.go:171","msg":"trace[51938750] transaction","detail":"{read_only:false; response_revision:4731; number_of_response:1; }","duration":"141.391632ms","start":"2026-08-04T15:28:09.651266Z","end":"2026-08-04T15:28:09.792658Z","steps":["trace[51938750] 'process raft request' (duration: 122.453749ms)","trace[51938750] 'compare' (duration: 18.288579ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:28:09.792998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.465077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11493"} {"level":"info","ts":"2026-08-04T15:28:09.793036Z","caller":"traceutil/trace.go:171","msg":"trace[805093516] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4731; }","duration":"131.5463ms","start":"2026-08-04T15:28:09.661482Z","end":"2026-08-04T15:28:09.793029Z","steps":["trace[805093516] 'agreement among raft nodes before linearized reading' (duration: 131.214652ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:31:04.495971Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3149} {"level":"info","ts":"2026-08-04T15:31:04.593026Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3149,"took":"95.566528ms","hash":2863116529,"current-db-size-bytes":58900480,"current-db-size":"59 MB","current-db-size-in-use-bytes":31207424,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-04T15:31:04.593284Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2863116529,"revision":3149,"compact-revision":-1} {"level":"info","ts":"2026-08-04T15:32:12.277952Z","caller":"traceutil/trace.go:171","msg":"trace[634476124] transaction","detail":"{read_only:false; response_revision:6902; number_of_response:1; }","duration":"138.148873ms","start":"2026-08-04T15:32:12.139778Z","end":"2026-08-04T15:32:12.277926Z","steps":["trace[634476124] 'process raft request' (duration: 109.364118ms)","trace[634476124] 'compare' (duration: 28.655735ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:32:12.664518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.567528ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:32:12.664732Z","caller":"traceutil/trace.go:171","msg":"trace[1372732586] transaction","detail":"{read_only:false; response_revision:6906; number_of_response:1; }","duration":"185.489865ms","start":"2026-08-04T15:32:12.479225Z","end":"2026-08-04T15:32:12.664715Z","steps":["trace[1372732586] 'process raft request' (duration: 185.404035ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:32:12.66494Z","caller":"traceutil/trace.go:171","msg":"trace[63991559] transaction","detail":"{read_only:false; response_revision:6905; number_of_response:1; }","duration":"308.450107ms","start":"2026-08-04T15:32:12.356476Z","end":"2026-08-04T15:32:12.664926Z","steps":["trace[63991559] 'process raft request' (duration: 74.37769ms)","trace[63991559] 'compare' (duration: 233.302348ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:32:12.665003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:32:12.356461Z","time spent":"308.509957ms","remote":"127.0.0.1:52232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":536,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:36:04.501732Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6240} {"level":"info","ts":"2026-08-04T15:36:04.593515Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6240,"took":"90.053062ms","hash":2876281303,"current-db-size-bytes":58900480,"current-db-size":"59 MB","current-db-size-in-use-bytes":18612224,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-08-04T15:36:04.593574Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2876281303,"revision":6240,"compact-revision":3149}