{"level":"warn","ts":"2026-05-30T16:49:10.361964Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-30T16:49:10.362181Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.129:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.129:2380","--initial-cluster=instance=https://199.204.45.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.129: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-05-30T16:49:10.362348Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-30T16:49:10.362378Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-05-30T16:49:10.362428Z","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-05-30T16:49:10.363769Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"]} {"level":"info","ts":"2026-05-30T16:49:10.363947Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.129: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-05-30T16:49:10.370224Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.825699ms"} {"level":"info","ts":"2026-05-30T16:49:10.384738Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-05-30T16:49:10.384872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-05-30T16:49:10.38492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-05-30T16:49:10.384943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cec112083e8d3ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-30T16:49:10.384959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-05-30T16:49:10.385004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-05-30T16:49:10.393751Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-30T16:49:10.397601Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-30T16:49:10.400391Z","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-05-30T16:49:10.403713Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cec112083e8d3ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-30T16:49:10.403929Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cec112083e8d3ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-30T16:49:10.404211Z","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-05-30T16:49:10.404339Z","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-05-30T16:49:10.404382Z","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-05-30T16:49:10.405371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-05-30T16:49:10.405963Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","added-peer-id":"3cec112083e8d3ff","added-peer-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-05-30T16:49:10.40957Z","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-05-30T16:49:10.409689Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-05-30T16:49:10.409754Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-05-30T16:49:10.409832Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cec112083e8d3ff","initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-30T16:49:10.409876Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-30T16:49:10.585643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-05-30T16:49:10.58571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-05-30T16:49:10.585738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-05-30T16:49:10.585754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-05-30T16:49:10.585762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-05-30T16:49:10.585773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-05-30T16:49:10.585782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-05-30T16:49:10.597567Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T16:49:10.606978Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-30T16:49:10.607024Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-30T16:49:10.606979Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cec112083e8d3ff","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.129:2379]}","request-path":"/0/members/3cec112083e8d3ff/attributes","cluster-id":"3f68255a3b440659","publish-timeout":"7s"} {"level":"info","ts":"2026-05-30T16:49:10.607324Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T16:49:10.607498Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T16:49:10.607535Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T16:49:10.607593Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-30T16:49:10.607658Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-30T16:49:10.610163Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"info","ts":"2026-05-30T16:49:10.61058Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-30T16:49:12.152736Z","caller":"traceutil/trace.go:171","msg":"trace[369404619] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"101.60844ms","start":"2026-05-30T16:49:12.051101Z","end":"2026-05-30T16:49:12.152709Z","steps":["trace[369404619] 'process raft request' (duration: 70.785947ms)","trace[369404619] 'compare' (duration: 30.525208ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T16:49:15.51545Z","caller":"traceutil/trace.go:171","msg":"trace[2061319458] linearizableReadLoop","detail":"{readStateIndex:281; appliedIndex:280; }","duration":"120.280228ms","start":"2026-05-30T16:49:15.395143Z","end":"2026-05-30T16:49:15.515423Z","steps":["trace[2061319458] 'read index received' (duration: 116.038269ms)","trace[2061319458] 'applied index is now lower than readState.Index' (duration: 4.241259ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T16:49:15.515538Z","caller":"traceutil/trace.go:171","msg":"trace[1180035186] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"131.550717ms","start":"2026-05-30T16:49:15.38398Z","end":"2026-05-30T16:49:15.51553Z","steps":["trace[1180035186] 'process raft request' (duration: 127.282449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:49:15.515675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.505021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T16:49:15.515798Z","caller":"traceutil/trace.go:171","msg":"trace[1688392985] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:0; response_revision:275; }","duration":"120.660785ms","start":"2026-05-30T16:49:15.395101Z","end":"2026-05-30T16:49:15.515762Z","steps":["trace[1688392985] 'agreement among raft nodes before linearized reading' (duration: 120.439921ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:49:16.621967Z","caller":"traceutil/trace.go:171","msg":"trace[1084295981] transaction","detail":"{read_only:false; number_of_response:0; response_revision:309; }","duration":"117.288152ms","start":"2026-05-30T16:49:16.504655Z","end":"2026-05-30T16:49:16.621943Z","steps":["trace[1084295981] 'process raft request' (duration: 117.067708ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:49:16.621998Z","caller":"traceutil/trace.go:171","msg":"trace[1047453779] transaction","detail":"{read_only:false; number_of_response:0; response_revision:309; }","duration":"116.745882ms","start":"2026-05-30T16:49:16.50523Z","end":"2026-05-30T16:49:16.621976Z","steps":["trace[1047453779] 'process raft request' (duration: 116.617019ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:49:16.622133Z","caller":"traceutil/trace.go:171","msg":"trace[918124671] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"101.114741ms","start":"2026-05-30T16:49:16.52098Z","end":"2026-05-30T16:49:16.622094Z","steps":["trace[918124671] 'process raft request' (duration: 100.940398ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:49:16.622216Z","caller":"traceutil/trace.go:171","msg":"trace[1480530083] transaction","detail":"{read_only:false; number_of_response:0; response_revision:309; }","duration":"116.803443ms","start":"2026-05-30T16:49:16.505395Z","end":"2026-05-30T16:49:16.622198Z","steps":["trace[1480530083] 'process raft request' (duration: 116.484867ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:49:24.58679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.713444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T16:49:24.586986Z","caller":"traceutil/trace.go:171","msg":"trace[2018655947] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"241.211728ms","start":"2026-05-30T16:49:24.345735Z","end":"2026-05-30T16:49:24.586947Z","steps":["trace[2018655947] 'process raft request' (duration: 111.461364ms)","trace[2018655947] 'compare' (duration: 128.47195ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T16:49:24.829453Z","caller":"traceutil/trace.go:171","msg":"trace[1487755187] linearizableReadLoop","detail":"{readStateIndex:419; appliedIndex:418; }","duration":"133.614795ms","start":"2026-05-30T16:49:24.695808Z","end":"2026-05-30T16:49:24.829423Z","steps":["trace[1487755187] 'read index received' (duration: 133.415322ms)","trace[1487755187] 'applied index is now lower than readState.Index' (duration: 198.133µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T16:49:24.829535Z","caller":"traceutil/trace.go:171","msg":"trace[1813001982] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"233.288ms","start":"2026-05-30T16:49:24.596228Z","end":"2026-05-30T16:49:24.829516Z","steps":["trace[1813001982] 'process raft request' (duration: 233.050036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:49:24.829602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.772539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T16:49:24.829692Z","caller":"traceutil/trace.go:171","msg":"trace[2006121543] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:405; }","duration":"133.941832ms","start":"2026-05-30T16:49:24.695736Z","end":"2026-05-30T16:49:24.829678Z","steps":["trace[2006121543] 'agreement among raft nodes before linearized reading' (duration: 133.792929ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:49:53.299345Z","caller":"traceutil/trace.go:171","msg":"trace[94925977] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"136.212562ms","start":"2026-05-30T16:49:53.1631Z","end":"2026-05-30T16:49:53.299313Z","steps":["trace[94925977] 'process raft request' (duration: 136.08346ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:49:53.496681Z","caller":"traceutil/trace.go:171","msg":"trace[529028848] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"114.685523ms","start":"2026-05-30T16:49:53.381963Z","end":"2026-05-30T16:49:53.496648Z","steps":["trace[529028848] 'process raft request' (duration: 114.056472ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:49:54.158693Z","caller":"traceutil/trace.go:171","msg":"trace[1463928421] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"195.367256ms","start":"2026-05-30T16:49:53.963308Z","end":"2026-05-30T16:49:54.158675Z","steps":["trace[1463928421] 'process raft request' (duration: 194.962909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:49:54.504001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.735908ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T16:49:54.504148Z","caller":"traceutil/trace.go:171","msg":"trace[1190432256] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:637; }","duration":"285.896271ms","start":"2026-05-30T16:49:54.21823Z","end":"2026-05-30T16:49:54.504126Z","steps":["trace[1190432256] 'range keys from in-memory index tree' (duration: 285.716918ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:49:54.504158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.871496ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-30T16:49:54.50437Z","caller":"traceutil/trace.go:171","msg":"trace[726257905] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"287.491511ms","start":"2026-05-30T16:49:54.216861Z","end":"2026-05-30T16:49:54.504352Z","steps":["trace[726257905] 'process raft request' (duration: 52.35206ms)","trace[726257905] 'compare' (duration: 234.703323ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T16:49:54.717166Z","caller":"traceutil/trace.go:171","msg":"trace[4296597] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"193.584173ms","start":"2026-05-30T16:49:54.523555Z","end":"2026-05-30T16:49:54.71714Z","steps":["trace[4296597] 'process raft request' (duration: 185.031315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:50:10.808013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.024803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-r4vgj\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-30T16:50:10.808113Z","caller":"traceutil/trace.go:171","msg":"trace[832849990] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-r4vgj; range_end:; response_count:1; response_revision:801; }","duration":"105.186868ms","start":"2026-05-30T16:50:10.702906Z","end":"2026-05-30T16:50:10.808093Z","steps":["trace[832849990] 'range keys from in-memory index tree' (duration: 104.82331ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:50:10.993008Z","caller":"traceutil/trace.go:171","msg":"trace[87212841] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"177.671187ms","start":"2026-05-30T16:50:10.815312Z","end":"2026-05-30T16:50:10.992983Z","steps":["trace[87212841] 'process raft request' (duration: 177.502004ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:50:11.019717Z","caller":"traceutil/trace.go:171","msg":"trace[355463739] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"203.359544ms","start":"2026-05-30T16:50:10.816314Z","end":"2026-05-30T16:50:11.019674Z","steps":["trace[355463739] 'process raft request' (duration: 192.675866ms)","trace[355463739] 'compare' (duration: 10.503534ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T16:51:02.139401Z","caller":"traceutil/trace.go:171","msg":"trace[698745822] transaction","detail":"{read_only:false; response_revision:1459; number_of_response:1; }","duration":"102.497445ms","start":"2026-05-30T16:51:02.03687Z","end":"2026-05-30T16:51:02.139367Z","steps":["trace[698745822] 'process raft request' (duration: 102.292581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:51:24.435234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.634331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T16:51:24.43526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.604136ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T16:51:24.435377Z","caller":"traceutil/trace.go:171","msg":"trace[1575821090] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1859; }","duration":"220.838785ms","start":"2026-05-30T16:51:24.214517Z","end":"2026-05-30T16:51:24.435355Z","steps":["trace[1575821090] 'count revisions from in-memory index tree' (duration: 220.520178ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:51:24.435412Z","caller":"traceutil/trace.go:171","msg":"trace[70904683] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1859; }","duration":"216.791159ms","start":"2026-05-30T16:51:24.218599Z","end":"2026-05-30T16:51:24.43539Z","steps":["trace[70904683] 'range keys from in-memory index tree' (duration: 216.582065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:51:24.435793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.253623ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T16:51:24.435919Z","caller":"traceutil/trace.go:171","msg":"trace[2077410179] linearizableReadLoop","detail":"{readStateIndex:1933; appliedIndex:1932; }","duration":"137.18451ms","start":"2026-05-30T16:51:24.298717Z","end":"2026-05-30T16:51:24.435901Z","steps":["trace[2077410179] 'read index received' (duration: 46.491µs)","trace[2077410179] 'applied index is now lower than readState.Index' (duration: 137.135948ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T16:51:24.435952Z","caller":"traceutil/trace.go:171","msg":"trace[1218132598] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"209.129596ms","start":"2026-05-30T16:51:24.226765Z","end":"2026-05-30T16:51:24.435895Z","steps":["trace[1218132598] 'process raft request' (duration: 44.67064ms)","trace[1218132598] 'compare' (duration: 163.58621ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T16:51:24.436083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.372433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-30T16:51:24.436118Z","caller":"traceutil/trace.go:171","msg":"trace[2044517256] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1860; }","duration":"137.445424ms","start":"2026-05-30T16:51:24.298663Z","end":"2026-05-30T16:51:24.436109Z","steps":["trace[2044517256] 'agreement among raft nodes before linearized reading' (duration: 137.296812ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:51:24.599032Z","caller":"traceutil/trace.go:171","msg":"trace[554239452] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"101.519007ms","start":"2026-05-30T16:51:24.497483Z","end":"2026-05-30T16:51:24.599002Z","steps":["trace[554239452] 'process raft request' (duration: 94.016907ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:52:09.343862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.797996ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-30T16:52:09.34429Z","caller":"traceutil/trace.go:171","msg":"trace[730221140] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"130.807634ms","start":"2026-05-30T16:52:09.213459Z","end":"2026-05-30T16:52:09.344266Z","steps":["trace[730221140] 'process raft request' (duration: 130.547919ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:52:15.628823Z","caller":"traceutil/trace.go:171","msg":"trace[568535888] linearizableReadLoop","detail":"{readStateIndex:2275; appliedIndex:2274; }","duration":"106.273216ms","start":"2026-05-30T16:52:15.522526Z","end":"2026-05-30T16:52:15.6288Z","steps":["trace[568535888] 'read index received' (duration: 106.086972ms)","trace[568535888] 'applied index is now lower than readState.Index' (duration: 185.444µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T16:52:15.628922Z","caller":"traceutil/trace.go:171","msg":"trace[1147618048] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"262.21843ms","start":"2026-05-30T16:52:15.366682Z","end":"2026-05-30T16:52:15.6289Z","steps":["trace[1147618048] 'process raft request' (duration: 261.989396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:52:15.629095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.562002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-30T16:52:15.629129Z","caller":"traceutil/trace.go:171","msg":"trace[1022719343] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2192; }","duration":"106.650434ms","start":"2026-05-30T16:52:15.522471Z","end":"2026-05-30T16:52:15.629121Z","steps":["trace[1022719343] 'agreement among raft nodes before linearized reading' (duration: 106.492801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:53:38.604282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.977349ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T16:53:38.604496Z","caller":"traceutil/trace.go:171","msg":"trace[1964420176] linearizableReadLoop","detail":"{readStateIndex:2945; appliedIndex:2943; }","duration":"184.58571ms","start":"2026-05-30T16:53:38.419892Z","end":"2026-05-30T16:53:38.604478Z","steps":["trace[1964420176] 'read index received' (duration: 149.202798ms)","trace[1964420176] 'applied index is now lower than readState.Index' (duration: 35.381572ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T16:53:38.604647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.761984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-30T16:53:38.604682Z","caller":"traceutil/trace.go:171","msg":"trace[716988793] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2837; }","duration":"184.841455ms","start":"2026-05-30T16:53:38.419829Z","end":"2026-05-30T16:53:38.604671Z","steps":["trace[716988793] 'agreement among raft nodes before linearized reading' (duration: 184.703732ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:53:38.604882Z","caller":"traceutil/trace.go:171","msg":"trace[33303209] transaction","detail":"{read_only:false; response_revision:2837; number_of_response:1; }","duration":"282.299198ms","start":"2026-05-30T16:53:38.322573Z","end":"2026-05-30T16:53:38.604872Z","steps":["trace[33303209] 'process raft request' (duration: 281.819518ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:53:38.604864Z","caller":"traceutil/trace.go:171","msg":"trace[528123946] transaction","detail":"{read_only:false; response_revision:2836; number_of_response:1; }","duration":"327.220958ms","start":"2026-05-30T16:53:38.277611Z","end":"2026-05-30T16:53:38.604832Z","steps":["trace[528123946] 'process raft request' (duration: 87.603546ms)","trace[528123946] 'compare' (duration: 238.729475ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T16:53:38.604987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T16:53:38.277583Z","time spent":"327.34069ms","remote":"127.0.0.1:48468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T16:53:38.627926Z","caller":"traceutil/trace.go:171","msg":"trace[12318525] transaction","detail":"{read_only:false; response_revision:2838; number_of_response:1; }","duration":"115.971679ms","start":"2026-05-30T16:53:38.511933Z","end":"2026-05-30T16:53:38.627905Z","steps":["trace[12318525] 'process raft request' (duration: 115.853297ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:53:43.803854Z","caller":"traceutil/trace.go:171","msg":"trace[1898021179] linearizableReadLoop","detail":"{readStateIndex:2976; appliedIndex:2975; }","duration":"108.082254ms","start":"2026-05-30T16:53:43.695751Z","end":"2026-05-30T16:53:43.803833Z","steps":["trace[1898021179] 'read index received' (duration: 97.162717ms)","trace[1898021179] 'applied index is now lower than readState.Index' (duration: 10.918637ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T16:53:43.803992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.227816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T16:53:43.804069Z","caller":"traceutil/trace.go:171","msg":"trace[389778716] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2866; }","duration":"108.349249ms","start":"2026-05-30T16:53:43.695706Z","end":"2026-05-30T16:53:43.804056Z","steps":["trace[389778716] 'agreement among raft nodes before linearized reading' (duration: 108.209476ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:53:43.803881Z","caller":"traceutil/trace.go:171","msg":"trace[771520401] transaction","detail":"{read_only:false; response_revision:2866; number_of_response:1; }","duration":"131.696371ms","start":"2026-05-30T16:53:43.672147Z","end":"2026-05-30T16:53:43.803843Z","steps":["trace[771520401] 'process raft request' (duration: 120.790665ms)","trace[771520401] 'compare' (duration: 10.809624ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T16:56:14.337787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.304616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-30T16:56:14.337883Z","caller":"traceutil/trace.go:171","msg":"trace[666155969] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3859; }","duration":"104.436928ms","start":"2026-05-30T16:56:14.23343Z","end":"2026-05-30T16:56:14.337867Z","steps":["trace[666155969] 'range keys from in-memory index tree' (duration: 104.174173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:56:14.337856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.19452ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T16:56:14.338211Z","caller":"traceutil/trace.go:171","msg":"trace[939829991] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:3859; }","duration":"120.545327ms","start":"2026-05-30T16:56:14.217616Z","end":"2026-05-30T16:56:14.338161Z","steps":["trace[939829991] 'range keys from in-memory index tree' (duration: 120.17287ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:56:14.478442Z","caller":"traceutil/trace.go:171","msg":"trace[1279592194] transaction","detail":"{read_only:false; response_revision:3860; number_of_response:1; }","duration":"135.598754ms","start":"2026-05-30T16:56:14.342815Z","end":"2026-05-30T16:56:14.478414Z","steps":["trace[1279592194] 'process raft request' (duration: 135.464302ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:57:37.3876Z","caller":"traceutil/trace.go:171","msg":"trace[1380104305] transaction","detail":"{read_only:false; response_revision:4852; number_of_response:1; }","duration":"121.00147ms","start":"2026-05-30T16:57:37.266571Z","end":"2026-05-30T16:57:37.387573Z","steps":["trace[1380104305] 'process raft request' (duration: 120.823896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:57:56.193813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.83781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T16:57:56.193917Z","caller":"traceutil/trace.go:171","msg":"trace[521827095] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:5313; }","duration":"246.996893ms","start":"2026-05-30T16:57:55.946897Z","end":"2026-05-30T16:57:56.193894Z","steps":["trace[521827095] 'count revisions from in-memory index tree' (duration: 246.729489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:57:56.194165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.343116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T16:57:56.194415Z","caller":"traceutil/trace.go:171","msg":"trace[1525581067] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:5313; }","duration":"215.610153ms","start":"2026-05-30T16:57:55.97873Z","end":"2026-05-30T16:57:56.194341Z","steps":["trace[1525581067] 'count revisions from in-memory index tree' (duration: 215.194884ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:57:56.194604Z","caller":"traceutil/trace.go:171","msg":"trace[338168635] transaction","detail":"{read_only:false; response_revision:5314; number_of_response:1; }","duration":"176.793962ms","start":"2026-05-30T16:57:56.017783Z","end":"2026-05-30T16:57:56.194577Z","steps":["trace[338168635] 'process raft request' (duration: 169.223819ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:58:03.459627Z","caller":"traceutil/trace.go:171","msg":"trace[1865218452] transaction","detail":"{read_only:false; response_revision:5447; number_of_response:1; }","duration":"144.190146ms","start":"2026-05-30T16:58:03.31538Z","end":"2026-05-30T16:58:03.459571Z","steps":["trace[1865218452] 'process raft request' (duration: 126.930511ms)","trace[1865218452] 'compare' (duration: 16.96237ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T16:58:03.459637Z","caller":"traceutil/trace.go:171","msg":"trace[75905151] transaction","detail":"{read_only:false; response_revision:5448; number_of_response:1; }","duration":"142.072637ms","start":"2026-05-30T16:58:03.317533Z","end":"2026-05-30T16:58:03.459606Z","steps":["trace[75905151] 'process raft request' (duration: 141.965835ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:58:03.853585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.559317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T16:58:03.853955Z","caller":"traceutil/trace.go:171","msg":"trace[364514527] linearizableReadLoop","detail":"{readStateIndex:5651; appliedIndex:5649; }","duration":"359.035874ms","start":"2026-05-30T16:58:03.494899Z","end":"2026-05-30T16:58:03.853934Z","steps":["trace[364514527] 'read index received' (duration: 105.051128ms)","trace[364514527] 'applied index is now lower than readState.Index' (duration: 253.981896ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T16:58:03.853878Z","caller":"traceutil/trace.go:171","msg":"trace[1326203881] transaction","detail":"{read_only:false; response_revision:5450; number_of_response:1; }","duration":"383.78315ms","start":"2026-05-30T16:58:03.470017Z","end":"2026-05-30T16:58:03.8538Z","steps":["trace[1326203881] 'process raft request' (duration: 129.913847ms)","trace[1326203881] 'compare' (duration: 253.273881ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T16:58:03.854052Z","caller":"traceutil/trace.go:171","msg":"trace[395974151] transaction","detail":"{read_only:false; number_of_response:1; response_revision:5450; }","duration":"381.063859ms","start":"2026-05-30T16:58:03.472936Z","end":"2026-05-30T16:58:03.853999Z","steps":["trace[395974151] 'process raft request' (duration: 380.746793ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:58:03.854132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T16:58:03.469997Z","time spent":"384.028346ms","remote":"127.0.0.1:41844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1702,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T16:58:03.853869Z","caller":"traceutil/trace.go:171","msg":"trace[796576592] transaction","detail":"{read_only:false; response_revision:5451; number_of_response:1; }","duration":"238.236338ms","start":"2026-05-30T16:58:03.615614Z","end":"2026-05-30T16:58:03.85385Z","steps":["trace[796576592] 'process raft request' (duration: 238.155337ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:58:03.854306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T16:58:03.472927Z","time spent":"381.220022ms","remote":"127.0.0.1:41844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":103,"response count":0,"response size":1750,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T16:58:03.854375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.730686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a395d38f-ab95-41ad-9f10-3cfe05c90ea1\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-30T16:58:03.854529Z","caller":"traceutil/trace.go:171","msg":"trace[2141565892] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a395d38f-ab95-41ad-9f10-3cfe05c90ea1; range_end:; response_count:1; response_revision:5451; }","duration":"386.92521ms","start":"2026-05-30T16:58:03.467569Z","end":"2026-05-30T16:58:03.854494Z","steps":["trace[2141565892] 'agreement among raft nodes before linearized reading' (duration: 386.467952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:58:03.854609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T16:58:03.467557Z","time spent":"387.035192ms","remote":"127.0.0.1:48390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a395d38f-ab95-41ad-9f10-3cfe05c90ea1\" "} {"level":"warn","ts":"2026-05-30T16:58:03.927855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.093383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T16:58:03.92788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.947068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-30T16:58:03.927941Z","caller":"traceutil/trace.go:171","msg":"trace[578084979] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5452; }","duration":"232.211305ms","start":"2026-05-30T16:58:03.695709Z","end":"2026-05-30T16:58:03.92792Z","steps":["trace[578084979] 'agreement among raft nodes before linearized reading' (duration: 231.987511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:58:03.927947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.718371ms","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-05-30T16:58:03.928034Z","caller":"traceutil/trace.go:171","msg":"trace[1870183024] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5452; }","duration":"269.853884ms","start":"2026-05-30T16:58:03.658162Z","end":"2026-05-30T16:58:03.928016Z","steps":["trace[1870183024] 'agreement among raft nodes before linearized reading' (duration: 269.589539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:58:03.928096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.043317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-30T16:58:03.927977Z","caller":"traceutil/trace.go:171","msg":"trace[201997483] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:5452; }","duration":"251.07596ms","start":"2026-05-30T16:58:03.676876Z","end":"2026-05-30T16:58:03.927952Z","steps":["trace[201997483] 'agreement among raft nodes before linearized reading' (duration: 250.817725ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:58:03.928144Z","caller":"traceutil/trace.go:171","msg":"trace[304115608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5452; }","duration":"270.126339ms","start":"2026-05-30T16:58:03.658006Z","end":"2026-05-30T16:58:03.928132Z","steps":["trace[304115608] 'agreement among raft nodes before linearized reading' (duration: 270.003657ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:58:03.928219Z","caller":"traceutil/trace.go:171","msg":"trace[1504423676] transaction","detail":"{read_only:false; response_revision:5452; number_of_response:1; }","duration":"248.222946ms","start":"2026-05-30T16:58:03.679967Z","end":"2026-05-30T16:58:03.92819Z","steps":["trace[1504423676] 'process raft request' (duration: 247.522903ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:58:03.996175Z","caller":"traceutil/trace.go:171","msg":"trace[1334784230] transaction","detail":"{read_only:false; response_revision:5453; number_of_response:1; }","duration":"136.775957ms","start":"2026-05-30T16:58:03.859358Z","end":"2026-05-30T16:58:03.996134Z","steps":["trace[1334784230] 'process raft request' (duration: 120.112193ms)","trace[1334784230] 'compare' (duration: 16.385138ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T16:58:03.996237Z","caller":"traceutil/trace.go:171","msg":"trace[1073869105] transaction","detail":"{read_only:false; response_revision:5454; number_of_response:1; }","duration":"127.353771ms","start":"2026-05-30T16:58:03.86885Z","end":"2026-05-30T16:58:03.996203Z","steps":["trace[1073869105] 'process raft request' (duration: 127.216387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T16:58:03.996389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.30619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-30T16:58:03.99651Z","caller":"traceutil/trace.go:171","msg":"trace[1956448947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5454; }","duration":"109.496013ms","start":"2026-05-30T16:58:03.886993Z","end":"2026-05-30T16:58:03.996489Z","steps":["trace[1956448947] 'agreement among raft nodes before linearized reading' (duration: 109.203397ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T16:59:10.701613Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3013} {"level":"info","ts":"2026-05-30T16:59:10.783372Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3013,"took":"80.297459ms","hash":3150784129,"current-db-size-bytes":58597376,"current-db-size":"59 MB","current-db-size-in-use-bytes":31633408,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-30T16:59:10.783432Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3150784129,"revision":3013,"compact-revision":-1} {"level":"info","ts":"2026-05-30T17:04:10.706918Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6089} {"level":"info","ts":"2026-05-30T17:04:10.806515Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6089,"took":"98.214913ms","hash":2307532304,"current-db-size-bytes":58597376,"current-db-size":"59 MB","current-db-size-in-use-bytes":21778432,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-05-30T17:04:10.806596Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2307532304,"revision":6089,"compact-revision":3013} {"level":"warn","ts":"2026-05-30T17:04:17.858621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.837801ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T17:04:17.858746Z","caller":"traceutil/trace.go:171","msg":"trace[1572429038] linearizableReadLoop","detail":"{readStateIndex:8637; appliedIndex:8636; }","duration":"324.860291ms","start":"2026-05-30T17:04:17.533851Z","end":"2026-05-30T17:04:17.858711Z","steps":["trace[1572429038] 'read index received' (duration: 135.800266ms)","trace[1572429038] 'applied index is now lower than readState.Index' (duration: 189.058495ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T17:04:17.858988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.439894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T17:04:17.859004Z","caller":"traceutil/trace.go:171","msg":"trace[1293929100] transaction","detail":"{read_only:false; response_revision:8346; number_of_response:1; }","duration":"333.597844ms","start":"2026-05-30T17:04:17.525383Z","end":"2026-05-30T17:04:17.85898Z","steps":["trace[1293929100] 'process raft request' (duration: 144.318335ms)","trace[1293929100] 'compare' (duration: 188.689499ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T17:04:17.859021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.152307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-05-30T17:04:17.859063Z","caller":"traceutil/trace.go:171","msg":"trace[1928734106] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:8346; }","duration":"291.563077ms","start":"2026-05-30T17:04:17.567483Z","end":"2026-05-30T17:04:17.859046Z","steps":["trace[1928734106] 'agreement among raft nodes before linearized reading' (duration: 291.405314ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T17:04:17.859119Z","caller":"traceutil/trace.go:171","msg":"trace[1208360762] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:8346; }","duration":"325.298ms","start":"2026-05-30T17:04:17.533805Z","end":"2026-05-30T17:04:17.859103Z","steps":["trace[1208360762] 'agreement among raft nodes before linearized reading' (duration: 325.033895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T17:04:17.859152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T17:04:17.533783Z","time spent":"325.36019ms","remote":"127.0.0.1:48372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" "} {"level":"warn","ts":"2026-05-30T17:04:17.859146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T17:04:17.525355Z","time spent":"333.704517ms","remote":"127.0.0.1:48468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T17:04:17.859228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.085554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T17:04:17.859281Z","caller":"traceutil/trace.go:171","msg":"trace[1939472813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8346; }","duration":"166.162535ms","start":"2026-05-30T17:04:17.693103Z","end":"2026-05-30T17:04:17.859265Z","steps":["trace[1939472813] 'agreement among raft nodes before linearized reading' (duration: 166.074273ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T17:04:28.480584Z","caller":"traceutil/trace.go:171","msg":"trace[1734756071] linearizableReadLoop","detail":"{readStateIndex:8695; appliedIndex:8694; }","duration":"168.111512ms","start":"2026-05-30T17:04:28.312451Z","end":"2026-05-30T17:04:28.480563Z","steps":["trace[1734756071] 'read index received' (duration: 168.02978ms)","trace[1734756071] 'applied index is now lower than readState.Index' (duration: 80.472µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T17:04:28.480664Z","caller":"traceutil/trace.go:171","msg":"trace[1862288758] transaction","detail":"{read_only:false; response_revision:8402; number_of_response:1; }","duration":"197.030895ms","start":"2026-05-30T17:04:28.283609Z","end":"2026-05-30T17:04:28.48064Z","steps":["trace[1862288758] 'process raft request' (duration: 196.809991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T17:04:28.48074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.266834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T17:04:28.480775Z","caller":"traceutil/trace.go:171","msg":"trace[207029578] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:8402; }","duration":"168.355206ms","start":"2026-05-30T17:04:28.312409Z","end":"2026-05-30T17:04:28.480764Z","steps":["trace[207029578] 'agreement among raft nodes before linearized reading' (duration: 168.249874ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T17:05:12.22665Z","caller":"traceutil/trace.go:171","msg":"trace[944162055] transaction","detail":"{read_only:false; response_revision:8732; number_of_response:1; }","duration":"106.449405ms","start":"2026-05-30T17:05:12.120176Z","end":"2026-05-30T17:05:12.226626Z","steps":["trace[944162055] 'process raft request' (duration: 90.777981ms)","trace[944162055] 'compare' (duration: 15.188194ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T17:05:13.114324Z","caller":"traceutil/trace.go:171","msg":"trace[1673085530] linearizableReadLoop","detail":"{readStateIndex:9048; appliedIndex:9047; }","duration":"152.050501ms","start":"2026-05-30T17:05:12.96224Z","end":"2026-05-30T17:05:13.114291Z","steps":["trace[1673085530] 'read index received' (duration: 65.882646ms)","trace[1673085530] 'applied index is now lower than readState.Index' (duration: 86.165995ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T17:05:13.114352Z","caller":"traceutil/trace.go:171","msg":"trace[689762184] transaction","detail":"{read_only:false; response_revision:8740; number_of_response:1; }","duration":"184.53414ms","start":"2026-05-30T17:05:12.929767Z","end":"2026-05-30T17:05:13.114301Z","steps":["trace[689762184] 'process raft request' (duration: 98.380615ms)","trace[689762184] 'compare' (duration: 85.852109ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T17:05:13.114643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.375938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-w7989\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-30T17:05:13.114699Z","caller":"traceutil/trace.go:171","msg":"trace[1311298438] range","detail":"{range_begin:/registry/pods/openstack/keepalived-w7989; range_end:; response_count:1; response_revision:8740; }","duration":"152.503541ms","start":"2026-05-30T17:05:12.962181Z","end":"2026-05-30T17:05:13.114685Z","steps":["trace[1311298438] 'agreement among raft nodes before linearized reading' (duration: 152.201525ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T17:05:13.142172Z","caller":"traceutil/trace.go:171","msg":"trace[844692227] transaction","detail":"{read_only:false; response_revision:8741; number_of_response:1; }","duration":"127.924208ms","start":"2026-05-30T17:05:13.014227Z","end":"2026-05-30T17:05:13.142151Z","steps":["trace[844692227] 'process raft request' (duration: 127.48831ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T17:05:13.678279Z","caller":"traceutil/trace.go:171","msg":"trace[514731278] transaction","detail":"{read_only:false; response_revision:8747; number_of_response:1; }","duration":"141.001184ms","start":"2026-05-30T17:05:13.537257Z","end":"2026-05-30T17:05:13.678258Z","steps":["trace[514731278] 'process raft request' (duration: 101.903551ms)","trace[514731278] 'compare' (duration: 38.779647ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T17:05:13.678373Z","caller":"traceutil/trace.go:171","msg":"trace[578798167] transaction","detail":"{read_only:false; response_revision:8748; number_of_response:1; }","duration":"125.490573ms","start":"2026-05-30T17:05:13.552863Z","end":"2026-05-30T17:05:13.678354Z","steps":["trace[578798167] 'process raft request' (duration: 125.35292ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T17:05:25.542072Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023da.wal"} {"level":"info","ts":"2026-05-30T17:06:27.019999Z","caller":"traceutil/trace.go:171","msg":"trace[1261762160] transaction","detail":"{read_only:false; response_revision:9474; number_of_response:1; }","duration":"107.85231ms","start":"2026-05-30T17:06:26.912117Z","end":"2026-05-30T17:06:27.019969Z","steps":["trace[1261762160] 'process raft request' (duration: 107.698436ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T17:06:52.738693Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3cec112083e8d3ff","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-30T17:06:52.744237Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-30T17:06:52.744349Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-30T17:09:10.713262Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8178} {"level":"info","ts":"2026-05-30T17:09:10.778002Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8178,"took":"61.921152ms","hash":3267690980,"current-db-size-bytes":58597376,"current-db-size":"59 MB","current-db-size-in-use-bytes":28332032,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-30T17:09:10.778088Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3267690980,"revision":8178,"compact-revision":6089} {"level":"info","ts":"2026-05-30T17:11:59.280898Z","caller":"traceutil/trace.go:171","msg":"trace[1461949823] transaction","detail":"{read_only:false; response_revision:11871; number_of_response:1; }","duration":"159.391189ms","start":"2026-05-30T17:11:59.121479Z","end":"2026-05-30T17:11:59.28087Z","steps":["trace[1461949823] 'process raft request' (duration: 159.198775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T17:12:00.587689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.085178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/cinder-backup-6d84c8f9cb-qlrt8.18b467141b751cfd\" ","response":"range_response_count:1 size:811"} {"level":"info","ts":"2026-05-30T17:12:00.588018Z","caller":"traceutil/trace.go:171","msg":"trace[1152905029] range","detail":"{range_begin:/registry/events/openstack/cinder-backup-6d84c8f9cb-qlrt8.18b467141b751cfd; range_end:; response_count:1; response_revision:11875; }","duration":"107.470115ms","start":"2026-05-30T17:12:00.480529Z","end":"2026-05-30T17:12:00.587999Z","steps":["trace[1152905029] 'range keys from in-memory index tree' (duration: 106.913575ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T17:14:10.718806Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10536} {"level":"info","ts":"2026-05-30T17:14:10.796856Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10536,"took":"75.559283ms","hash":1410749636,"current-db-size-bytes":58597376,"current-db-size":"59 MB","current-db-size-in-use-bytes":26529792,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-30T17:14:10.796913Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1410749636,"revision":10536,"compact-revision":8178} {"level":"info","ts":"2026-05-30T17:17:45.312891Z","caller":"traceutil/trace.go:171","msg":"trace[13535537] transaction","detail":"{read_only:false; response_revision:14201; number_of_response:1; }","duration":"150.930151ms","start":"2026-05-30T17:17:45.161909Z","end":"2026-05-30T17:17:45.312839Z","steps":["trace[13535537] 'process raft request' (duration: 149.76876ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T17:18:43.46194Z","caller":"traceutil/trace.go:171","msg":"trace[1077119294] transaction","detail":"{read_only:false; response_revision:14666; number_of_response:1; }","duration":"123.110804ms","start":"2026-05-30T17:18:43.338803Z","end":"2026-05-30T17:18:43.461914Z","steps":["trace[1077119294] 'process raft request' (duration: 122.93711ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T17:18:43.529181Z","caller":"traceutil/trace.go:171","msg":"trace[272511813] linearizableReadLoop","detail":"{readStateIndex:15170; appliedIndex:15168; }","duration":"189.49321ms","start":"2026-05-30T17:18:43.339672Z","end":"2026-05-30T17:18:43.529166Z","steps":["trace[272511813] 'read index received' (duration: 122.096605ms)","trace[272511813] 'applied index is now lower than readState.Index' (duration: 67.395745ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T17:18:43.529267Z","caller":"traceutil/trace.go:171","msg":"trace[735249658] transaction","detail":"{read_only:false; response_revision:14667; number_of_response:1; }","duration":"190.320095ms","start":"2026-05-30T17:18:43.33894Z","end":"2026-05-30T17:18:43.529261Z","steps":["trace[735249658] 'process raft request' (duration: 190.130991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T17:18:43.529582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.880837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/ovn-controller-default-dd4rh\" ","response":"range_response_count:1 size:14151"} {"level":"info","ts":"2026-05-30T17:18:43.529651Z","caller":"traceutil/trace.go:171","msg":"trace[1336120618] range","detail":"{range_begin:/registry/pods/openstack/ovn-controller-default-dd4rh; range_end:; response_count:1; response_revision:14667; }","duration":"189.976549ms","start":"2026-05-30T17:18:43.339656Z","end":"2026-05-30T17:18:43.529633Z","steps":["trace[1336120618] 'agreement among raft nodes before linearized reading' (duration: 189.752384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T17:18:43.529661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.875937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/ovn-northd-fc978744-tb7dm\" ","response":"range_response_count:1 size:7032"} {"level":"warn","ts":"2026-05-30T17:18:43.529824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.35291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T17:18:43.529823Z","caller":"traceutil/trace.go:171","msg":"trace[252173668] range","detail":"{range_begin:/registry/pods/openstack/ovn-northd-fc978744-tb7dm; range_end:; response_count:1; response_revision:14667; }","duration":"190.06191ms","start":"2026-05-30T17:18:43.339702Z","end":"2026-05-30T17:18:43.529764Z","steps":["trace[252173668] 'agreement among raft nodes before linearized reading' (duration: 189.610692ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T17:18:43.52989Z","caller":"traceutil/trace.go:171","msg":"trace[1399097042] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:14667; }","duration":"187.450921ms","start":"2026-05-30T17:18:43.342417Z","end":"2026-05-30T17:18:43.529868Z","steps":["trace[1399097042] 'agreement among raft nodes before linearized reading' (duration: 187.344569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T17:18:43.530119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.603574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2224963"} {"level":"info","ts":"2026-05-30T17:18:43.530148Z","caller":"traceutil/trace.go:171","msg":"trace[76350972] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:14667; }","duration":"187.665895ms","start":"2026-05-30T17:18:43.342476Z","end":"2026-05-30T17:18:43.530141Z","steps":["trace[76350972] 'agreement among raft nodes before linearized reading' (duration: 187.017273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T17:18:43.530757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.062788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:90 size:990615"} {"level":"info","ts":"2026-05-30T17:18:43.53087Z","caller":"traceutil/trace.go:171","msg":"trace[632014281] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:90; response_revision:14667; }","duration":"111.206811ms","start":"2026-05-30T17:18:43.419638Z","end":"2026-05-30T17:18:43.530845Z","steps":["trace[632014281] 'agreement among raft nodes before linearized reading' (duration: 109.908357ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T17:19:10.725512Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12781} {"level":"info","ts":"2026-05-30T17:19:10.804365Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12781,"took":"75.55621ms","hash":951729241,"current-db-size-bytes":58597376,"current-db-size":"59 MB","current-db-size-in-use-bytes":29417472,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-30T17:19:10.804419Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":951729241,"revision":12781,"compact-revision":10536} {"level":"warn","ts":"2026-05-30T17:20:09.33542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.588807ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-30T17:20:10.1627Z","caller":"traceutil/trace.go:171","msg":"trace[1983281841] linearizableReadLoop","detail":"{readStateIndex:16239; appliedIndex:16238; }","duration":"168.034269ms","start":"2026-05-30T17:20:09.994635Z","end":"2026-05-30T17:20:10.162669Z","steps":["trace[1983281841] 'read index received' (duration: 167.704893ms)","trace[1983281841] 'applied index is now lower than readState.Index' (duration: 327.836µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T17:20:10.163016Z","caller":"traceutil/trace.go:171","msg":"trace[1942073132] transaction","detail":"{read_only:false; response_revision:15704; number_of_response:1; }","duration":"179.272729ms","start":"2026-05-30T17:20:09.983701Z","end":"2026-05-30T17:20:10.162974Z","steps":["trace[1942073132] 'process raft request' (duration: 178.76102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T17:20:10.163256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.588699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-l7cvf\" ","response":"range_response_count:1 size:22093"} {"level":"info","ts":"2026-05-30T17:20:10.163377Z","caller":"traceutil/trace.go:171","msg":"trace[1481115756] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-l7cvf; range_end:; response_count:1; response_revision:15704; }","duration":"168.731812ms","start":"2026-05-30T17:20:09.994586Z","end":"2026-05-30T17:20:10.163318Z","steps":["trace[1481115756] 'agreement among raft nodes before linearized reading' (duration: 168.314234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T17:20:10.165077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.072754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-w7989\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-30T17:20:10.166092Z","caller":"traceutil/trace.go:171","msg":"trace[1574140535] range","detail":"{range_begin:/registry/pods/openstack/keepalived-w7989; range_end:; response_count:1; response_revision:15704; }","duration":"165.164855ms","start":"2026-05-30T17:20:10.000904Z","end":"2026-05-30T17:20:10.166069Z","steps":["trace[1574140535] 'agreement among raft nodes before linearized reading' (duration: 163.950142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T17:20:10.166546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.673665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-l7cvf\" ","response":"range_response_count:1 size:22093"} {"level":"info","ts":"2026-05-30T17:20:10.166643Z","caller":"traceutil/trace.go:171","msg":"trace[1949383495] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-l7cvf; range_end:; response_count:1; response_revision:15704; }","duration":"165.803458ms","start":"2026-05-30T17:20:10.000824Z","end":"2026-05-30T17:20:10.166628Z","steps":["trace[1949383495] 'agreement among raft nodes before linearized reading' (duration: 162.554426ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T17:20:10.379907Z","caller":"traceutil/trace.go:171","msg":"trace[51892326] linearizableReadLoop","detail":"{readStateIndex:16240; appliedIndex:16239; }","duration":"207.760156ms","start":"2026-05-30T17:20:10.172121Z","end":"2026-05-30T17:20:10.379881Z","steps":["trace[51892326] 'read index received' (duration: 176.301654ms)","trace[51892326] 'applied index is now lower than readState.Index' (duration: 31.457162ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T17:20:10.38013Z","caller":"traceutil/trace.go:171","msg":"trace[1178877108] transaction","detail":"{read_only:false; response_revision:15705; number_of_response:1; }","duration":"209.873935ms","start":"2026-05-30T17:20:10.170162Z","end":"2026-05-30T17:20:10.380036Z","steps":["trace[1178877108] 'process raft request' (duration: 178.316602ms)","trace[1178877108] 'compare' (duration: 30.986543ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T17:20:10.380801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.434212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769906"} {"level":"info","ts":"2026-05-30T17:20:10.380843Z","caller":"traceutil/trace.go:171","msg":"trace[196606572] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15705; }","duration":"205.500363ms","start":"2026-05-30T17:20:10.175334Z","end":"2026-05-30T17:20:10.380834Z","steps":["trace[196606572] 'agreement among raft nodes before linearized reading' (duration: 204.78534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T17:20:10.383446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.291822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769906"} {"level":"warn","ts":"2026-05-30T17:20:10.38369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.322096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769906"} {"level":"info","ts":"2026-05-30T17:20:10.383743Z","caller":"traceutil/trace.go:171","msg":"trace[1718721934] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15705; }","duration":"199.46449ms","start":"2026-05-30T17:20:10.184266Z","end":"2026-05-30T17:20:10.38373Z","steps":["trace[1718721934] 'agreement among raft nodes before linearized reading' (duration: 197.227187ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T17:20:10.383708Z","caller":"traceutil/trace.go:171","msg":"trace[628098081] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15705; }","duration":"211.578597ms","start":"2026-05-30T17:20:10.172104Z","end":"2026-05-30T17:20:10.383682Z","steps":["trace[628098081] 'agreement among raft nodes before linearized reading' (duration: 208.829855ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T17:20:41.232198Z","caller":"traceutil/trace.go:171","msg":"trace[1153744570] transaction","detail":"{read_only:false; response_revision:15862; number_of_response:1; }","duration":"133.779055ms","start":"2026-05-30T17:20:41.09838Z","end":"2026-05-30T17:20:41.232159Z","steps":["trace[1153744570] 'process raft request' (duration: 133.559331ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T17:24:10.731502Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15058} {"level":"info","ts":"2026-05-30T17:24:10.825394Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15058,"took":"90.900117ms","hash":2828420039,"current-db-size-bytes":58597376,"current-db-size":"59 MB","current-db-size-in-use-bytes":31035392,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-30T17:24:10.825467Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2828420039,"revision":15058,"compact-revision":12781} {"level":"warn","ts":"2026-05-30T17:26:09.228517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.100625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-30T17:26:09.228624Z","caller":"traceutil/trace.go:171","msg":"trace[1142769365] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:18315; }","duration":"121.272788ms","start":"2026-05-30T17:26:09.107329Z","end":"2026-05-30T17:26:09.228602Z","steps":["trace[1142769365] 'count revisions from in-memory index tree' (duration: 121.023503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T17:26:09.228817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.166408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-2ff82\" ","response":"range_response_count:1 size:20849"} {"level":"info","ts":"2026-05-30T17:26:09.228909Z","caller":"traceutil/trace.go:171","msg":"trace[114043602] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-2ff82; range_end:; response_count:1; response_revision:18315; }","duration":"226.2967ms","start":"2026-05-30T17:26:09.002592Z","end":"2026-05-30T17:26:09.228888Z","steps":["trace[114043602] 'range keys from in-memory index tree' (duration: 226.010435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T17:26:09.49857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.125185ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-30T17:26:09.498662Z","caller":"traceutil/trace.go:171","msg":"trace[576985269] linearizableReadLoop","detail":"{readStateIndex:18937; appliedIndex:18936; }","duration":"261.201735ms","start":"2026-05-30T17:26:09.237446Z","end":"2026-05-30T17:26:09.498648Z","steps":["trace[576985269] 'read index received' (duration: 83.742µs)","trace[576985269] 'applied index is now lower than readState.Index' (duration: 261.116783ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T17:26:09.498911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.44867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-30T17:26:09.498979Z","caller":"traceutil/trace.go:171","msg":"trace[484733669] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:18315; }","duration":"261.570632ms","start":"2026-05-30T17:26:09.237391Z","end":"2026-05-30T17:26:09.498962Z","steps":["trace[484733669] 'agreement among raft nodes before linearized reading' (duration: 261.43849ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T17:26:09.502335Z","caller":"traceutil/trace.go:171","msg":"trace[1857228850] transaction","detail":"{read_only:false; response_revision:18316; number_of_response:1; }","duration":"258.386123ms","start":"2026-05-30T17:26:09.243924Z","end":"2026-05-30T17:26:09.50231Z","steps":["trace[1857228850] 'process raft request' (duration: 258.2323ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T17:26:09.764735Z","caller":"traceutil/trace.go:171","msg":"trace[199129656] linearizableReadLoop","detail":"{readStateIndex:18939; appliedIndex:18937; }","duration":"266.030696ms","start":"2026-05-30T17:26:09.498681Z","end":"2026-05-30T17:26:09.764711Z","steps":["trace[199129656] 'read index received' (duration: 3.452265ms)","trace[199129656] 'applied index is now lower than readState.Index' (duration: 262.577211ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T17:26:09.765026Z","caller":"traceutil/trace.go:171","msg":"trace[1630204859] transaction","detail":"{read_only:false; response_revision:18317; number_of_response:1; }","duration":"352.798725ms","start":"2026-05-30T17:26:09.412034Z","end":"2026-05-30T17:26:09.764833Z","steps":["trace[1630204859] 'process raft request' (duration: 352.52863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T17:26:09.765058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.146366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-30T17:26:09.765143Z","caller":"traceutil/trace.go:171","msg":"trace[1225222782] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18317; }","duration":"522.270628ms","start":"2026-05-30T17:26:09.242853Z","end":"2026-05-30T17:26:09.765124Z","steps":["trace[1225222782] 'agreement among raft nodes before linearized reading' (duration: 522.015793ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T17:26:09.765109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.182095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-30T17:26:09.765195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T17:26:09.242842Z","time spent":"522.34417ms","remote":"127.0.0.1:48390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-30T17:26:09.765313Z","caller":"traceutil/trace.go:171","msg":"trace[977911190] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:18317; }","duration":"515.42598ms","start":"2026-05-30T17:26:09.249828Z","end":"2026-05-30T17:26:09.765254Z","steps":["trace[977911190] 'agreement among raft nodes before linearized reading' (duration: 515.128884ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T17:26:09.765428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T17:26:09.249819Z","time spent":"515.592023ms","remote":"127.0.0.1:42842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":1,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true "} {"level":"warn","ts":"2026-05-30T17:26:09.765446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T17:26:09.412006Z","time spent":"353.097481ms","remote":"127.0.0.1:48468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T17:26:09.765126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.244838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-4mb8z\" ","response":"range_response_count:1 size:9789"} {"level":"info","ts":"2026-05-30T17:26:09.765768Z","caller":"traceutil/trace.go:171","msg":"trace[1836457671] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-4mb8z; range_end:; response_count:1; response_revision:18317; }","duration":"408.036402ms","start":"2026-05-30T17:26:09.357645Z","end":"2026-05-30T17:26:09.765681Z","steps":["trace[1836457671] 'agreement among raft nodes before linearized reading' (duration: 407.100665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T17:26:09.765958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T17:26:09.357623Z","time spent":"408.194855ms","remote":"127.0.0.1:48390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9811,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-4mb8z\" "} {"level":"warn","ts":"2026-05-30T17:26:09.765023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.986321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-30T17:26:09.766264Z","caller":"traceutil/trace.go:171","msg":"trace[1141928429] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:18317; }","duration":"426.159884ms","start":"2026-05-30T17:26:09.33998Z","end":"2026-05-30T17:26:09.76614Z","steps":["trace[1141928429] 'agreement among raft nodes before linearized reading' (duration: 424.848449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T17:26:09.766346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T17:26:09.339964Z","time spent":"426.363998ms","remote":"127.0.0.1:48518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":137,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-05-30T17:29:05.762425Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3cec112083e8d3ff","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-30T17:29:05.767408Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-30T17:29:05.767552Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-30T17:29:10.73853Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17503} {"level":"info","ts":"2026-05-30T17:29:10.835545Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17503,"took":"94.355022ms","hash":480390728,"current-db-size-bytes":58597376,"current-db-size":"59 MB","current-db-size-in-use-bytes":29741056,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-30T17:29:10.835609Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":480390728,"revision":17503,"compact-revision":15058} {"level":"info","ts":"2026-05-30T17:34:10.744064Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19361} {"level":"info","ts":"2026-05-30T17:34:10.860063Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19361,"took":"113.721609ms","hash":2044251085,"current-db-size-bytes":58597376,"current-db-size":"59 MB","current-db-size-in-use-bytes":24084480,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-30T17:34:10.860125Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2044251085,"revision":19361,"compact-revision":17503}