{"level":"warn","ts":"2026-05-27T06:04:19.84747Z","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-27T06:04:19.847588Z","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-27T06:04:19.847674Z","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-27T06:04:19.847693Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-05-27T06:04:19.847714Z","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-27T06:04:19.848357Z","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-27T06:04:19.848436Z","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-27T06:04:19.851129Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.474108ms"} {"level":"info","ts":"2026-05-27T06:04:19.857742Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-05-27T06:04:19.858002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-05-27T06:04:19.858046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-05-27T06:04:19.858129Z","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-27T06:04:19.85814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-05-27T06:04:19.858229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-05-27T06:04:19.867686Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-27T06:04:19.870726Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-27T06:04:19.872343Z","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-27T06:04:19.87483Z","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-27T06:04:19.874979Z","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-27T06:04:19.875099Z","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-27T06:04:19.875297Z","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-27T06:04:19.875313Z","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-27T06:04:19.876087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-05-27T06:04:19.876313Z","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-27T06:04:19.87884Z","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-27T06:04:19.879176Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-05-27T06:04:19.879254Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-05-27T06:04:19.879472Z","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-27T06:04:19.879552Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-27T06:04:20.259124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-05-27T06:04:20.259295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-05-27T06:04:20.259384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-05-27T06:04:20.259449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-05-27T06:04:20.25949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-05-27T06:04:20.259569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-05-27T06:04:20.259607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-05-27T06:04:20.260669Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T06:04:20.261854Z","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-27T06:04:20.262187Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T06:04:20.26222Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T06:04:20.262269Z","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-27T06:04:20.262427Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T06:04:20.262416Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-27T06:04:20.26247Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T06:04:20.262487Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-27T06:04:20.266797Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"info","ts":"2026-05-27T06:04:20.266899Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-27T06:05:02.803688Z","caller":"traceutil/trace.go:171","msg":"trace[2104906592] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"155.677158ms","start":"2026-05-27T06:05:02.647976Z","end":"2026-05-27T06:05:02.803653Z","steps":["trace[2104906592] 'process raft request' (duration: 154.738182ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:05:02.973277Z","caller":"traceutil/trace.go:171","msg":"trace[1279696176] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"102.968526ms","start":"2026-05-27T06:05:02.870283Z","end":"2026-05-27T06:05:02.973252Z","steps":["trace[1279696176] 'process raft request' (duration: 102.863243ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:05:02.973444Z","caller":"traceutil/trace.go:171","msg":"trace[569986093] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"166.12731ms","start":"2026-05-27T06:05:02.807232Z","end":"2026-05-27T06:05:02.973359Z","steps":["trace[569986093] 'process raft request' (duration: 92.584186ms)","trace[569986093] 'compare' (duration: 73.171243ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:05:03.471086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.447697ms","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-27T06:05:03.471195Z","caller":"traceutil/trace.go:171","msg":"trace[95819204] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:633; }","duration":"225.579181ms","start":"2026-05-27T06:05:03.245597Z","end":"2026-05-27T06:05:03.471176Z","steps":["trace[95819204] 'range keys from in-memory index tree' (duration: 225.302813ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:05:03.499712Z","caller":"traceutil/trace.go:171","msg":"trace[112287994] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"104.532679ms","start":"2026-05-27T06:05:03.39513Z","end":"2026-05-27T06:05:03.499663Z","steps":["trace[112287994] 'process raft request' (duration: 102.42736ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:05:35.700896Z","caller":"traceutil/trace.go:171","msg":"trace[136808363] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"108.426748ms","start":"2026-05-27T06:05:35.592387Z","end":"2026-05-27T06:05:35.700813Z","steps":["trace[136808363] 'process raft request' (duration: 108.174511ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:05:35.701038Z","caller":"traceutil/trace.go:171","msg":"trace[2027864361] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"108.304495ms","start":"2026-05-27T06:05:35.592679Z","end":"2026-05-27T06:05:35.700984Z","steps":["trace[2027864361] 'process raft request' (duration: 107.950065ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:05:35.700961Z","caller":"traceutil/trace.go:171","msg":"trace[1789402126] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"107.928474ms","start":"2026-05-27T06:05:35.593003Z","end":"2026-05-27T06:05:35.700931Z","steps":["trace[1789402126] 'process raft request' (duration: 107.743239ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:05:35.70132Z","caller":"traceutil/trace.go:171","msg":"trace[1432072823] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"107.123172ms","start":"2026-05-27T06:05:35.594184Z","end":"2026-05-27T06:05:35.701307Z","steps":["trace[1432072823] 'process raft request' (duration: 106.955627ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:05:35.701493Z","caller":"traceutil/trace.go:171","msg":"trace[1958181203] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"107.730059ms","start":"2026-05-27T06:05:35.59372Z","end":"2026-05-27T06:05:35.70145Z","steps":["trace[1958181203] 'process raft request' (duration: 107.169543ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:05:35.701973Z","caller":"traceutil/trace.go:171","msg":"trace[1475587791] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"111.306019ms","start":"2026-05-27T06:05:35.590616Z","end":"2026-05-27T06:05:35.701922Z","steps":["trace[1475587791] 'process raft request' (duration: 81.62995ms)","trace[1475587791] 'compare' (duration: 28.196088ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:05:35.956001Z","caller":"traceutil/trace.go:171","msg":"trace[965207391] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"106.062391ms","start":"2026-05-27T06:05:35.849907Z","end":"2026-05-27T06:05:35.955969Z","steps":["trace[965207391] 'process raft request' (duration: 94.93166ms)","trace[965207391] 'compare' (duration: 10.869724ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:05:35.956026Z","caller":"traceutil/trace.go:171","msg":"trace[442371641] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"106.00997ms","start":"2026-05-27T06:05:35.849968Z","end":"2026-05-27T06:05:35.955978Z","steps":["trace[442371641] 'process raft request' (duration: 105.915648ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:05:35.956428Z","caller":"traceutil/trace.go:171","msg":"trace[683019705] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"102.44666ms","start":"2026-05-27T06:05:35.853973Z","end":"2026-05-27T06:05:35.95642Z","steps":["trace[683019705] 'process raft request' (duration: 102.268025ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:05:35.956536Z","caller":"traceutil/trace.go:171","msg":"trace[1471195190] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"106.476172ms","start":"2026-05-27T06:05:35.850013Z","end":"2026-05-27T06:05:35.956489Z","steps":["trace[1471195190] 'process raft request' (duration: 105.911587ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:06:35.794388Z","caller":"traceutil/trace.go:171","msg":"trace[110743390] linearizableReadLoop","detail":"{readStateIndex:1937; appliedIndex:1936; }","duration":"186.941902ms","start":"2026-05-27T06:06:35.607416Z","end":"2026-05-27T06:06:35.794358Z","steps":["trace[110743390] 'read index received' (duration: 171.150652ms)","trace[110743390] 'applied index is now lower than readState.Index' (duration: 15.78994ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:06:35.79483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.401095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-27T06:06:35.794874Z","caller":"traceutil/trace.go:171","msg":"trace[655004618] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"198.185535ms","start":"2026-05-27T06:06:35.59664Z","end":"2026-05-27T06:06:35.794826Z","steps":["trace[655004618] 'process raft request' (duration: 181.950573ms)","trace[655004618] 'compare' (duration: 15.622565ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:06:35.794938Z","caller":"traceutil/trace.go:171","msg":"trace[1713526660] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1867; }","duration":"187.663812ms","start":"2026-05-27T06:06:35.607247Z","end":"2026-05-27T06:06:35.794911Z","steps":["trace[1713526660] 'agreement among raft nodes before linearized reading' (duration: 187.361204ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:06:35.794975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.737132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-27T06:06:35.795041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.626952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-27T06:06:35.795129Z","caller":"traceutil/trace.go:171","msg":"trace[1770339089] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1867; }","duration":"159.750136ms","start":"2026-05-27T06:06:35.635353Z","end":"2026-05-27T06:06:35.795103Z","steps":["trace[1770339089] 'agreement among raft nodes before linearized reading' (duration: 159.54858ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:06:35.795031Z","caller":"traceutil/trace.go:171","msg":"trace[892881210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1867; }","duration":"174.842675ms","start":"2026-05-27T06:06:35.620173Z","end":"2026-05-27T06:06:35.795016Z","steps":["trace[892881210] 'agreement among raft nodes before linearized reading' (duration: 174.64784ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:07:20.23547Z","caller":"traceutil/trace.go:171","msg":"trace[1058144612] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"153.736787ms","start":"2026-05-27T06:07:20.0817Z","end":"2026-05-27T06:07:20.235437Z","steps":["trace[1058144612] 'process raft request' (duration: 130.276705ms)","trace[1058144612] 'compare' (duration: 23.00984ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:07:20.263841Z","caller":"traceutil/trace.go:171","msg":"trace[1876016801] linearizableReadLoop","detail":"{readStateIndex:2246; appliedIndex:2244; }","duration":"174.012072ms","start":"2026-05-27T06:07:20.089812Z","end":"2026-05-27T06:07:20.263824Z","steps":["trace[1876016801] 'read index received' (duration: 122.18118ms)","trace[1876016801] 'applied index is now lower than readState.Index' (duration: 51.829742ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:07:20.263946Z","caller":"traceutil/trace.go:171","msg":"trace[529631517] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"179.34727ms","start":"2026-05-27T06:07:20.084522Z","end":"2026-05-27T06:07:20.26387Z","steps":["trace[529631517] 'process raft request' (duration: 179.134024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:07:20.264059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.226128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T06:07:20.264141Z","caller":"traceutil/trace.go:171","msg":"trace[1108786453] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:2167; }","duration":"174.357961ms","start":"2026-05-27T06:07:20.089764Z","end":"2026-05-27T06:07:20.264122Z","steps":["trace[1108786453] 'agreement among raft nodes before linearized reading' (duration: 174.178046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:07:20.264299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.854373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-27T06:07:20.264401Z","caller":"traceutil/trace.go:171","msg":"trace[1249902404] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2167; }","duration":"121.040537ms","start":"2026-05-27T06:07:20.143335Z","end":"2026-05-27T06:07:20.264376Z","steps":["trace[1249902404] 'agreement among raft nodes before linearized reading' (duration: 120.685888ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:07:26.487675Z","caller":"traceutil/trace.go:171","msg":"trace[920470340] transaction","detail":"{read_only:false; response_revision:2199; number_of_response:1; }","duration":"137.887776ms","start":"2026-05-27T06:07:26.349746Z","end":"2026-05-27T06:07:26.487633Z","steps":["trace[920470340] 'process raft request' (duration: 126.15473ms)","trace[920470340] 'compare' (duration: 11.466839ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:07:26.93799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.222374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T06:07:26.938095Z","caller":"traceutil/trace.go:171","msg":"trace[173967657] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2201; }","duration":"166.385188ms","start":"2026-05-27T06:07:26.771687Z","end":"2026-05-27T06:07:26.938072Z","steps":["trace[173967657] 'range keys from in-memory index tree' (duration: 166.122551ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:08:52.928684Z","caller":"traceutil/trace.go:171","msg":"trace[537294541] linearizableReadLoop","detail":"{readStateIndex:2965; appliedIndex:2964; }","duration":"156.912666ms","start":"2026-05-27T06:08:52.771744Z","end":"2026-05-27T06:08:52.928657Z","steps":["trace[537294541] 'read index received' (duration: 123.378175ms)","trace[537294541] 'applied index is now lower than readState.Index' (duration: 33.533501ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:08:52.928866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.113721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T06:08:52.928984Z","caller":"traceutil/trace.go:171","msg":"trace[1552209398] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2857; }","duration":"157.283155ms","start":"2026-05-27T06:08:52.771679Z","end":"2026-05-27T06:08:52.928962Z","steps":["trace[1552209398] 'agreement among raft nodes before linearized reading' (duration: 157.126321ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:08:52.928913Z","caller":"traceutil/trace.go:171","msg":"trace[1351839493] transaction","detail":"{read_only:false; response_revision:2857; number_of_response:1; }","duration":"161.983456ms","start":"2026-05-27T06:08:52.766895Z","end":"2026-05-27T06:08:52.928878Z","steps":["trace[1351839493] 'process raft request' (duration: 128.198708ms)","trace[1351839493] 'compare' (duration: 33.455909ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:12:53.496967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.412092ms","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-27T06:12:53.497045Z","caller":"traceutil/trace.go:171","msg":"trace[1298388935] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:5048; }","duration":"224.522245ms","start":"2026-05-27T06:12:53.272506Z","end":"2026-05-27T06:12:53.497028Z","steps":["trace[1298388935] 'range keys from in-memory index tree' (duration: 224.247537ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:12:53.497217Z","caller":"traceutil/trace.go:171","msg":"trace[269254526] linearizableReadLoop","detail":"{readStateIndex:5238; appliedIndex:5237; }","duration":"147.806912ms","start":"2026-05-27T06:12:53.349378Z","end":"2026-05-27T06:12:53.497185Z","steps":["trace[269254526] 'read index received' (duration: 133.619041ms)","trace[269254526] 'applied index is now lower than readState.Index' (duration: 14.186011ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:12:53.497403Z","caller":"traceutil/trace.go:171","msg":"trace[473192633] transaction","detail":"{read_only:false; response_revision:5049; number_of_response:1; }","duration":"153.756816ms","start":"2026-05-27T06:12:53.34363Z","end":"2026-05-27T06:12:53.497387Z","steps":["trace[473192633] 'process raft request' (duration: 139.321118ms)","trace[473192633] 'compare' (duration: 14.076988ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:12:53.497784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.411288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a0ef7028-2ade-475c-88ce-b3976a5af172\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-05-27T06:12:53.497815Z","caller":"traceutil/trace.go:171","msg":"trace[1498416660] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a0ef7028-2ade-475c-88ce-b3976a5af172; range_end:; response_count:1; response_revision:5049; }","duration":"148.4735ms","start":"2026-05-27T06:12:53.349333Z","end":"2026-05-27T06:12:53.497806Z","steps":["trace[1498416660] 'agreement among raft nodes before linearized reading' (duration: 148.377698ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:12:53.899368Z","caller":"traceutil/trace.go:171","msg":"trace[1761320413] transaction","detail":"{read_only:false; response_revision:5054; number_of_response:1; }","duration":"159.38985ms","start":"2026-05-27T06:12:53.739954Z","end":"2026-05-27T06:12:53.899344Z","steps":["trace[1761320413] 'process raft request' (duration: 159.228816ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:12:55.61072Z","caller":"traceutil/trace.go:171","msg":"trace[63230697] transaction","detail":"{read_only:false; response_revision:5096; number_of_response:1; }","duration":"160.241084ms","start":"2026-05-27T06:12:55.450438Z","end":"2026-05-27T06:12:55.610679Z","steps":["trace[63230697] 'process raft request' (duration: 159.995437ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:12:55.617795Z","caller":"traceutil/trace.go:171","msg":"trace[530033425] transaction","detail":"{read_only:false; response_revision:5097; number_of_response:1; }","duration":"114.233327ms","start":"2026-05-27T06:12:55.503536Z","end":"2026-05-27T06:12:55.617769Z","steps":["trace[530033425] 'process raft request' (duration: 114.001161ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:12:55.618104Z","caller":"traceutil/trace.go:171","msg":"trace[969260834] transaction","detail":"{read_only:false; response_revision:5098; number_of_response:1; }","duration":"101.512316ms","start":"2026-05-27T06:12:55.516576Z","end":"2026-05-27T06:12:55.618088Z","steps":["trace[969260834] 'process raft request' (duration: 101.115975ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:12:55.618513Z","caller":"traceutil/trace.go:171","msg":"trace[1726123516] linearizableReadLoop","detail":"{readStateIndex:5288; appliedIndex:5286; }","duration":"110.938627ms","start":"2026-05-27T06:12:55.50756Z","end":"2026-05-27T06:12:55.618498Z","steps":["trace[1726123516] 'read index received' (duration: 103.06035ms)","trace[1726123516] 'applied index is now lower than readState.Index' (duration: 7.876826ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:12:55.618768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.106818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-27T06:12:55.618816Z","caller":"traceutil/trace.go:171","msg":"trace[2038350184] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5099; }","duration":"104.208271ms","start":"2026-05-27T06:12:55.514596Z","end":"2026-05-27T06:12:55.618805Z","steps":["trace[2038350184] 'agreement among raft nodes before linearized reading' (duration: 104.068907ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:12:55.619001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.399229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a0ef7028-2ade-475c-88ce-b3976a5af172\" ","response":"range_response_count:1 size:3907"} {"level":"info","ts":"2026-05-27T06:12:55.619142Z","caller":"traceutil/trace.go:171","msg":"trace[453028195] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a0ef7028-2ade-475c-88ce-b3976a5af172; range_end:; response_count:1; response_revision:5099; }","duration":"111.607354ms","start":"2026-05-27T06:12:55.507501Z","end":"2026-05-27T06:12:55.619108Z","steps":["trace[453028195] 'agreement among raft nodes before linearized reading' (duration: 111.193893ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:13:01.418582Z","caller":"traceutil/trace.go:171","msg":"trace[2112244934] transaction","detail":"{read_only:false; response_revision:5218; number_of_response:1; }","duration":"139.258806ms","start":"2026-05-27T06:13:01.279303Z","end":"2026-05-27T06:13:01.418562Z","steps":["trace[2112244934] 'process raft request' (duration: 139.204214ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:13:01.418621Z","caller":"traceutil/trace.go:171","msg":"trace[37832641] transaction","detail":"{read_only:false; response_revision:5217; number_of_response:1; }","duration":"141.698253ms","start":"2026-05-27T06:13:01.276886Z","end":"2026-05-27T06:13:01.418584Z","steps":["trace[37832641] 'process raft request' (duration: 115.603014ms)","trace[37832641] 'compare' (duration: 25.863093ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:13:07.245391Z","caller":"traceutil/trace.go:171","msg":"trace[494260837] transaction","detail":"{read_only:false; response_revision:5325; number_of_response:1; }","duration":"137.56041ms","start":"2026-05-27T06:13:07.10781Z","end":"2026-05-27T06:13:07.24537Z","steps":["trace[494260837] 'process raft request' (duration: 137.376595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:13:07.455594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.53991ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T06:13:07.455718Z","caller":"traceutil/trace.go:171","msg":"trace[633796480] transaction","detail":"{read_only:false; response_revision:5326; number_of_response:1; }","duration":"208.077552ms","start":"2026-05-27T06:13:07.247625Z","end":"2026-05-27T06:13:07.455703Z","steps":["trace[633796480] 'process raft request' (duration: 98.904885ms)","trace[633796480] 'compare' (duration: 108.366785ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:13:15.924271Z","caller":"traceutil/trace.go:171","msg":"trace[1428629253] transaction","detail":"{read_only:false; response_revision:5518; number_of_response:1; }","duration":"259.217291ms","start":"2026-05-27T06:13:15.665024Z","end":"2026-05-27T06:13:15.924242Z","steps":["trace[1428629253] 'process raft request' (duration: 258.995295ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:13:15.925333Z","caller":"traceutil/trace.go:171","msg":"trace[1878187958] transaction","detail":"{read_only:false; response_revision:5519; number_of_response:1; }","duration":"231.185418ms","start":"2026-05-27T06:13:15.694116Z","end":"2026-05-27T06:13:15.925302Z","steps":["trace[1878187958] 'process raft request' (duration: 231.064045ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:13:15.925578Z","caller":"traceutil/trace.go:171","msg":"trace[425349656] linearizableReadLoop","detail":"{readStateIndex:5722; appliedIndex:5720; }","duration":"226.240933ms","start":"2026-05-27T06:13:15.699268Z","end":"2026-05-27T06:13:15.925509Z","steps":["trace[425349656] 'read index received' (duration: 224.941837ms)","trace[425349656] 'applied index is now lower than readState.Index' (duration: 1.295746ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:13:15.925723Z","caller":"traceutil/trace.go:171","msg":"trace[1567279902] transaction","detail":"{read_only:false; response_revision:5520; number_of_response:1; }","duration":"213.518632ms","start":"2026-05-27T06:13:15.712182Z","end":"2026-05-27T06:13:15.925701Z","steps":["trace[1567279902] 'process raft request' (duration: 213.08369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:13:15.925904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.596202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T06:13:15.925962Z","caller":"traceutil/trace.go:171","msg":"trace[1962069278] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:5520; }","duration":"226.728966ms","start":"2026-05-27T06:13:15.699215Z","end":"2026-05-27T06:13:15.925944Z","steps":["trace[1962069278] 'agreement among raft nodes before linearized reading' (duration: 226.574751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:13:15.926106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.377151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-05-27T06:13:15.926131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.243648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T06:13:15.926236Z","caller":"traceutil/trace.go:171","msg":"trace[728395308] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5520; }","duration":"212.565786ms","start":"2026-05-27T06:13:15.713641Z","end":"2026-05-27T06:13:15.926207Z","steps":["trace[728395308] 'agreement among raft nodes before linearized reading' (duration: 212.245377ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:13:15.926301Z","caller":"traceutil/trace.go:171","msg":"trace[68356209] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5520; }","duration":"154.504796ms","start":"2026-05-27T06:13:15.771763Z","end":"2026-05-27T06:13:15.926268Z","steps":["trace[68356209] 'agreement among raft nodes before linearized reading' (duration: 154.203257ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:14:20.309938Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3009} {"level":"info","ts":"2026-05-27T06:14:20.531961Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3009,"took":"220.04589ms","hash":3054666520,"current-db-size-bytes":58961920,"current-db-size":"59 MB","current-db-size-in-use-bytes":31821824,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-27T06:14:20.53202Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3054666520,"revision":3009,"compact-revision":-1} {"level":"info","ts":"2026-05-27T06:19:20.365258Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6088} {"level":"info","ts":"2026-05-27T06:19:20.650983Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6088,"took":"283.156676ms","hash":2598221569,"current-db-size-bytes":58961920,"current-db-size":"59 MB","current-db-size-in-use-bytes":21848064,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-05-27T06:19:20.651092Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2598221569,"revision":6088,"compact-revision":3009} {"level":"warn","ts":"2026-05-27T06:19:28.520887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.857362ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T06:19:28.52135Z","caller":"traceutil/trace.go:171","msg":"trace[944847634] linearizableReadLoop","detail":"{readStateIndex:8647; appliedIndex:8646; }","duration":"518.537617ms","start":"2026-05-27T06:19:28.002784Z","end":"2026-05-27T06:19:28.521321Z","steps":["trace[944847634] 'read index received' (duration: 341.954765ms)","trace[944847634] 'applied index is now lower than readState.Index' (duration: 176.581662ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:19:28.521422Z","caller":"traceutil/trace.go:171","msg":"trace[283734338] transaction","detail":"{read_only:false; response_revision:8354; number_of_response:1; }","duration":"521.328044ms","start":"2026-05-27T06:19:28.000084Z","end":"2026-05-27T06:19:28.521412Z","steps":["trace[283734338] 'process raft request' (duration: 344.686041ms)","trace[283734338] 'compare' (duration: 175.711648ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:19:28.521471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:19:28.000057Z","time spent":"521.386955ms","remote":"127.0.0.1:50416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T06:19:28.521562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.739812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T06:19:28.521691Z","caller":"traceutil/trace.go:171","msg":"trace[579948353] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8354; }","duration":"518.908608ms","start":"2026-05-27T06:19:28.002763Z","end":"2026-05-27T06:19:28.521672Z","steps":["trace[579948353] 'agreement among raft nodes before linearized reading' (duration: 518.672631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:19:28.521733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:19:28.002754Z","time spent":"518.971179ms","remote":"127.0.0.1:50608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-05-27T06:19:28.528594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.315679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-27T06:19:28.52862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.308736ms","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-05-27T06:19:28.528668Z","caller":"traceutil/trace.go:171","msg":"trace[381388751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8354; }","duration":"350.374577ms","start":"2026-05-27T06:19:28.17828Z","end":"2026-05-27T06:19:28.528654Z","steps":["trace[381388751] 'agreement among raft nodes before linearized reading' (duration: 350.206332ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:19:28.528667Z","caller":"traceutil/trace.go:171","msg":"trace[1103925950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8354; }","duration":"353.445112ms","start":"2026-05-27T06:19:28.175203Z","end":"2026-05-27T06:19:28.528648Z","steps":["trace[1103925950] 'agreement among raft nodes before linearized reading' (duration: 353.246016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:19:28.52869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:19:28.178271Z","time spent":"350.413328ms","remote":"127.0.0.1:50416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-27T06:19:28.528703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:19:28.175178Z","time spent":"353.517604ms","remote":"127.0.0.1:50416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-27T06:19:39.957229Z","caller":"traceutil/trace.go:171","msg":"trace[349442607] transaction","detail":"{read_only:false; response_revision:8412; number_of_response:1; }","duration":"375.396746ms","start":"2026-05-27T06:19:39.581804Z","end":"2026-05-27T06:19:39.9572Z","steps":["trace[349442607] 'process raft request' (duration: 375.15369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:19:39.957434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:19:39.581773Z","time spent":"375.550451ms","remote":"127.0.0.1:50416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T06:19:40.22776Z","caller":"traceutil/trace.go:171","msg":"trace[1690429143] linearizableReadLoop","detail":"{readStateIndex:8709; appliedIndex:8707; }","duration":"456.383746ms","start":"2026-05-27T06:19:39.771332Z","end":"2026-05-27T06:19:40.227716Z","steps":["trace[1690429143] 'read index received' (duration: 185.746874ms)","trace[1690429143] 'applied index is now lower than readState.Index' (duration: 270.635302ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:19:40.227807Z","caller":"traceutil/trace.go:171","msg":"trace[452588229] transaction","detail":"{read_only:false; response_revision:8413; number_of_response:1; }","duration":"582.236632ms","start":"2026-05-27T06:19:39.64551Z","end":"2026-05-27T06:19:40.227746Z","steps":["trace[452588229] 'process raft request' (duration: 535.870555ms)","trace[452588229] 'compare' (duration: 46.018407ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:19:40.227951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.588882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T06:19:40.22806Z","caller":"traceutil/trace.go:171","msg":"trace[2086170735] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8413; }","duration":"456.713326ms","start":"2026-05-27T06:19:39.77132Z","end":"2026-05-27T06:19:40.228033Z","steps":["trace[2086170735] 'agreement among raft nodes before linearized reading' (duration: 456.525851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:19:40.228194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:19:39.771257Z","time spent":"456.922401ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T06:19:40.227997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:19:39.64548Z","time spent":"582.417527ms","remote":"127.0.0.1:50416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T06:19:40.235885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.330595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T06:19:40.235978Z","caller":"traceutil/trace.go:171","msg":"trace[999961344] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:8414; }","duration":"322.469169ms","start":"2026-05-27T06:19:39.913489Z","end":"2026-05-27T06:19:40.235958Z","steps":["trace[999961344] 'agreement among raft nodes before linearized reading' (duration: 322.307204ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:19:40.236006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.065171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"warn","ts":"2026-05-27T06:19:40.236021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:19:39.91346Z","time spent":"322.550191ms","remote":"127.0.0.1:50278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":7,"response size":29,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-05-27T06:19:40.236047Z","caller":"traceutil/trace.go:171","msg":"trace[1527558750] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8414; }","duration":"122.136903ms","start":"2026-05-27T06:19:40.113898Z","end":"2026-05-27T06:19:40.236035Z","steps":["trace[1527558750] 'agreement among raft nodes before linearized reading' (duration: 121.990929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:19:40.236061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.897353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-27T06:19:40.236149Z","caller":"traceutil/trace.go:171","msg":"trace[721127352] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8414; }","duration":"237.033637ms","start":"2026-05-27T06:19:39.999105Z","end":"2026-05-27T06:19:40.236139Z","steps":["trace[721127352] 'agreement among raft nodes before linearized reading' (duration: 236.79998ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:19:40.236307Z","caller":"traceutil/trace.go:171","msg":"trace[2083308351] transaction","detail":"{read_only:false; response_revision:8414; number_of_response:1; }","duration":"233.240422ms","start":"2026-05-27T06:19:40.003058Z","end":"2026-05-27T06:19:40.236299Z","steps":["trace[2083308351] 'process raft request' (duration: 232.610595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:19:40.236406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.02285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T06:19:40.236464Z","caller":"traceutil/trace.go:171","msg":"trace[763838334] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:8414; }","duration":"118.105333ms","start":"2026-05-27T06:19:40.118349Z","end":"2026-05-27T06:19:40.236454Z","steps":["trace[763838334] 'agreement among raft nodes before linearized reading' (duration: 117.509966ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:19:40.686982Z","caller":"traceutil/trace.go:171","msg":"trace[454800336] linearizableReadLoop","detail":"{readStateIndex:8714; appliedIndex:8712; }","duration":"101.76918ms","start":"2026-05-27T06:19:40.585186Z","end":"2026-05-27T06:19:40.686956Z","steps":["trace[454800336] 'read index received' (duration: 59.61949ms)","trace[454800336] 'applied index is now lower than readState.Index' (duration: 42.14801ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:19:40.687092Z","caller":"traceutil/trace.go:171","msg":"trace[498338886] transaction","detail":"{read_only:false; response_revision:8418; number_of_response:1; }","duration":"105.040241ms","start":"2026-05-27T06:19:40.582007Z","end":"2026-05-27T06:19:40.687048Z","steps":["trace[498338886] 'process raft request' (duration: 82.875911ms)","trace[498338886] 'compare' (duration: 21.916373ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:19:40.687243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.036079ms","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-27T06:19:40.687289Z","caller":"traceutil/trace.go:171","msg":"trace[68355027] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8418; }","duration":"102.11437ms","start":"2026-05-27T06:19:40.585163Z","end":"2026-05-27T06:19:40.687278Z","steps":["trace[68355027] 'agreement among raft nodes before linearized reading' (duration: 101.945416ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:19:40.899929Z","caller":"traceutil/trace.go:171","msg":"trace[816529066] transaction","detail":"{read_only:false; response_revision:8420; number_of_response:1; }","duration":"115.592292ms","start":"2026-05-27T06:19:40.784296Z","end":"2026-05-27T06:19:40.899889Z","steps":["trace[816529066] 'process raft request' (duration: 26.834119ms)","trace[816529066] 'compare' (duration: 88.547468ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:19:42.854337Z","caller":"traceutil/trace.go:171","msg":"trace[938902101] transaction","detail":"{read_only:false; response_revision:8428; number_of_response:1; }","duration":"103.117669ms","start":"2026-05-27T06:19:42.751195Z","end":"2026-05-27T06:19:42.854312Z","steps":["trace[938902101] 'process raft request' (duration: 102.971525ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:19:42.877575Z","caller":"traceutil/trace.go:171","msg":"trace[513124312] linearizableReadLoop","detail":"{readStateIndex:8725; appliedIndex:8723; }","duration":"125.419754ms","start":"2026-05-27T06:19:42.752109Z","end":"2026-05-27T06:19:42.877529Z","steps":["trace[513124312] 'read index received' (duration: 102.05311ms)","trace[513124312] 'applied index is now lower than readState.Index' (duration: 23.365844ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:19:42.877682Z","caller":"traceutil/trace.go:171","msg":"trace[1725925550] transaction","detail":"{read_only:false; response_revision:8429; number_of_response:1; }","duration":"125.851835ms","start":"2026-05-27T06:19:42.751808Z","end":"2026-05-27T06:19:42.87766Z","steps":["trace[1725925550] 'process raft request' (duration: 125.640019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:19:42.877999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.874456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:224125"} {"level":"info","ts":"2026-05-27T06:19:42.878042Z","caller":"traceutil/trace.go:171","msg":"trace[1382403267] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8429; }","duration":"125.947037ms","start":"2026-05-27T06:19:42.752085Z","end":"2026-05-27T06:19:42.878032Z","steps":["trace[1382403267] 'agreement among raft nodes before linearized reading' (duration: 125.590898ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:20:09.892243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.318173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T06:20:09.892352Z","caller":"traceutil/trace.go:171","msg":"trace[1172000725] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8626; }","duration":"120.465987ms","start":"2026-05-27T06:20:09.771862Z","end":"2026-05-27T06:20:09.892327Z","steps":["trace[1172000725] 'range keys from in-memory index tree' (duration: 120.254232ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:20:18.607414Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000231b.wal"} {"level":"info","ts":"2026-05-27T06:20:22.173858Z","caller":"traceutil/trace.go:171","msg":"trace[386796620] transaction","detail":"{read_only:false; response_revision:8731; number_of_response:1; }","duration":"123.538981ms","start":"2026-05-27T06:20:22.050295Z","end":"2026-05-27T06:20:22.173834Z","steps":["trace[386796620] 'process raft request' (duration: 123.393027ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:20:22.319629Z","caller":"traceutil/trace.go:171","msg":"trace[1604446245] transaction","detail":"{read_only:false; response_revision:8734; number_of_response:1; }","duration":"100.95582ms","start":"2026-05-27T06:20:22.21864Z","end":"2026-05-27T06:20:22.319596Z","steps":["trace[1604446245] 'process raft request' (duration: 100.764904ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:20:22.676528Z","caller":"traceutil/trace.go:171","msg":"trace[1471245449] linearizableReadLoop","detail":"{readStateIndex:9044; appliedIndex:9043; }","duration":"145.562908ms","start":"2026-05-27T06:20:22.530941Z","end":"2026-05-27T06:20:22.676504Z","steps":["trace[1471245449] 'read index received' (duration: 145.398063ms)","trace[1471245449] 'applied index is now lower than readState.Index' (duration: 163.735µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:20:22.676629Z","caller":"traceutil/trace.go:171","msg":"trace[588032679] transaction","detail":"{read_only:false; response_revision:8735; number_of_response:1; }","duration":"191.608736ms","start":"2026-05-27T06:20:22.484985Z","end":"2026-05-27T06:20:22.676594Z","steps":["trace[588032679] 'process raft request' (duration: 191.38444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:20:22.676707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.747373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-27T06:20:22.676741Z","caller":"traceutil/trace.go:171","msg":"trace[2105480752] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8735; }","duration":"145.824405ms","start":"2026-05-27T06:20:22.530906Z","end":"2026-05-27T06:20:22.676731Z","steps":["trace[2105480752] 'agreement among raft nodes before linearized reading' (duration: 145.689051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:20:22.900829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.4657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T06:20:22.900914Z","caller":"traceutil/trace.go:171","msg":"trace[1988128521] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8736; }","duration":"131.590233ms","start":"2026-05-27T06:20:22.769304Z","end":"2026-05-27T06:20:22.900894Z","steps":["trace[1988128521] 'range keys from in-memory index tree' (duration: 131.403378ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:20:23.468703Z","caller":"traceutil/trace.go:171","msg":"trace[249006017] transaction","detail":"{read_only:false; response_revision:8742; number_of_response:1; }","duration":"113.871736ms","start":"2026-05-27T06:20:23.354801Z","end":"2026-05-27T06:20:23.468672Z","steps":["trace[249006017] 'process raft request' (duration: 54.692386ms)","trace[249006017] 'compare' (duration: 59.041406ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:20:23.795567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.48586ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T06:20:23.795869Z","caller":"traceutil/trace.go:171","msg":"trace[1762882425] transaction","detail":"{read_only:false; response_revision:8743; number_of_response:1; }","duration":"292.849312ms","start":"2026-05-27T06:20:23.502999Z","end":"2026-05-27T06:20:23.795848Z","steps":["trace[1762882425] 'process raft request' (duration: 71.989122ms)","trace[1762882425] 'compare' (duration: 220.179532ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:21:40.939728Z","caller":"traceutil/trace.go:171","msg":"trace[733550605] transaction","detail":"{read_only:false; response_revision:9496; number_of_response:1; }","duration":"115.606783ms","start":"2026-05-27T06:21:40.824086Z","end":"2026-05-27T06:21:40.939693Z","steps":["trace[733550605] 'process raft request' (duration: 115.314565ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:22:01.57322Z","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-27T06:22:01.578559Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-27T06:22:01.578674Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-27T06:24:20.371905Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8175} {"level":"info","ts":"2026-05-27T06:24:20.436941Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8175,"took":"62.889301ms","hash":2267887251,"current-db-size-bytes":58961920,"current-db-size":"59 MB","current-db-size-in-use-bytes":28426240,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-27T06:24:20.437012Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2267887251,"revision":8175,"compact-revision":6088} {"level":"warn","ts":"2026-05-27T06:25:54.649419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.40856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-27T06:25:54.649513Z","caller":"traceutil/trace.go:171","msg":"trace[1235088675] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:11131; }","duration":"118.557604ms","start":"2026-05-27T06:25:54.530936Z","end":"2026-05-27T06:25:54.649494Z","steps":["trace[1235088675] 'range keys from in-memory index tree' (duration: 118.209694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:27:16.389112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.227639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T06:27:16.389218Z","caller":"traceutil/trace.go:171","msg":"trace[1846430022] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11913; }","duration":"100.372624ms","start":"2026-05-27T06:27:16.288825Z","end":"2026-05-27T06:27:16.389197Z","steps":["trace[1846430022] 'range keys from in-memory index tree' (duration: 100.118227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:27:16.389205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.585549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T06:27:16.389256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.521387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T06:27:16.38938Z","caller":"traceutil/trace.go:171","msg":"trace[891115600] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11913; }","duration":"100.663901ms","start":"2026-05-27T06:27:16.28869Z","end":"2026-05-27T06:27:16.389353Z","steps":["trace[891115600] 'range keys from in-memory index tree' (duration: 100.431055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:27:16.38933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.412124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T06:27:16.389467Z","caller":"traceutil/trace.go:171","msg":"trace[2032666902] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11913; }","duration":"100.597709ms","start":"2026-05-27T06:27:16.288837Z","end":"2026-05-27T06:27:16.389435Z","steps":["trace[2032666902] 'range keys from in-memory index tree' (duration: 100.337043ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:27:16.389333Z","caller":"traceutil/trace.go:171","msg":"trace[528793047] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11913; }","duration":"100.776605ms","start":"2026-05-27T06:27:16.288527Z","end":"2026-05-27T06:27:16.389304Z","steps":["trace[528793047] 'range keys from in-memory index tree' (duration: 100.448685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:27:17.613498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.527825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-27T06:27:17.613634Z","caller":"traceutil/trace.go:171","msg":"trace[1837104034] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:11923; }","duration":"182.69367ms","start":"2026-05-27T06:27:17.430891Z","end":"2026-05-27T06:27:17.613584Z","steps":["trace[1837104034] 'range keys from in-memory index tree' (duration: 182.199686ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:29:20.37918Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10539} {"level":"info","ts":"2026-05-27T06:29:20.458744Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10539,"took":"77.669238ms","hash":1792854015,"current-db-size-bytes":58961920,"current-db-size":"59 MB","current-db-size-in-use-bytes":26300416,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-27T06:29:20.4588Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1792854015,"revision":10539,"compact-revision":8175} {"level":"warn","ts":"2026-05-27T06:33:14.885398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.811971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lh75v\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-05-27T06:33:14.885486Z","caller":"traceutil/trace.go:171","msg":"trace[310212889] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lh75v; range_end:; response_count:1; response_revision:14351; }","duration":"114.950185ms","start":"2026-05-27T06:33:14.770517Z","end":"2026-05-27T06:33:14.885467Z","steps":["trace[310212889] 'range keys from in-memory index tree' (duration: 114.629776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:33:36.965204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.874922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-27T06:33:36.965349Z","caller":"traceutil/trace.go:171","msg":"trace[326133267] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14466; }","duration":"156.075718ms","start":"2026-05-27T06:33:36.809256Z","end":"2026-05-27T06:33:36.965332Z","steps":["trace[326133267] 'range keys from in-memory index tree' (duration: 155.712318ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:34:20.384507Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12761} {"level":"info","ts":"2026-05-27T06:34:20.460417Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12761,"took":"73.907835ms","hash":3471131546,"current-db-size-bytes":58961920,"current-db-size":"59 MB","current-db-size-in-use-bytes":28549120,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-27T06:34:20.460535Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3471131546,"revision":12761,"compact-revision":10539} {"level":"warn","ts":"2026-05-27T06:35:39.497947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.76191ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T06:35:39.498096Z","caller":"traceutil/trace.go:171","msg":"trace[921957132] transaction","detail":"{read_only:false; response_revision:15677; number_of_response:1; }","duration":"301.004108ms","start":"2026-05-27T06:35:39.197064Z","end":"2026-05-27T06:35:39.498068Z","steps":["trace[921957132] 'process raft request' (duration: 137.011323ms)","trace[921957132] 'compare' (duration: 163.607815ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:35:39.498185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:35:39.197051Z","time spent":"301.088991ms","remote":"127.0.0.1:50416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T06:35:39.81952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.306471ms","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-27T06:35:39.819594Z","caller":"traceutil/trace.go:171","msg":"trace[1729357222] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15678; }","duration":"222.385874ms","start":"2026-05-27T06:35:39.59719Z","end":"2026-05-27T06:35:39.819576Z","steps":["trace[1729357222] 'range keys from in-memory index tree' (duration: 222.169548ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:35:39.819941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.176119ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T06:35:39.820078Z","caller":"traceutil/trace.go:171","msg":"trace[493241174] linearizableReadLoop","detail":"{readStateIndex:16220; appliedIndex:16219; }","duration":"216.987905ms","start":"2026-05-27T06:35:39.603068Z","end":"2026-05-27T06:35:39.820055Z","steps":["trace[493241174] 'read index received' (duration: 112.543899ms)","trace[493241174] 'applied index is now lower than readState.Index' (duration: 104.442006ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:35:39.820231Z","caller":"traceutil/trace.go:171","msg":"trace[2138961057] transaction","detail":"{read_only:false; response_revision:15679; number_of_response:1; }","duration":"219.027731ms","start":"2026-05-27T06:35:39.601037Z","end":"2026-05-27T06:35:39.820065Z","steps":["trace[2138961057] 'process raft request' (duration: 114.621026ms)","trace[2138961057] 'compare' (duration: 103.87273ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:35:39.820263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.18215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-27T06:35:39.820318Z","caller":"traceutil/trace.go:171","msg":"trace[1039563696] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15679; }","duration":"217.258783ms","start":"2026-05-27T06:35:39.603041Z","end":"2026-05-27T06:35:39.8203Z","steps":["trace[1039563696] 'agreement among raft nodes before linearized reading' (duration: 217.130649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:35:39.820347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.290994ms","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-27T06:35:39.820409Z","caller":"traceutil/trace.go:171","msg":"trace[321471528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15679; }","duration":"180.377567ms","start":"2026-05-27T06:35:39.640014Z","end":"2026-05-27T06:35:39.820392Z","steps":["trace[321471528] 'agreement among raft nodes before linearized reading' (duration: 180.191492ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:35:39.820628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.360316ms","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-27T06:35:39.820684Z","caller":"traceutil/trace.go:171","msg":"trace[352060035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15679; }","duration":"180.431979ms","start":"2026-05-27T06:35:39.640239Z","end":"2026-05-27T06:35:39.820671Z","steps":["trace[352060035] 'agreement among raft nodes before linearized reading' (duration: 180.302695ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:35:39.942354Z","caller":"traceutil/trace.go:171","msg":"trace[1154347504] transaction","detail":"{read_only:false; response_revision:15683; number_of_response:1; }","duration":"117.014161ms","start":"2026-05-27T06:35:39.825302Z","end":"2026-05-27T06:35:39.942316Z","steps":["trace[1154347504] 'process raft request' (duration: 94.007027ms)","trace[1154347504] 'compare' (duration: 22.827309ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:39:20.38992Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14921} {"level":"info","ts":"2026-05-27T06:39:20.48024Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14921,"took":"87.868451ms","hash":1787818412,"current-db-size-bytes":58961920,"current-db-size":"59 MB","current-db-size-in-use-bytes":30380032,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-27T06:39:20.4803Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1787818412,"revision":14921,"compact-revision":12761} {"level":"warn","ts":"2026-05-27T06:43:06.376832Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102630723478144,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-27T06:43:06.389204Z","caller":"traceutil/trace.go:171","msg":"trace[1632158286] linearizableReadLoop","detail":"{readStateIndex:19420; appliedIndex:19419; }","duration":"513.334162ms","start":"2026-05-27T06:43:05.875833Z","end":"2026-05-27T06:43:06.389167Z","steps":["trace[1632158286] 'read index received' (duration: 512.956182ms)","trace[1632158286] 'applied index is now lower than readState.Index' (duration: 376.86µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:43:06.389379Z","caller":"traceutil/trace.go:171","msg":"trace[944207584] transaction","detail":"{read_only:false; response_revision:18771; number_of_response:1; }","duration":"539.790251ms","start":"2026-05-27T06:43:05.849481Z","end":"2026-05-27T06:43:06.389271Z","steps":["trace[944207584] 'process raft request' (duration: 539.434111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:06.389523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.843081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"warn","ts":"2026-05-27T06:43:06.390143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.277271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-27T06:43:06.390408Z","caller":"traceutil/trace.go:171","msg":"trace[567119528] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18771; }","duration":"431.719403ms","start":"2026-05-27T06:43:05.958655Z","end":"2026-05-27T06:43:06.390375Z","steps":["trace[567119528] 'agreement among raft nodes before linearized reading' (duration: 431.101936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:06.390484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:05.958638Z","time spent":"431.827657ms","remote":"127.0.0.1:50416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-27T06:43:06.390193Z","caller":"traceutil/trace.go:171","msg":"trace[2111729772] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18771; }","duration":"512.54915ms","start":"2026-05-27T06:43:05.877608Z","end":"2026-05-27T06:43:06.390157Z","steps":["trace[2111729772] 'agreement among raft nodes before linearized reading' (duration: 511.740668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:06.390629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:05.877544Z","time spent":"513.072505ms","remote":"127.0.0.1:50294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":724,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-05-27T06:43:06.389588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.087384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-27T06:43:06.390821Z","caller":"traceutil/trace.go:171","msg":"trace[771176748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18771; }","duration":"294.38426ms","start":"2026-05-27T06:43:06.096424Z","end":"2026-05-27T06:43:06.390808Z","steps":["trace[771176748] 'agreement among raft nodes before linearized reading' (duration: 293.019583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:06.389616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.123024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-27T06:43:06.390934Z","caller":"traceutil/trace.go:171","msg":"trace[1166824874] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18771; }","duration":"429.481881ms","start":"2026-05-27T06:43:05.961442Z","end":"2026-05-27T06:43:06.390924Z","steps":["trace[1166824874] 'agreement among raft nodes before linearized reading' (duration: 428.107904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:06.390969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:05.961419Z","time spent":"429.540923ms","remote":"127.0.0.1:50416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-27T06:43:06.389697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.18062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-27T06:43:06.391046Z","caller":"traceutil/trace.go:171","msg":"trace[1556308685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18771; }","duration":"293.580058ms","start":"2026-05-27T06:43:06.097458Z","end":"2026-05-27T06:43:06.391038Z","steps":["trace[1556308685] 'agreement among raft nodes before linearized reading' (duration: 292.189789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:06.389738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.895675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-05-27T06:43:06.391126Z","caller":"traceutil/trace.go:171","msg":"trace[2126029733] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18771; }","duration":"463.319374ms","start":"2026-05-27T06:43:05.927797Z","end":"2026-05-27T06:43:06.391116Z","steps":["trace[2126029733] 'agreement among raft nodes before linearized reading' (duration: 461.879844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:06.391154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:05.927778Z","time spent":"463.368266ms","remote":"127.0.0.1:50294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":724,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-05-27T06:43:06.389723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.014513ms","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-27T06:43:06.391276Z","caller":"traceutil/trace.go:171","msg":"trace[334252035] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18771; }","duration":"457.592726ms","start":"2026-05-27T06:43:05.933652Z","end":"2026-05-27T06:43:06.391245Z","steps":["trace[334252035] 'agreement among raft nodes before linearized reading' (duration: 455.92016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:06.391424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:05.933603Z","time spent":"457.795142ms","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-27T06:43:06.389757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.540752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-27T06:43:06.391599Z","caller":"traceutil/trace.go:171","msg":"trace[1058332729] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18771; }","duration":"293.418114ms","start":"2026-05-27T06:43:06.098165Z","end":"2026-05-27T06:43:06.391584Z","steps":["trace[1058332729] 'agreement among raft nodes before linearized reading' (duration: 291.46212ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:06.389489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.646911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-05-27T06:43:06.391733Z","caller":"traceutil/trace.go:171","msg":"trace[1707860513] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18771; }","duration":"515.964485ms","start":"2026-05-27T06:43:05.875761Z","end":"2026-05-27T06:43:06.391726Z","steps":["trace[1707860513] 'agreement among raft nodes before linearized reading' (duration: 513.534338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:06.391757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:05.875737Z","time spent":"516.012966ms","remote":"127.0.0.1:50294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":724,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-05-27T06:43:06.390211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:05.84946Z","time spent":"540.649354ms","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9202,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T06:43:12.920683Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102630723478291,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T06:43:13.425703Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102630723478291,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T06:43:13.926301Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102630723478291,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T06:43:14.427062Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102630723478291,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T06:43:14.602495Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.182765143s","expected-duration":"1s"} {"level":"info","ts":"2026-05-27T06:43:14.602873Z","caller":"traceutil/trace.go:171","msg":"trace[459640008] transaction","detail":"{read_only:false; response_revision:18803; number_of_response:1; }","duration":"2.183254127s","start":"2026-05-27T06:43:12.419593Z","end":"2026-05-27T06:43:14.602847Z","steps":["trace[459640008] 'process raft request' (duration: 2.183116433s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.603023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:12.419576Z","time spent":"2.183368829s","remote":"127.0.0.1:50416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T06:43:14.736968Z","caller":"traceutil/trace.go:171","msg":"trace[227689146] linearizableReadLoop","detail":"{readStateIndex:19460; appliedIndex:19453; }","duration":"2.316829737s","start":"2026-05-27T06:43:12.420112Z","end":"2026-05-27T06:43:14.736942Z","steps":["trace[227689146] 'read index received' (duration: 2.182730503s)","trace[227689146] 'applied index is now lower than readState.Index' (duration: 134.098214ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:43:14.737237Z","caller":"traceutil/trace.go:171","msg":"trace[831243084] transaction","detail":"{read_only:false; response_revision:18809; number_of_response:1; }","duration":"1.134339709s","start":"2026-05-27T06:43:13.60287Z","end":"2026-05-27T06:43:14.737209Z","steps":["trace[831243084] 'process raft request' (duration: 1.13400136s)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:14.737337Z","caller":"traceutil/trace.go:171","msg":"trace[668586174] transaction","detail":"{read_only:false; response_revision:18804; number_of_response:1; }","duration":"2.31658639s","start":"2026-05-27T06:43:12.420715Z","end":"2026-05-27T06:43:14.737301Z","steps":["trace[668586174] 'process raft request' (duration: 2.315772078s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.737379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.317256488s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-27T06:43:14.737458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.31693174s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-27T06:43:14.737511Z","caller":"traceutil/trace.go:171","msg":"trace[945595764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18810; }","duration":"2.317016971s","start":"2026-05-27T06:43:12.420479Z","end":"2026-05-27T06:43:14.737496Z","steps":["trace[945595764] 'agreement among raft nodes before linearized reading' (duration: 2.316832326s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.737519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:12.420696Z","time spent":"2.316724324s","remote":"127.0.0.1:50416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T06:43:14.737517Z","caller":"traceutil/trace.go:171","msg":"trace[544586399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18810; }","duration":"2.317417183s","start":"2026-05-27T06:43:12.420056Z","end":"2026-05-27T06:43:14.737473Z","steps":["trace[544586399] 'agreement among raft nodes before linearized reading' (duration: 2.317118804s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.737533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.365647581s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:140"} {"level":"warn","ts":"2026-05-27T06:43:14.737604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.92095865s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-27T06:43:14.737636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.917753942s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lh75v\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-05-27T06:43:14.73762Z","caller":"traceutil/trace.go:171","msg":"trace[872107989] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:18810; }","duration":"1.365791196s","start":"2026-05-27T06:43:13.371811Z","end":"2026-05-27T06:43:14.737602Z","steps":["trace[872107989] 'agreement among raft nodes before linearized reading' (duration: 1.365524209s)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:14.737676Z","caller":"traceutil/trace.go:171","msg":"trace[1974495661] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lh75v; range_end:; response_count:1; response_revision:18810; }","duration":"1.917805674s","start":"2026-05-27T06:43:12.81986Z","end":"2026-05-27T06:43:14.737666Z","steps":["trace[1974495661] 'agreement among raft nodes before linearized reading' (duration: 1.917608399s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.73767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.554006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"warn","ts":"2026-05-27T06:43:14.737707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:12.819854Z","time spent":"1.917847475s","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-lh75v\" "} {"level":"info","ts":"2026-05-27T06:43:14.737665Z","caller":"traceutil/trace.go:171","msg":"trace[511979505] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:18810; }","duration":"1.921048723s","start":"2026-05-27T06:43:12.816607Z","end":"2026-05-27T06:43:14.737656Z","steps":["trace[511979505] 'agreement among raft nodes before linearized reading' (duration: 1.92096734s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.73755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:12.420467Z","time spent":"2.317076104s","remote":"127.0.0.1:50416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-27T06:43:14.737427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:13.602854Z","time spent":"1.134469704s","remote":"127.0.0.1:50416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T06:43:14.737803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.966018033s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T06:43:14.739054Z","caller":"traceutil/trace.go:171","msg":"trace[1505524143] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18810; }","duration":"1.967262887s","start":"2026-05-27T06:43:12.771776Z","end":"2026-05-27T06:43:14.739039Z","steps":["trace[1505524143] 'agreement among raft nodes before linearized reading' (duration: 1.965998062s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.739108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:12.771725Z","time spent":"1.967371029s","remote":"127.0.0.1:50124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T06:43:14.739199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.068083614s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-dhcp-agent-default-f5fcn\" ","response":"range_response_count:1 size:18647"} {"level":"info","ts":"2026-05-27T06:43:14.739239Z","caller":"traceutil/trace.go:171","msg":"trace[1640587305] range","detail":"{range_begin:/registry/pods/openstack/neutron-dhcp-agent-default-f5fcn; range_end:; response_count:1; response_revision:18810; }","duration":"2.068183266s","start":"2026-05-27T06:43:12.671045Z","end":"2026-05-27T06:43:14.739228Z","steps":["trace[1640587305] 'agreement among raft nodes before linearized reading' (duration: 2.066622103s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.739276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:12.671022Z","time spent":"2.068246878s","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":18669,"request content":"key:\"/registry/pods/openstack/neutron-dhcp-agent-default-f5fcn\" "} {"level":"info","ts":"2026-05-27T06:43:14.739689Z","caller":"traceutil/trace.go:171","msg":"trace[1385713887] transaction","detail":"{read_only:false; response_revision:18808; number_of_response:1; }","duration":"1.638983143s","start":"2026-05-27T06:43:13.098157Z","end":"2026-05-27T06:43:14.73714Z","steps":["trace[1385713887] 'process raft request' (duration: 1.638678714s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.739704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.400021499s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-27T06:43:14.73762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:12.420031Z","time spent":"2.317561187s","remote":"127.0.0.1:50416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-27T06:43:14.739784Z","caller":"traceutil/trace.go:171","msg":"trace[710019566] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:18810; }","duration":"1.400144432s","start":"2026-05-27T06:43:13.339604Z","end":"2026-05-27T06:43:14.739749Z","steps":["trace[710019566] 'agreement among raft nodes before linearized reading' (duration: 1.398196249s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.739806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:13.098147Z","time spent":"1.641593254s","remote":"127.0.0.1:50416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T06:43:14.739832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:13.339578Z","time spent":"1.400242485s","remote":"127.0.0.1:49618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "} {"level":"warn","ts":"2026-05-27T06:43:14.739149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.112865498s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-metadata-agent-default-qqt9s\" ","response":"range_response_count:1 size:14278"} {"level":"info","ts":"2026-05-27T06:43:14.740176Z","caller":"traceutil/trace.go:171","msg":"trace[1853683649] range","detail":"{range_begin:/registry/pods/openstack/neutron-metadata-agent-default-qqt9s; range_end:; response_count:1; response_revision:18810; }","duration":"2.114010099s","start":"2026-05-27T06:43:12.626141Z","end":"2026-05-27T06:43:14.740152Z","steps":["trace[1853683649] 'agreement among raft nodes before linearized reading' (duration: 2.111615633s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.740214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:12.626127Z","time spent":"2.114077011s","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":14300,"request content":"key:\"/registry/pods/openstack/neutron-metadata-agent-default-qqt9s\" "} {"level":"warn","ts":"2026-05-27T06:43:14.737748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:12.816591Z","time spent":"1.921146456s","remote":"127.0.0.1:50546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-05-27T06:43:14.737904Z","caller":"traceutil/trace.go:171","msg":"trace[573190640] transaction","detail":"{read_only:false; response_revision:18805; number_of_response:1; }","duration":"2.316882408s","start":"2026-05-27T06:43:12.420973Z","end":"2026-05-27T06:43:14.737855Z","steps":["trace[573190640] 'process raft request' (duration: 2.315742607s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.740554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.656815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-05-27T06:43:14.74062Z","caller":"traceutil/trace.go:171","msg":"trace[417168498] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18810; }","duration":"874.754368ms","start":"2026-05-27T06:43:13.86585Z","end":"2026-05-27T06:43:14.740604Z","steps":["trace[417168498] 'agreement among raft nodes before linearized reading' (duration: 871.892969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.740646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:12.420961Z","time spent":"2.319605543s","remote":"127.0.0.1:50416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T06:43:14.737514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.150175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T06:43:14.740808Z","caller":"traceutil/trace.go:171","msg":"trace[2009245159] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:18810; }","duration":"659.521929ms","start":"2026-05-27T06:43:14.081261Z","end":"2026-05-27T06:43:14.740783Z","steps":["trace[2009245159] 'agreement among raft nodes before linearized reading' (duration: 656.102135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.74085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.868618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-4bgs4\" ","response":"range_response_count:1 size:22101"} {"level":"warn","ts":"2026-05-27T06:43:14.740856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:14.081235Z","time spent":"659.60349ms","remote":"127.0.0.1:38906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-27T06:43:14.740892Z","caller":"traceutil/trace.go:171","msg":"trace[351334591] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-4bgs4; range_end:; response_count:1; response_revision:18810; }","duration":"802.959991ms","start":"2026-05-27T06:43:13.93792Z","end":"2026-05-27T06:43:14.74088Z","steps":["trace[351334591] 'agreement among raft nodes before linearized reading' (duration: 799.866025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.738791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.924309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-7w88w\" ","response":"range_response_count:1 size:20755"} {"level":"warn","ts":"2026-05-27T06:43:14.741002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.545093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"warn","ts":"2026-05-27T06:43:14.738831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.472996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-05-27T06:43:14.741011Z","caller":"traceutil/trace.go:171","msg":"trace[983856346] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-7w88w; range_end:; response_count:1; response_revision:18810; }","duration":"858.158291ms","start":"2026-05-27T06:43:13.882812Z","end":"2026-05-27T06:43:14.74097Z","steps":["trace[983856346] 'agreement among raft nodes before linearized reading' (duration: 854.976953ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:14.741046Z","caller":"traceutil/trace.go:171","msg":"trace[843421600] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18810; }","duration":"327.741858ms","start":"2026-05-27T06:43:14.413294Z","end":"2026-05-27T06:43:14.741036Z","steps":["trace[843421600] 'agreement among raft nodes before linearized reading' (duration: 324.284253ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.741076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:13.882794Z","time spent":"858.265323ms","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20777,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-7w88w\" "} {"level":"warn","ts":"2026-05-27T06:43:14.741152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.734345409s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-27T06:43:14.738937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.067904069s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-l3-agent-default-hbblg\" ","response":"range_response_count:1 size:18671"} {"level":"warn","ts":"2026-05-27T06:43:14.741089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:14.413268Z","time spent":"327.81255ms","remote":"127.0.0.1:50294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":724,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-05-27T06:43:14.740675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:13.865827Z","time spent":"874.82409ms","remote":"127.0.0.1:50294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":724,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"info","ts":"2026-05-27T06:43:14.738984Z","caller":"traceutil/trace.go:171","msg":"trace[1429678771] transaction","detail":"{read_only:false; response_revision:18810; number_of_response:1; }","duration":"398.407305ms","start":"2026-05-27T06:43:14.338849Z","end":"2026-05-27T06:43:14.737257Z","steps":["trace[1429678771] 'process raft request' (duration: 398.050305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.737705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:13.371772Z","time spent":"1.36592376s","remote":"127.0.0.1:50160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":162,"request content":"key:\"/registry/masterleases/199.204.45.129\" "} {"level":"warn","ts":"2026-05-27T06:43:14.741424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:14.338833Z","time spent":"402.481997ms","remote":"127.0.0.1:50416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T06:43:14.741454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.251867177s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-4bgs4\" ","response":"range_response_count:1 size:22101"} {"level":"info","ts":"2026-05-27T06:43:14.741515Z","caller":"traceutil/trace.go:171","msg":"trace[1606567307] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-4bgs4; range_end:; response_count:1; response_revision:18810; }","duration":"1.251940649s","start":"2026-05-27T06:43:13.489542Z","end":"2026-05-27T06:43:14.741483Z","steps":["trace[1606567307] 'agreement among raft nodes before linearized reading' (duration: 1.251798265s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.741549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:13.489527Z","time spent":"1.252012692s","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22123,"request content":"key:\"/registry/pods/openstack/nova-compute-default-4bgs4\" "} {"level":"warn","ts":"2026-05-27T06:43:14.740935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:13.937899Z","time spent":"803.019762ms","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22123,"request content":"key:\"/registry/pods/openstack/nova-compute-default-4bgs4\" "} {"level":"info","ts":"2026-05-27T06:43:14.738892Z","caller":"traceutil/trace.go:171","msg":"trace[589840806] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18810; }","duration":"322.663588ms","start":"2026-05-27T06:43:14.41507Z","end":"2026-05-27T06:43:14.737733Z","steps":["trace[589840806] 'agreement among raft nodes before linearized reading' (duration: 322.353989ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:14.741613Z","caller":"traceutil/trace.go:171","msg":"trace[1534308790] transaction","detail":"{read_only:false; response_revision:18806; number_of_response:1; }","duration":"1.94019122s","start":"2026-05-27T06:43:12.801405Z","end":"2026-05-27T06:43:14.741596Z","steps":["trace[1534308790] 'process raft request' (duration: 1.935358167s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.741676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:14.415057Z","time spent":"326.593177ms","remote":"127.0.0.1:50294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":724,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-05-27T06:43:14.741725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:12.801385Z","time spent":"1.940288793s","remote":"127.0.0.1:50416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T06:43:14.741051Z","caller":"traceutil/trace.go:171","msg":"trace[609008074] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18810; }","duration":"327.611635ms","start":"2026-05-27T06:43:14.413416Z","end":"2026-05-27T06:43:14.741028Z","steps":["trace[609008074] 'agreement among raft nodes before linearized reading' (duration: 324.082587ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:14.741804Z","caller":"traceutil/trace.go:171","msg":"trace[571512231] range","detail":"{range_begin:/registry/pods/openstack/neutron-l3-agent-default-hbblg; range_end:; response_count:1; response_revision:18810; }","duration":"2.070783828s","start":"2026-05-27T06:43:12.670988Z","end":"2026-05-27T06:43:14.741772Z","steps":["trace[571512231] 'agreement among raft nodes before linearized reading' (duration: 2.066694875s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.741855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:14.413389Z","time spent":"328.436778ms","remote":"127.0.0.1:50294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":724,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-05-27T06:43:14.741878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:12.67097Z","time spent":"2.07088805s","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":18693,"request content":"key:\"/registry/pods/openstack/neutron-l3-agent-default-hbblg\" "} {"level":"warn","ts":"2026-05-27T06:43:14.738933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.915049117s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-27T06:43:14.741965Z","caller":"traceutil/trace.go:171","msg":"trace[1058237678] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:18810; }","duration":"1.918114192s","start":"2026-05-27T06:43:12.82383Z","end":"2026-05-27T06:43:14.741944Z","steps":["trace[1058237678] 'agreement among raft nodes before linearized reading' (duration: 1.914007109s)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:14.741958Z","caller":"traceutil/trace.go:171","msg":"trace[1937124300] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:18810; }","duration":"1.735125471s","start":"2026-05-27T06:43:13.006785Z","end":"2026-05-27T06:43:14.74191Z","steps":["trace[1937124300] 'agreement among raft nodes before linearized reading' (duration: 1.730984707s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.742013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:12.823815Z","time spent":"1.918178294s","remote":"127.0.0.1:58788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":2,"response size":30,"request content":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true "} {"level":"warn","ts":"2026-05-27T06:43:14.742025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.719211173s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"warn","ts":"2026-05-27T06:43:14.742036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:13.006722Z","time spent":"1.735287376s","remote":"127.0.0.1:52074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-05-27T06:43:14.742074Z","caller":"traceutil/trace.go:171","msg":"trace[1594329128] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18810; }","duration":"1.719244214s","start":"2026-05-27T06:43:13.022804Z","end":"2026-05-27T06:43:14.742048Z","steps":["trace[1594329128] 'agreement among raft nodes before linearized reading' (duration: 1.714956456s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.742106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:13.022739Z","time spent":"1.719358597s","remote":"127.0.0.1:50294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":724,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"info","ts":"2026-05-27T06:43:14.742217Z","caller":"traceutil/trace.go:171","msg":"trace[1901326112] transaction","detail":"{read_only:false; response_revision:18807; number_of_response:1; }","duration":"1.642036207s","start":"2026-05-27T06:43:13.095774Z","end":"2026-05-27T06:43:14.737811Z","steps":["trace[1901326112] 'process raft request' (duration: 1.641023169s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.742331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:13.095752Z","time spent":"1.646501749s","remote":"127.0.0.1:50416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T06:43:14.742421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.676535607s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-27T06:43:14.742457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"995.274629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-bt4wn\" ","response":"range_response_count:1 size:9789"} {"level":"info","ts":"2026-05-27T06:43:14.742479Z","caller":"traceutil/trace.go:171","msg":"trace[381398028] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:18810; }","duration":"1.676575898s","start":"2026-05-27T06:43:13.06587Z","end":"2026-05-27T06:43:14.742446Z","steps":["trace[381398028] 'agreement among raft nodes before linearized reading' (duration: 1.676117055s)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:14.742505Z","caller":"traceutil/trace.go:171","msg":"trace[980772127] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-bt4wn; range_end:; response_count:1; response_revision:18810; }","duration":"995.335411ms","start":"2026-05-27T06:43:13.747143Z","end":"2026-05-27T06:43:14.742478Z","steps":["trace[980772127] 'agreement among raft nodes before linearized reading' (duration: 990.624911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.742517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:13.065813Z","time spent":"1.676690761s","remote":"127.0.0.1:38928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-27T06:43:14.742538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:13.747126Z","time spent":"995.404172ms","remote":"127.0.0.1:50316","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-bt4wn\" "} {"level":"info","ts":"2026-05-27T06:43:14.921688Z","caller":"traceutil/trace.go:171","msg":"trace[1547098509] transaction","detail":"{read_only:false; response_revision:18813; number_of_response:1; }","duration":"135.39183ms","start":"2026-05-27T06:43:14.786269Z","end":"2026-05-27T06:43:14.921661Z","steps":["trace[1547098509] 'process raft request' (duration: 45.448842ms)","trace[1547098509] 'compare' (duration: 89.820785ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:43:47.270769Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102630723479037,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T06:43:47.770927Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102630723479037,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T06:43:48.18523Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.425935013s","expected-duration":"1s"} {"level":"info","ts":"2026-05-27T06:43:48.18565Z","caller":"traceutil/trace.go:171","msg":"trace[47351752] transaction","detail":"{read_only:false; response_revision:18969; number_of_response:1; }","duration":"1.426463218s","start":"2026-05-27T06:43:46.759163Z","end":"2026-05-27T06:43:48.185626Z","steps":["trace[47351752] 'process raft request' (duration: 1.426277273s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.185801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:46.759147Z","time spent":"1.426580951s","remote":"127.0.0.1:50416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T06:43:48.204925Z","caller":"traceutil/trace.go:171","msg":"trace[1480626932] transaction","detail":"{read_only:false; response_revision:18971; number_of_response:1; }","duration":"1.357332714s","start":"2026-05-27T06:43:46.847571Z","end":"2026-05-27T06:43:48.204904Z","steps":["trace[1480626932] 'process raft request' (duration: 1.357278352s)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:48.204949Z","caller":"traceutil/trace.go:171","msg":"trace[469397714] transaction","detail":"{read_only:false; response_revision:18970; number_of_response:1; }","duration":"1.359100693s","start":"2026-05-27T06:43:46.845815Z","end":"2026-05-27T06:43:48.204916Z","steps":["trace[469397714] 'process raft request' (duration: 1.358930148s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.20503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:46.847554Z","time spent":"1.357420187s","remote":"127.0.0.1:50416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T06:43:48.205125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:46.845793Z","time spent":"1.359240026s","remote":"127.0.0.1:50416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T06:43:48.205168Z","caller":"traceutil/trace.go:171","msg":"trace[2140942764] transaction","detail":"{read_only:false; response_revision:18972; number_of_response:1; }","duration":"1.349919649s","start":"2026-05-27T06:43:46.855228Z","end":"2026-05-27T06:43:48.205148Z","steps":["trace[2140942764] 'process raft request' (duration: 1.349652512s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.205265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:46.855213Z","time spent":"1.349997632s","remote":"127.0.0.1:50416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T06:43:48.205257Z","caller":"traceutil/trace.go:171","msg":"trace[109203330] linearizableReadLoop","detail":"{readStateIndex:19630; appliedIndex:19625; }","duration":"1.434566922s","start":"2026-05-27T06:43:46.770665Z","end":"2026-05-27T06:43:48.205232Z","steps":["trace[109203330] 'read index received' (duration: 1.414752786s)","trace[109203330] 'applied index is now lower than readState.Index' (duration: 19.812856ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:43:48.205404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.434740266s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T06:43:48.20544Z","caller":"traceutil/trace.go:171","msg":"trace[667756878] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18973; }","duration":"1.434812468s","start":"2026-05-27T06:43:46.770618Z","end":"2026-05-27T06:43:48.20543Z","steps":["trace[667756878] 'agreement among raft nodes before linearized reading' (duration: 1.434746126s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.205468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:46.770599Z","time spent":"1.43486324s","remote":"127.0.0.1:50124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T06:43:48.205477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.117427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-05-27T06:43:48.205498Z","caller":"traceutil/trace.go:171","msg":"trace[1120777642] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:18973; }","duration":"280.171889ms","start":"2026-05-27T06:43:47.925317Z","end":"2026-05-27T06:43:48.205489Z","steps":["trace[1120777642] 'agreement among raft nodes before linearized reading' (duration: 280.106327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.205626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.250768079s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-27T06:43:48.205644Z","caller":"traceutil/trace.go:171","msg":"trace[1560764918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18973; }","duration":"1.25082605s","start":"2026-05-27T06:43:46.954814Z","end":"2026-05-27T06:43:48.20564Z","steps":["trace[1560764918] 'agreement among raft nodes before linearized reading' (duration: 1.250772028s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.205408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.394442216s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-27T06:43:48.205656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:46.95479Z","time spent":"1.25086296s","remote":"127.0.0.1:50416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-27T06:43:48.205671Z","caller":"traceutil/trace.go:171","msg":"trace[614233321] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:18973; }","duration":"1.394728905s","start":"2026-05-27T06:43:46.810932Z","end":"2026-05-27T06:43:48.205661Z","steps":["trace[614233321] 'agreement among raft nodes before linearized reading' (duration: 1.394452897s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.205682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.042172672s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-metadata-agent-default-qqt9s\" ","response":"range_response_count:1 size:14278"} {"level":"warn","ts":"2026-05-27T06:43:48.205691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.79601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-l3-agent-default-hbblg\" ","response":"range_response_count:1 size:18671"} {"level":"info","ts":"2026-05-27T06:43:48.205729Z","caller":"traceutil/trace.go:171","msg":"trace[601323905] range","detail":"{range_begin:/registry/pods/openstack/neutron-metadata-agent-default-qqt9s; range_end:; response_count:1; response_revision:18973; }","duration":"1.042258493s","start":"2026-05-27T06:43:47.163459Z","end":"2026-05-27T06:43:48.205717Z","steps":["trace[601323905] 'agreement among raft nodes before linearized reading' (duration: 1.042053457s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.205757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.373710705s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"warn","ts":"2026-05-27T06:43:48.205762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:47.163443Z","time spent":"1.042312415s","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":14300,"request content":"key:\"/registry/pods/openstack/neutron-metadata-agent-default-qqt9s\" "} {"level":"info","ts":"2026-05-27T06:43:48.205777Z","caller":"traceutil/trace.go:171","msg":"trace[1283933382] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18973; }","duration":"1.373753287s","start":"2026-05-27T06:43:46.832019Z","end":"2026-05-27T06:43:48.205773Z","steps":["trace[1283933382] 'agreement among raft nodes before linearized reading' (duration: 1.373702365s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.205794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:46.832007Z","time spent":"1.373781057s","remote":"127.0.0.1:50294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":724,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-05-27T06:43:48.205801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.860861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T06:43:48.20583Z","caller":"traceutil/trace.go:171","msg":"trace[1354522910] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:18973; }","duration":"413.920183ms","start":"2026-05-27T06:43:47.7919Z","end":"2026-05-27T06:43:48.20582Z","steps":["trace[1354522910] 'agreement among raft nodes before linearized reading' (duration: 413.871552ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.20589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.374510427s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"warn","ts":"2026-05-27T06:43:48.205897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:47.791885Z","time spent":"413.991615ms","remote":"127.0.0.1:52072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-05-27T06:43:48.205943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.478759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-7w88w\" ","response":"range_response_count:1 size:20755"} {"level":"info","ts":"2026-05-27T06:43:48.205976Z","caller":"traceutil/trace.go:171","msg":"trace[1676612230] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-7w88w; range_end:; response_count:1; response_revision:18973; }","duration":"981.575822ms","start":"2026-05-27T06:43:47.224391Z","end":"2026-05-27T06:43:48.205967Z","steps":["trace[1676612230] 'agreement among raft nodes before linearized reading' (duration: 981.451519ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:48.205748Z","caller":"traceutil/trace.go:171","msg":"trace[1088224490] range","detail":"{range_begin:/registry/pods/openstack/neutron-l3-agent-default-hbblg; range_end:; response_count:1; response_revision:18973; }","duration":"923.886773ms","start":"2026-05-27T06:43:47.281846Z","end":"2026-05-27T06:43:48.205733Z","steps":["trace[1088224490] 'agreement among raft nodes before linearized reading' (duration: 923.682877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.206018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:47.281839Z","time spent":"924.17429ms","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":18693,"request content":"key:\"/registry/pods/openstack/neutron-l3-agent-default-hbblg\" "} {"level":"warn","ts":"2026-05-27T06:43:48.206014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:47.224369Z","time spent":"981.636814ms","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20777,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-7w88w\" "} {"level":"info","ts":"2026-05-27T06:43:48.205421Z","caller":"traceutil/trace.go:171","msg":"trace[1115822346] transaction","detail":"{read_only:false; response_revision:18973; number_of_response:1; }","duration":"1.336524251s","start":"2026-05-27T06:43:46.868739Z","end":"2026-05-27T06:43:48.205263Z","steps":["trace[1115822346] 'process raft request' (duration: 1.336321405s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.206171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.014232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-4bgs4\" ","response":"range_response_count:1 size:22101"} {"level":"warn","ts":"2026-05-27T06:43:48.206174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.373993033s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-05-27T06:43:48.206202Z","caller":"traceutil/trace.go:171","msg":"trace[516792959] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-4bgs4; range_end:; response_count:1; response_revision:18973; }","duration":"946.044243ms","start":"2026-05-27T06:43:47.26015Z","end":"2026-05-27T06:43:48.206195Z","steps":["trace[516792959] 'agreement among raft nodes before linearized reading' (duration: 945.955911ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:48.206218Z","caller":"traceutil/trace.go:171","msg":"trace[1464921314] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18973; }","duration":"1.374080646s","start":"2026-05-27T06:43:46.832125Z","end":"2026-05-27T06:43:48.206206Z","steps":["trace[1464921314] 'agreement among raft nodes before linearized reading' (duration: 1.373562851s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.206227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:47.260111Z","time spent":"946.110805ms","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22123,"request content":"key:\"/registry/pods/openstack/nova-compute-default-4bgs4\" "} {"level":"warn","ts":"2026-05-27T06:43:48.206293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.170935ms","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-27T06:43:48.206343Z","caller":"traceutil/trace.go:171","msg":"trace[1143929838] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18973; }","duration":"861.270697ms","start":"2026-05-27T06:43:47.345061Z","end":"2026-05-27T06:43:48.206332Z","steps":["trace[1143929838] 'agreement among raft nodes before linearized reading' (duration: 861.088292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.205952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.374760655s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"warn","ts":"2026-05-27T06:43:48.206373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:47.345041Z","time spent":"861.324369ms","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-27T06:43:48.206381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.556203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-4bgs4\" ","response":"range_response_count:1 size:22101"} {"level":"info","ts":"2026-05-27T06:43:48.206394Z","caller":"traceutil/trace.go:171","msg":"trace[1360937673] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18973; }","duration":"1.375183056s","start":"2026-05-27T06:43:46.831188Z","end":"2026-05-27T06:43:48.206371Z","steps":["trace[1360937673] 'agreement among raft nodes before linearized reading' (duration: 1.374737674s)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:48.206414Z","caller":"traceutil/trace.go:171","msg":"trace[815770841] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-4bgs4; range_end:; response_count:1; response_revision:18973; }","duration":"923.596785ms","start":"2026-05-27T06:43:47.282807Z","end":"2026-05-27T06:43:48.206404Z","steps":["trace[815770841] 'agreement among raft nodes before linearized reading' (duration: 923.494112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.206433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:46.83111Z","time spent":"1.37531438s","remote":"127.0.0.1:50294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":724,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-05-27T06:43:48.206445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:47.282799Z","time spent":"923.636726ms","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22123,"request content":"key:\"/registry/pods/openstack/nova-compute-default-4bgs4\" "} {"level":"info","ts":"2026-05-27T06:43:48.205916Z","caller":"traceutil/trace.go:171","msg":"trace[1056075860] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18973; }","duration":"1.374581229s","start":"2026-05-27T06:43:46.831331Z","end":"2026-05-27T06:43:48.205912Z","steps":["trace[1056075860] 'agreement among raft nodes before linearized reading' (duration: 1.374529608s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.206533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:46.831309Z","time spent":"1.375215226s","remote":"127.0.0.1:50294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":724,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-05-27T06:43:48.205974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"982.606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-dhcp-agent-default-f5fcn\" ","response":"range_response_count:1 size:18647"} {"level":"warn","ts":"2026-05-27T06:43:48.206475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:46.832111Z","time spent":"1.374136447s","remote":"127.0.0.1:50294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":724,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"info","ts":"2026-05-27T06:43:48.206607Z","caller":"traceutil/trace.go:171","msg":"trace[1261934577] range","detail":"{range_begin:/registry/pods/openstack/neutron-dhcp-agent-default-f5fcn; range_end:; response_count:1; response_revision:18973; }","duration":"983.264758ms","start":"2026-05-27T06:43:47.223332Z","end":"2026-05-27T06:43:48.206597Z","steps":["trace[1261934577] 'agreement among raft nodes before linearized reading' (duration: 982.565599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.206621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.839396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-bt4wn\" ","response":"range_response_count:1 size:9789"} {"level":"warn","ts":"2026-05-27T06:43:48.20664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:47.223311Z","time spent":"983.31996ms","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":18669,"request content":"key:\"/registry/pods/openstack/neutron-dhcp-agent-default-f5fcn\" "} {"level":"info","ts":"2026-05-27T06:43:48.206647Z","caller":"traceutil/trace.go:171","msg":"trace[339646185] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-bt4wn; range_end:; response_count:1; response_revision:18973; }","duration":"921.875797ms","start":"2026-05-27T06:43:47.284763Z","end":"2026-05-27T06:43:48.206639Z","steps":["trace[339646185] 'agreement among raft nodes before linearized reading' (duration: 921.802245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.206672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:47.284756Z","time spent":"921.909988ms","remote":"127.0.0.1:50316","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-bt4wn\" "} {"level":"warn","ts":"2026-05-27T06:43:48.206714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:46.868714Z","time spent":"1.337416235s","remote":"127.0.0.1:50416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T06:43:48.205707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:46.810916Z","time spent":"1.394779666s","remote":"127.0.0.1:50214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-05-27T06:43:48.206947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.326631758s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-27T06:43:48.206984Z","caller":"traceutil/trace.go:171","msg":"trace[59403487] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18973; }","duration":"1.32670969s","start":"2026-05-27T06:43:46.880266Z","end":"2026-05-27T06:43:48.206976Z","steps":["trace[59403487] 'agreement among raft nodes before linearized reading' (duration: 1.326621027s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.206999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.325855606s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-27T06:43:48.207019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:46.880248Z","time spent":"1.326761982s","remote":"127.0.0.1:50416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-27T06:43:48.207033Z","caller":"traceutil/trace.go:171","msg":"trace[859816308] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18973; }","duration":"1.325929679s","start":"2026-05-27T06:43:46.881094Z","end":"2026-05-27T06:43:48.207023Z","steps":["trace[859816308] 'agreement among raft nodes before linearized reading' (duration: 1.325840257s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.20706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:46.880977Z","time spent":"1.326077853s","remote":"127.0.0.1:50416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-27T06:43:48.206056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025749799s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lh75v\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-05-27T06:43:48.207155Z","caller":"traceutil/trace.go:171","msg":"trace[1962508080] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lh75v; range_end:; response_count:1; response_revision:18973; }","duration":"1.026867029s","start":"2026-05-27T06:43:47.180279Z","end":"2026-05-27T06:43:48.207147Z","steps":["trace[1962508080] 'agreement among raft nodes before linearized reading' (duration: 1.025717358s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.207182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:47.180269Z","time spent":"1.026904791s","remote":"127.0.0.1:50316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-lh75v\" "} {"level":"warn","ts":"2026-05-27T06:43:48.20724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.367393881s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-05-27T06:43:48.207284Z","caller":"traceutil/trace.go:171","msg":"trace[1656617135] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18973; }","duration":"1.367461123s","start":"2026-05-27T06:43:46.839812Z","end":"2026-05-27T06:43:48.207273Z","steps":["trace[1656617135] 'agreement among raft nodes before linearized reading' (duration: 1.36734391s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.207314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:46.839799Z","time spent":"1.367508234s","remote":"127.0.0.1:50294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":724,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-05-27T06:43:48.206879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.252057864s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-27T06:43:48.208841Z","caller":"traceutil/trace.go:171","msg":"trace[1838224295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18973; }","duration":"1.254058889s","start":"2026-05-27T06:43:46.954768Z","end":"2026-05-27T06:43:48.208827Z","steps":["trace[1838224295] 'agreement among raft nodes before linearized reading' (duration: 1.252010162s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.208879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:46.954744Z","time spent":"1.254121691s","remote":"127.0.0.1:50416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-27T06:43:48.333911Z","caller":"traceutil/trace.go:171","msg":"trace[2074368712] transaction","detail":"{read_only:false; response_revision:18975; number_of_response:1; }","duration":"122.417143ms","start":"2026-05-27T06:43:48.21146Z","end":"2026-05-27T06:43:48.333878Z","steps":["trace[2074368712] 'process raft request' (duration: 104.90463ms)","trace[2074368712] 'compare' (duration: 17.194404ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:43:48.333934Z","caller":"traceutil/trace.go:171","msg":"trace[563165] linearizableReadLoop","detail":"{readStateIndex:19633; appliedIndex:19630; }","duration":"121.067865ms","start":"2026-05-27T06:43:48.212837Z","end":"2026-05-27T06:43:48.333905Z","steps":["trace[563165] 'read index received' (duration: 20.041453ms)","trace[563165] 'applied index is now lower than readState.Index' (duration: 101.025342ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:43:48.333947Z","caller":"traceutil/trace.go:171","msg":"trace[1784604162] transaction","detail":"{read_only:false; response_revision:18977; number_of_response:1; }","duration":"119.951885ms","start":"2026-05-27T06:43:48.21397Z","end":"2026-05-27T06:43:48.333922Z","steps":["trace[1784604162] 'process raft request' (duration: 119.895063ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:48.333935Z","caller":"traceutil/trace.go:171","msg":"trace[233517917] transaction","detail":"{read_only:false; response_revision:18976; number_of_response:1; }","duration":"122.33092ms","start":"2026-05-27T06:43:48.211581Z","end":"2026-05-27T06:43:48.333912Z","steps":["trace[233517917] 'process raft request' (duration: 122.193346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.334389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.095033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T06:43:48.334434Z","caller":"traceutil/trace.go:171","msg":"trace[1675362030] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18977; }","duration":"118.163646ms","start":"2026-05-27T06:43:48.216259Z","end":"2026-05-27T06:43:48.334423Z","steps":["trace[1675362030] 'agreement among raft nodes before linearized reading' (duration: 118.077313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.33447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.375846ms","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-27T06:43:48.334548Z","caller":"traceutil/trace.go:171","msg":"trace[1179191468] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18977; }","duration":"120.49479ms","start":"2026-05-27T06:43:48.214037Z","end":"2026-05-27T06:43:48.334531Z","steps":["trace[1179191468] 'agreement among raft nodes before linearized reading' (duration: 120.243683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.3355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.637779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:945728"} {"level":"info","ts":"2026-05-27T06:43:48.335572Z","caller":"traceutil/trace.go:171","msg":"trace[1368345589] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:18977; }","duration":"122.742982ms","start":"2026-05-27T06:43:48.212812Z","end":"2026-05-27T06:43:48.335555Z","steps":["trace[1368345589] 'agreement among raft nodes before linearized reading' (duration: 121.22052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.335957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.332903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:945728"} {"level":"info","ts":"2026-05-27T06:43:48.336008Z","caller":"traceutil/trace.go:171","msg":"trace[1379759907] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:18977; }","duration":"121.415635ms","start":"2026-05-27T06:43:48.21458Z","end":"2026-05-27T06:43:48.335996Z","steps":["trace[1379759907] 'agreement among raft nodes before linearized reading' (duration: 120.060748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.336226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.429475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:945728"} {"level":"info","ts":"2026-05-27T06:43:48.336352Z","caller":"traceutil/trace.go:171","msg":"trace[1833207500] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:18977; }","duration":"121.540569ms","start":"2026-05-27T06:43:48.214767Z","end":"2026-05-27T06:43:48.336308Z","steps":["trace[1833207500] 'agreement among raft nodes before linearized reading' (duration: 119.644896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.337189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.363974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:945728"} {"level":"warn","ts":"2026-05-27T06:43:48.33723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.2302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:945728"} {"level":"info","ts":"2026-05-27T06:43:48.337247Z","caller":"traceutil/trace.go:171","msg":"trace[1894034450] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:18977; }","duration":"117.442095ms","start":"2026-05-27T06:43:48.219791Z","end":"2026-05-27T06:43:48.337233Z","steps":["trace[1894034450] 'agreement among raft nodes before linearized reading' (duration: 114.557356ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:48.337261Z","caller":"traceutil/trace.go:171","msg":"trace[1145203608] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:18977; }","duration":"121.274401ms","start":"2026-05-27T06:43:48.215979Z","end":"2026-05-27T06:43:48.337253Z","steps":["trace[1145203608] 'agreement among raft nodes before linearized reading' (duration: 118.377991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.337385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.357294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:945728"} {"level":"info","ts":"2026-05-27T06:43:48.337444Z","caller":"traceutil/trace.go:171","msg":"trace[1908444636] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:18977; }","duration":"121.430546ms","start":"2026-05-27T06:43:48.216Z","end":"2026-05-27T06:43:48.337431Z","steps":["trace[1908444636] 'agreement among raft nodes before linearized reading' (duration: 118.636559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.337583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.240818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:945728"} {"level":"warn","ts":"2026-05-27T06:43:48.337664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.236113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:945728"} {"level":"info","ts":"2026-05-27T06:43:48.337727Z","caller":"traceutil/trace.go:171","msg":"trace[117148205] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:18977; }","duration":"118.409393ms","start":"2026-05-27T06:43:48.219298Z","end":"2026-05-27T06:43:48.337708Z","steps":["trace[117148205] 'agreement among raft nodes before linearized reading' (duration: 115.06163ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:48.338333Z","caller":"traceutil/trace.go:171","msg":"trace[1618911073] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:18977; }","duration":"120.364657ms","start":"2026-05-27T06:43:48.217386Z","end":"2026-05-27T06:43:48.337751Z","steps":["trace[1618911073] 'agreement among raft nodes before linearized reading' (duration: 117.185379ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:50.315753Z","caller":"traceutil/trace.go:171","msg":"trace[455621691] transaction","detail":"{read_only:false; response_revision:18980; number_of_response:1; }","duration":"105.573107ms","start":"2026-05-27T06:43:50.210154Z","end":"2026-05-27T06:43:50.315727Z","steps":["trace[455621691] 'process raft request' (duration: 90.232304ms)","trace[455621691] 'compare' (duration: 15.23283ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:43:50.334054Z","caller":"traceutil/trace.go:171","msg":"trace[1673863680] linearizableReadLoop","detail":"{readStateIndex:19640; appliedIndex:19637; }","duration":"122.807242ms","start":"2026-05-27T06:43:50.211226Z","end":"2026-05-27T06:43:50.334033Z","steps":["trace[1673863680] 'read index received' (duration: 89.173846ms)","trace[1673863680] 'applied index is now lower than readState.Index' (duration: 33.632466ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:43:50.334163Z","caller":"traceutil/trace.go:171","msg":"trace[419791687] transaction","detail":"{read_only:false; response_revision:18982; number_of_response:1; }","duration":"122.956287ms","start":"2026-05-27T06:43:50.211196Z","end":"2026-05-27T06:43:50.334152Z","steps":["trace[419791687] 'process raft request' (duration: 122.760981ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:50.334411Z","caller":"traceutil/trace.go:171","msg":"trace[340798709] transaction","detail":"{read_only:false; response_revision:18983; number_of_response:1; }","duration":"122.420791ms","start":"2026-05-27T06:43:50.211976Z","end":"2026-05-27T06:43:50.334397Z","steps":["trace[340798709] 'process raft request' (duration: 122.01882ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:50.334658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.355027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-05-27T06:43:50.334728Z","caller":"traceutil/trace.go:171","msg":"trace[1406797423] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18983; }","duration":"123.45222ms","start":"2026-05-27T06:43:50.211262Z","end":"2026-05-27T06:43:50.334714Z","steps":["trace[1406797423] 'agreement among raft nodes before linearized reading' (duration: 123.271115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:50.335039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.80647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"warn","ts":"2026-05-27T06:43:50.335085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.842381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-05-27T06:43:50.335099Z","caller":"traceutil/trace.go:171","msg":"trace[263557750] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18983; }","duration":"119.891441ms","start":"2026-05-27T06:43:50.215194Z","end":"2026-05-27T06:43:50.335085Z","steps":["trace[263557750] 'agreement among raft nodes before linearized reading' (duration: 119.752148ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:50.335128Z","caller":"traceutil/trace.go:171","msg":"trace[780156436] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18983; }","duration":"123.923883ms","start":"2026-05-27T06:43:50.211193Z","end":"2026-05-27T06:43:50.335117Z","steps":["trace[780156436] 'agreement among raft nodes before linearized reading' (duration: 123.80844ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:50.334073Z","caller":"traceutil/trace.go:171","msg":"trace[1505560734] transaction","detail":"{read_only:false; response_revision:18981; number_of_response:1; }","duration":"122.935266ms","start":"2026-05-27T06:43:50.211114Z","end":"2026-05-27T06:43:50.33405Z","steps":["trace[1505560734] 'process raft request' (duration: 122.72266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:50.33537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.070037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-05-27T06:43:50.335435Z","caller":"traceutil/trace.go:171","msg":"trace[165664446] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18983; }","duration":"124.1555ms","start":"2026-05-27T06:43:50.211262Z","end":"2026-05-27T06:43:50.335417Z","steps":["trace[165664446] 'agreement among raft nodes before linearized reading' (duration: 123.999015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:50.335622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.409906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-05-27T06:43:50.335682Z","caller":"traceutil/trace.go:171","msg":"trace[444863436] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18983; }","duration":"120.484048ms","start":"2026-05-27T06:43:50.21518Z","end":"2026-05-27T06:43:50.335664Z","steps":["trace[444863436] 'agreement among raft nodes before linearized reading' (duration: 120.363965ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:50.480598Z","caller":"traceutil/trace.go:171","msg":"trace[1841650105] transaction","detail":"{read_only:false; response_revision:18986; number_of_response:1; }","duration":"138.190178ms","start":"2026-05-27T06:43:50.342386Z","end":"2026-05-27T06:43:50.480576Z","steps":["trace[1841650105] 'process raft request' (duration: 138.098975ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:50.480652Z","caller":"traceutil/trace.go:171","msg":"trace[813705677] transaction","detail":"{read_only:false; response_revision:18985; number_of_response:1; }","duration":"138.330411ms","start":"2026-05-27T06:43:50.342288Z","end":"2026-05-27T06:43:50.480619Z","steps":["trace[813705677] 'process raft request' (duration: 116.653284ms)","trace[813705677] 'compare' (duration: 21.41778ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:43:50.480781Z","caller":"traceutil/trace.go:171","msg":"trace[1076230125] transaction","detail":"{read_only:false; response_revision:18987; number_of_response:1; }","duration":"137.875598ms","start":"2026-05-27T06:43:50.342871Z","end":"2026-05-27T06:43:50.480746Z","steps":["trace[1076230125] 'process raft request' (duration: 137.666703ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:52.382401Z","caller":"traceutil/trace.go:171","msg":"trace[423627211] transaction","detail":"{read_only:false; response_revision:18988; number_of_response:1; }","duration":"180.308318ms","start":"2026-05-27T06:43:52.202072Z","end":"2026-05-27T06:43:52.382381Z","steps":["trace[423627211] 'process raft request' (duration: 180.169774ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:52.383065Z","caller":"traceutil/trace.go:171","msg":"trace[376892938] linearizableReadLoop","detail":"{readStateIndex:19646; appliedIndex:19646; }","duration":"171.432503ms","start":"2026-05-27T06:43:52.211611Z","end":"2026-05-27T06:43:52.383044Z","steps":["trace[376892938] 'read index received' (duration: 171.423273ms)","trace[376892938] 'applied index is now lower than readState.Index' (duration: 7.61µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:43:52.383292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.66152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-27T06:43:52.383344Z","caller":"traceutil/trace.go:171","msg":"trace[1260927070] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18988; }","duration":"171.770413ms","start":"2026-05-27T06:43:52.211559Z","end":"2026-05-27T06:43:52.383329Z","steps":["trace[1260927070] 'agreement among raft nodes before linearized reading' (duration: 171.587238ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:52.552414Z","caller":"traceutil/trace.go:171","msg":"trace[382686279] transaction","detail":"{read_only:false; response_revision:18989; number_of_response:1; }","duration":"273.776952ms","start":"2026-05-27T06:43:52.278612Z","end":"2026-05-27T06:43:52.552389Z","steps":["trace[382686279] 'process raft request' (duration: 229.991496ms)","trace[382686279] 'compare' (duration: 43.506189ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:43:52.552713Z","caller":"traceutil/trace.go:171","msg":"trace[571728259] transaction","detail":"{read_only:false; response_revision:18991; number_of_response:1; }","duration":"210.028136ms","start":"2026-05-27T06:43:52.342673Z","end":"2026-05-27T06:43:52.552701Z","steps":["trace[571728259] 'process raft request' (duration: 209.936614ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:52.552803Z","caller":"traceutil/trace.go:171","msg":"trace[968899404] transaction","detail":"{read_only:false; response_revision:18990; number_of_response:1; }","duration":"231.993162ms","start":"2026-05-27T06:43:52.320802Z","end":"2026-05-27T06:43:52.552795Z","steps":["trace[968899404] 'process raft request' (duration: 231.527709ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:52.553087Z","caller":"traceutil/trace.go:171","msg":"trace[43022779] transaction","detail":"{read_only:false; response_revision:18992; number_of_response:1; }","duration":"208.871104ms","start":"2026-05-27T06:43:52.344196Z","end":"2026-05-27T06:43:52.553067Z","steps":["trace[43022779] 'process raft request' (duration: 208.466493ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:52.553392Z","caller":"traceutil/trace.go:171","msg":"trace[1689700340] linearizableReadLoop","detail":"{readStateIndex:19651; appliedIndex:19646; }","duration":"170.250451ms","start":"2026-05-27T06:43:52.383124Z","end":"2026-05-27T06:43:52.553374Z","steps":["trace[1689700340] 'read index received' (duration: 125.497407ms)","trace[1689700340] 'applied index is now lower than readState.Index' (duration: 44.751744ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:43:52.553552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.679029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-05-27T06:43:52.55364Z","caller":"traceutil/trace.go:171","msg":"trace[1590258753] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18993; }","duration":"208.785013ms","start":"2026-05-27T06:43:52.344806Z","end":"2026-05-27T06:43:52.553591Z","steps":["trace[1590258753] 'agreement among raft nodes before linearized reading' (duration: 208.597437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:52.553792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.473121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-05-27T06:43:52.553842Z","caller":"traceutil/trace.go:171","msg":"trace[2081901671] transaction","detail":"{read_only:false; response_revision:18993; number_of_response:1; }","duration":"209.412069ms","start":"2026-05-27T06:43:52.344419Z","end":"2026-05-27T06:43:52.553831Z","steps":["trace[2081901671] 'process raft request' (duration: 208.327249ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:52.553859Z","caller":"traceutil/trace.go:171","msg":"trace[1345493140] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18993; }","duration":"209.587335ms","start":"2026-05-27T06:43:52.344259Z","end":"2026-05-27T06:43:52.553847Z","steps":["trace[1345493140] 'agreement among raft nodes before linearized reading' (duration: 209.43062ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:52.553989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.936858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-27T06:43:52.554021Z","caller":"traceutil/trace.go:171","msg":"trace[343632304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18993; }","duration":"188.00779ms","start":"2026-05-27T06:43:52.366005Z","end":"2026-05-27T06:43:52.554013Z","steps":["trace[343632304] 'agreement among raft nodes before linearized reading' (duration: 187.914878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:52.554097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.396214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-05-27T06:43:52.554132Z","caller":"traceutil/trace.go:171","msg":"trace[228435267] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18993; }","duration":"211.439755ms","start":"2026-05-27T06:43:52.342683Z","end":"2026-05-27T06:43:52.554123Z","steps":["trace[228435267] 'agreement among raft nodes before linearized reading' (duration: 211.349763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:52.554258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.579839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-05-27T06:43:52.55429Z","caller":"traceutil/trace.go:171","msg":"trace[735223888] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18993; }","duration":"211.622961ms","start":"2026-05-27T06:43:52.34266Z","end":"2026-05-27T06:43:52.554282Z","steps":["trace[735223888] 'agreement among raft nodes before linearized reading' (duration: 211.543458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:52.554398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.750084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-05-27T06:43:52.554436Z","caller":"traceutil/trace.go:171","msg":"trace[2077600351] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18993; }","duration":"211.821456ms","start":"2026-05-27T06:43:52.342606Z","end":"2026-05-27T06:43:52.554427Z","steps":["trace[2077600351] 'agreement among raft nodes before linearized reading' (duration: 211.726793ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:52.585897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.812772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18620"} {"level":"info","ts":"2026-05-27T06:43:52.585975Z","caller":"traceutil/trace.go:171","msg":"trace[974365579] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:18994; }","duration":"196.924215ms","start":"2026-05-27T06:43:52.389033Z","end":"2026-05-27T06:43:52.585957Z","steps":["trace[974365579] 'agreement among raft nodes before linearized reading' (duration: 196.672828ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:52.586281Z","caller":"traceutil/trace.go:171","msg":"trace[1361014638] transaction","detail":"{read_only:false; response_revision:18994; number_of_response:1; }","duration":"195.345222ms","start":"2026-05-27T06:43:52.390908Z","end":"2026-05-27T06:43:52.586253Z","steps":["trace[1361014638] 'process raft request' (duration: 194.647523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:52.586671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.640852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-27T06:43:52.586713Z","caller":"traceutil/trace.go:171","msg":"trace[456750843] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18994; }","duration":"100.693283ms","start":"2026-05-27T06:43:52.486009Z","end":"2026-05-27T06:43:52.586702Z","steps":["trace[456750843] 'agreement among raft nodes before linearized reading' (duration: 100.606371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:52.586887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.906337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-27T06:43:52.586927Z","caller":"traceutil/trace.go:171","msg":"trace[1965087198] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18994; }","duration":"101.955268ms","start":"2026-05-27T06:43:52.484961Z","end":"2026-05-27T06:43:52.586916Z","steps":["trace[1965087198] 'agreement among raft nodes before linearized reading' (duration: 101.871796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:52.587124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.564785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-27T06:43:52.587156Z","caller":"traceutil/trace.go:171","msg":"trace[674985362] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18994; }","duration":"102.627457ms","start":"2026-05-27T06:43:52.484521Z","end":"2026-05-27T06:43:52.587148Z","steps":["trace[674985362] 'agreement among raft nodes before linearized reading' (duration: 102.543654ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:52.704549Z","caller":"traceutil/trace.go:171","msg":"trace[1897433053] transaction","detail":"{read_only:false; response_revision:18996; number_of_response:1; }","duration":"106.624498ms","start":"2026-05-27T06:43:52.597895Z","end":"2026-05-27T06:43:52.70452Z","steps":["trace[1897433053] 'process raft request' (duration: 63.288214ms)","trace[1897433053] 'compare' (duration: 43.142978ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:43:52.70482Z","caller":"traceutil/trace.go:171","msg":"trace[1438362903] linearizableReadLoop","detail":"{readStateIndex:19655; appliedIndex:19653; }","duration":"106.812913ms","start":"2026-05-27T06:43:52.597975Z","end":"2026-05-27T06:43:52.704788Z","steps":["trace[1438362903] 'read index received' (duration: 17.085251ms)","trace[1438362903] 'applied index is now lower than readState.Index' (duration: 89.726532ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:43:52.704874Z","caller":"traceutil/trace.go:171","msg":"trace[934750583] transaction","detail":"{read_only:false; response_revision:18998; number_of_response:1; }","duration":"105.438365ms","start":"2026-05-27T06:43:52.599418Z","end":"2026-05-27T06:43:52.704857Z","steps":["trace[934750583] 'process raft request' (duration: 105.225329ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:52.705013Z","caller":"traceutil/trace.go:171","msg":"trace[589722973] transaction","detail":"{read_only:false; response_revision:18997; number_of_response:1; }","duration":"107.038999ms","start":"2026-05-27T06:43:52.597961Z","end":"2026-05-27T06:43:52.705Z","steps":["trace[589722973] 'process raft request' (duration: 106.517334ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:52.710111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.095278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:945728"} {"level":"info","ts":"2026-05-27T06:43:52.710401Z","caller":"traceutil/trace.go:171","msg":"trace[1660443571] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:18998; }","duration":"112.432067ms","start":"2026-05-27T06:43:52.597931Z","end":"2026-05-27T06:43:52.710363Z","steps":["trace[1660443571] 'agreement among raft nodes before linearized reading' (duration: 107.014298ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:52.715526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.349463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:945728"} {"level":"info","ts":"2026-05-27T06:43:52.715612Z","caller":"traceutil/trace.go:171","msg":"trace[2066845244] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:18998; }","duration":"117.445205ms","start":"2026-05-27T06:43:52.598139Z","end":"2026-05-27T06:43:52.715584Z","steps":["trace[2066845244] 'agreement among raft nodes before linearized reading' (duration: 110.271678ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:52.717126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.043149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:945728"} {"level":"info","ts":"2026-05-27T06:43:52.717166Z","caller":"traceutil/trace.go:171","msg":"trace[1648732571] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:18998; }","duration":"119.097481ms","start":"2026-05-27T06:43:52.598054Z","end":"2026-05-27T06:43:52.717152Z","steps":["trace[1648732571] 'agreement among raft nodes before linearized reading' (duration: 106.911096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:52.721985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.852312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:945728"} {"level":"info","ts":"2026-05-27T06:43:52.723009Z","caller":"traceutil/trace.go:171","msg":"trace[145938751] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:18998; }","duration":"124.88644ms","start":"2026-05-27T06:43:52.598111Z","end":"2026-05-27T06:43:52.722997Z","steps":["trace[145938751] 'agreement among raft nodes before linearized reading' (duration: 107.488891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:52.723468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.341963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:945728"} {"level":"info","ts":"2026-05-27T06:43:52.723489Z","caller":"traceutil/trace.go:171","msg":"trace[536137116] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:18998; }","duration":"125.367584ms","start":"2026-05-27T06:43:52.598116Z","end":"2026-05-27T06:43:52.723483Z","steps":["trace[536137116] 'agreement among raft nodes before linearized reading' (duration: 107.45462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:52.723792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.661672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:945728"} {"level":"info","ts":"2026-05-27T06:43:52.72381Z","caller":"traceutil/trace.go:171","msg":"trace[96007123] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:18998; }","duration":"125.685083ms","start":"2026-05-27T06:43:52.598119Z","end":"2026-05-27T06:43:52.723804Z","steps":["trace[96007123] 'agreement among raft nodes before linearized reading' (duration: 107.214994ms)","trace[96007123] 'range keys from bolt db' (duration: 18.408967ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:43:52.72334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.241331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:945728"} {"level":"info","ts":"2026-05-27T06:43:52.724545Z","caller":"traceutil/trace.go:171","msg":"trace[1030572976] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:18998; }","duration":"126.456144ms","start":"2026-05-27T06:43:52.598082Z","end":"2026-05-27T06:43:52.724538Z","steps":["trace[1030572976] 'agreement among raft nodes before linearized reading' (duration: 119.481562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:52.723717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.61661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:945728"} {"level":"info","ts":"2026-05-27T06:43:52.724921Z","caller":"traceutil/trace.go:171","msg":"trace[964011737] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:18998; }","duration":"126.828304ms","start":"2026-05-27T06:43:52.598088Z","end":"2026-05-27T06:43:52.724916Z","steps":["trace[964011737] 'agreement among raft nodes before linearized reading' (duration: 119.469592ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:54.706876Z","caller":"traceutil/trace.go:171","msg":"trace[1158118512] transaction","detail":"{read_only:false; response_revision:19004; number_of_response:1; }","duration":"101.763723ms","start":"2026-05-27T06:43:54.605075Z","end":"2026-05-27T06:43:54.706839Z","steps":["trace[1158118512] 'process raft request' (duration: 79.161021ms)","trace[1158118512] 'compare' (duration: 22.084788ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:43:54.919223Z","caller":"traceutil/trace.go:171","msg":"trace[1172897896] transaction","detail":"{read_only:false; response_revision:19006; number_of_response:1; }","duration":"205.897163ms","start":"2026-05-27T06:43:54.713305Z","end":"2026-05-27T06:43:54.919202Z","steps":["trace[1172897896] 'process raft request' (duration: 126.553597ms)","trace[1172897896] 'compare' (duration: 79.199762ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:43:54.919431Z","caller":"traceutil/trace.go:171","msg":"trace[1936483248] linearizableReadLoop","detail":"{readStateIndex:19667; appliedIndex:19663; }","duration":"178.227271ms","start":"2026-05-27T06:43:54.741184Z","end":"2026-05-27T06:43:54.919411Z","steps":["trace[1936483248] 'read index received' (duration: 38.518682ms)","trace[1936483248] 'applied index is now lower than readState.Index' (duration: 139.707689ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:43:54.919475Z","caller":"traceutil/trace.go:171","msg":"trace[1306227029] transaction","detail":"{read_only:false; response_revision:19007; number_of_response:1; }","duration":"205.982355ms","start":"2026-05-27T06:43:54.713465Z","end":"2026-05-27T06:43:54.919447Z","steps":["trace[1306227029] 'process raft request' (duration: 205.706677ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:54.919475Z","caller":"traceutil/trace.go:171","msg":"trace[1755167251] transaction","detail":"{read_only:false; response_revision:19008; number_of_response:1; }","duration":"204.915476ms","start":"2026-05-27T06:43:54.714533Z","end":"2026-05-27T06:43:54.919449Z","steps":["trace[1755167251] 'process raft request' (duration: 204.789822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:54.919631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.441036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-7w88w\" ","response":"range_response_count:1 size:20755"} {"level":"info","ts":"2026-05-27T06:43:54.919661Z","caller":"traceutil/trace.go:171","msg":"trace[122483514] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-7w88w; range_end:; response_count:1; response_revision:19008; }","duration":"178.504398ms","start":"2026-05-27T06:43:54.741148Z","end":"2026-05-27T06:43:54.919652Z","steps":["trace[122483514] 'agreement among raft nodes before linearized reading' (duration: 178.386645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:54.919678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.820288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-metadata-agent-default-qqt9s\" ","response":"range_response_count:1 size:14278"} {"level":"info","ts":"2026-05-27T06:43:54.919729Z","caller":"traceutil/trace.go:171","msg":"trace[1269172765] range","detail":"{range_begin:/registry/pods/openstack/neutron-metadata-agent-default-qqt9s; range_end:; response_count:1; response_revision:19008; }","duration":"169.899431ms","start":"2026-05-27T06:43:54.749811Z","end":"2026-05-27T06:43:54.919711Z","steps":["trace[1269172765] 'agreement among raft nodes before linearized reading' (duration: 169.758227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:54.919755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.550335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lh75v\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-05-27T06:43:54.919803Z","caller":"traceutil/trace.go:171","msg":"trace[680015949] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lh75v; range_end:; response_count:1; response_revision:19008; }","duration":"159.632178ms","start":"2026-05-27T06:43:54.760157Z","end":"2026-05-27T06:43:54.919789Z","steps":["trace[680015949] 'agreement among raft nodes before linearized reading' (duration: 159.465213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:54.919818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.507749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-4bgs4\" ","response":"range_response_count:1 size:22101"} {"level":"warn","ts":"2026-05-27T06:43:54.919839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.248235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-bt4wn\" ","response":"range_response_count:1 size:9789"} {"level":"info","ts":"2026-05-27T06:43:54.919875Z","caller":"traceutil/trace.go:171","msg":"trace[830973504] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-4bgs4; range_end:; response_count:1; response_revision:19008; }","duration":"153.592431ms","start":"2026-05-27T06:43:54.766266Z","end":"2026-05-27T06:43:54.919858Z","steps":["trace[830973504] 'agreement among raft nodes before linearized reading' (duration: 153.426216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:54.919889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.595587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-l3-agent-default-hbblg\" ","response":"range_response_count:1 size:18671"} {"level":"info","ts":"2026-05-27T06:43:54.91992Z","caller":"traceutil/trace.go:171","msg":"trace[313956078] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-bt4wn; range_end:; response_count:1; response_revision:19008; }","duration":"160.377328ms","start":"2026-05-27T06:43:54.759522Z","end":"2026-05-27T06:43:54.9199Z","steps":["trace[313956078] 'agreement among raft nodes before linearized reading' (duration: 160.138112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:54.919933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.478736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-dhcp-agent-default-f5fcn\" ","response":"range_response_count:1 size:18647"} {"level":"info","ts":"2026-05-27T06:43:54.919992Z","caller":"traceutil/trace.go:171","msg":"trace[575748701] range","detail":"{range_begin:/registry/pods/openstack/neutron-dhcp-agent-default-f5fcn; range_end:; response_count:1; response_revision:19008; }","duration":"158.560928ms","start":"2026-05-27T06:43:54.761417Z","end":"2026-05-27T06:43:54.919978Z","steps":["trace[575748701] 'agreement among raft nodes before linearized reading' (duration: 158.426735ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:54.920001Z","caller":"traceutil/trace.go:171","msg":"trace[649879077] range","detail":"{range_begin:/registry/pods/openstack/neutron-l3-agent-default-hbblg; range_end:; response_count:1; response_revision:19008; }","duration":"159.738841ms","start":"2026-05-27T06:43:54.760191Z","end":"2026-05-27T06:43:54.91993Z","steps":["trace[649879077] 'agreement among raft nodes before linearized reading' (duration: 159.455873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:54.920478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.34579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-4bgs4\" ","response":"range_response_count:1 size:22101"} {"level":"info","ts":"2026-05-27T06:43:54.920541Z","caller":"traceutil/trace.go:171","msg":"trace[1467118488] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-4bgs4; range_end:; response_count:1; response_revision:19008; }","duration":"159.472743ms","start":"2026-05-27T06:43:54.761053Z","end":"2026-05-27T06:43:54.920526Z","steps":["trace[1467118488] 'agreement among raft nodes before linearized reading' (duration: 158.547788ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:44:20.397035Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17387} {"level":"info","ts":"2026-05-27T06:44:20.490581Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17387,"took":"90.652948ms","hash":185930194,"current-db-size-bytes":58961920,"current-db-size":"59 MB","current-db-size-in-use-bytes":29040640,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-27T06:44:20.490651Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":185930194,"revision":17387,"compact-revision":14921} {"level":"info","ts":"2026-05-27T06:44:56.99499Z","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-27T06:44:57.005644Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-27T06:44:57.005716Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-27T06:49:20.403725Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19133} {"level":"info","ts":"2026-05-27T06:49:20.491399Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19133,"took":"84.309903ms","hash":3934586367,"current-db-size-bytes":58961920,"current-db-size":"59 MB","current-db-size-in-use-bytes":23654400,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-27T06:49:20.491463Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3934586367,"revision":19133,"compact-revision":17387}