{"level":"warn","ts":"2026-08-20T01:19:04.83759Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-20T01:19:04.837673Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.186:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.186:2380","--initial-cluster=instance=https://199.19.213.186:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.186:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.186:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-20T01:19:04.837964Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-20T01:19:04.837988Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.186:2380"]} {"level":"info","ts":"2026-08-20T01:19:04.83806Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-20T01:19:04.838765Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.186:2379"]} {"level":"info","ts":"2026-08-20T01:19:04.838838Z","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.19.213.186:2380"],"listen-peer-urls":["https://199.19.213.186:2380"],"advertise-client-urls":["https://199.19.213.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.186:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-20T01:19:04.841822Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.695511ms"} {"level":"info","ts":"2026-08-20T01:19:04.848908Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"22478416d949d47d","cluster-id":"568bb29601528c07"} {"level":"info","ts":"2026-08-20T01:19:04.849115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d switched to configuration voters=()"} {"level":"info","ts":"2026-08-20T01:19:04.849163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became follower at term 0"} {"level":"info","ts":"2026-08-20T01:19:04.849225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 22478416d949d47d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-20T01:19:04.84925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became follower at term 1"} {"level":"info","ts":"2026-08-20T01:19:04.849381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d switched to configuration voters=(2470088154305647741)"} {"level":"warn","ts":"2026-08-20T01:19:04.859378Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-20T01:19:04.861146Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-20T01:19:04.862684Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-20T01:19:04.865444Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"22478416d949d47d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-20T01:19:04.866104Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"22478416d949d47d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-20T01:19:04.866312Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-20T01:19:04.866424Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-20T01:19:04.866447Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-20T01:19:04.868598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d switched to configuration voters=(2470088154305647741)"} {"level":"info","ts":"2026-08-20T01:19:04.86873Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"568bb29601528c07","local-member-id":"22478416d949d47d","added-peer-id":"22478416d949d47d","added-peer-peer-urls":["https://199.19.213.186:2380"]} {"level":"info","ts":"2026-08-20T01:19:04.871235Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-20T01:19:04.871358Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.186:2380"} {"level":"info","ts":"2026-08-20T01:19:04.871453Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.186:2380"} {"level":"info","ts":"2026-08-20T01:19:04.871577Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"22478416d949d47d","initial-advertise-peer-urls":["https://199.19.213.186:2380"],"listen-peer-urls":["https://199.19.213.186:2380"],"advertise-client-urls":["https://199.19.213.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-20T01:19:04.871646Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-20T01:19:05.350277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d is starting a new election at term 1"} {"level":"info","ts":"2026-08-20T01:19:05.350345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became pre-candidate at term 1"} {"level":"info","ts":"2026-08-20T01:19:05.350386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d received MsgPreVoteResp from 22478416d949d47d at term 1"} {"level":"info","ts":"2026-08-20T01:19:05.350406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became candidate at term 2"} {"level":"info","ts":"2026-08-20T01:19:05.350417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d received MsgVoteResp from 22478416d949d47d at term 2"} {"level":"info","ts":"2026-08-20T01:19:05.350432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became leader at term 2"} {"level":"info","ts":"2026-08-20T01:19:05.350446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 22478416d949d47d elected leader 22478416d949d47d at term 2"} {"level":"info","ts":"2026-08-20T01:19:05.356676Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T01:19:05.357565Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-20T01:19:05.357562Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"22478416d949d47d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.186:2379]}","request-path":"/0/members/22478416d949d47d/attributes","cluster-id":"568bb29601528c07","publish-timeout":"7s"} {"level":"info","ts":"2026-08-20T01:19:05.357639Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-20T01:19:05.357939Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-20T01:19:05.357995Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-20T01:19:05.35826Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"568bb29601528c07","local-member-id":"22478416d949d47d","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T01:19:05.359146Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T01:19:05.359271Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T01:19:05.36111Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-20T01:19:05.361455Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.186:2379"} {"level":"warn","ts":"2026-08-20T01:19:05.650692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.003639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-20T01:19:05.650751Z","caller":"traceutil/trace.go:171","msg":"trace[1808746870] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"192.05017ms","start":"2026-08-20T01:19:05.458684Z","end":"2026-08-20T01:19:05.650734Z","steps":["trace[1808746870] 'count revisions from in-memory index tree' (duration: 191.841066ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:19:05.650934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.944599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-20T01:19:05.650932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.525848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-20T01:19:05.650982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.96428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-20T01:19:05.650999Z","caller":"traceutil/trace.go:171","msg":"trace[1145255302] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"179.99721ms","start":"2026-08-20T01:19:05.470996Z","end":"2026-08-20T01:19:05.650993Z","steps":["trace[1145255302] 'count revisions from in-memory index tree' (duration: 179.927908ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:19:05.651104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.217685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-20T01:19:05.651129Z","caller":"traceutil/trace.go:171","msg":"trace[426162606] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"169.295066ms","start":"2026-08-20T01:19:05.481828Z","end":"2026-08-20T01:19:05.651124Z","steps":["trace[426162606] 'range keys from in-memory index tree' (duration: 169.154814ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:19:05.651143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.239942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-20T01:19:05.651166Z","caller":"traceutil/trace.go:171","msg":"trace[1257109895] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"120.291833ms","start":"2026-08-20T01:19:05.530866Z","end":"2026-08-20T01:19:05.651158Z","steps":["trace[1257109895] 'range keys from in-memory index tree' (duration: 120.196821ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:19:05.651236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.256296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-20T01:19:05.65126Z","caller":"traceutil/trace.go:171","msg":"trace[729723614] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"169.303007ms","start":"2026-08-20T01:19:05.481952Z","end":"2026-08-20T01:19:05.651255Z","steps":["trace[729723614] 'count revisions from in-memory index tree' (duration: 169.205755ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:19:05.651334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.659804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-20T01:19:05.651356Z","caller":"traceutil/trace.go:171","msg":"trace[1456109312] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"157.702155ms","start":"2026-08-20T01:19:05.493649Z","end":"2026-08-20T01:19:05.651351Z","steps":["trace[1456109312] 'count revisions from in-memory index tree' (duration: 157.544082ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:19:05.651364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.369094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-20T01:19:05.651428Z","caller":"traceutil/trace.go:171","msg":"trace[1628105784] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"120.451775ms","start":"2026-08-20T01:19:05.530954Z","end":"2026-08-20T01:19:05.651406Z","steps":["trace[1628105784] 'count revisions from in-memory index tree' (duration: 120.289553ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:19:05.651457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.490731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-20T01:19:05.651474Z","caller":"traceutil/trace.go:171","msg":"trace[1406456528] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"146.530843ms","start":"2026-08-20T01:19:05.504938Z","end":"2026-08-20T01:19:05.651469Z","steps":["trace[1406456528] 'count revisions from in-memory index tree' (duration: 146.465181ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:19:05.651536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.635515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-20T01:19:05.651555Z","caller":"traceutil/trace.go:171","msg":"trace[1653807035] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"146.659215ms","start":"2026-08-20T01:19:05.504891Z","end":"2026-08-20T01:19:05.65155Z","steps":["trace[1653807035] 'range keys from in-memory index tree' (duration: 146.525673ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:19:05.650666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.525799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-20T01:19:05.651619Z","caller":"traceutil/trace.go:171","msg":"trace[1902578351] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"204.511257ms","start":"2026-08-20T01:19:05.447099Z","end":"2026-08-20T01:19:05.651611Z","steps":["trace[1902578351] 'count revisions from in-memory index tree' (duration: 203.434457ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:19:05.651665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.318683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-20T01:19:05.651097Z","caller":"traceutil/trace.go:171","msg":"trace[8429717] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"129.662441ms","start":"2026-08-20T01:19:05.521318Z","end":"2026-08-20T01:19:05.65098Z","steps":["trace[8429717] 'count revisions from in-memory index tree' (duration: 129.491037ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:19:05.651698Z","caller":"traceutil/trace.go:171","msg":"trace[664895947] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"109.353654ms","start":"2026-08-20T01:19:05.542336Z","end":"2026-08-20T01:19:05.65169Z","steps":["trace[664895947] 'count revisions from in-memory index tree' (duration: 109.275493ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:19:05.650682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.883707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-20T01:19:05.651896Z","caller":"traceutil/trace.go:171","msg":"trace[1917750754] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"193.09267ms","start":"2026-08-20T01:19:05.458777Z","end":"2026-08-20T01:19:05.65187Z","steps":["trace[1917750754] 'range keys from in-memory index tree' (duration: 191.849107ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:19:05.651949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.489286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-20T01:19:05.650961Z","caller":"traceutil/trace.go:171","msg":"trace[1865485657] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"179.972459ms","start":"2026-08-20T01:19:05.470982Z","end":"2026-08-20T01:19:05.650955Z","steps":["trace[1865485657] 'range keys from in-memory index tree' (duration: 179.918769ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:19:05.652002Z","caller":"traceutil/trace.go:171","msg":"trace[60678127] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"109.602628ms","start":"2026-08-20T01:19:05.542392Z","end":"2026-08-20T01:19:05.651995Z","steps":["trace[60678127] 'range keys from in-memory index tree' (duration: 109.398985ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:19:05.650623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.296014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-20T01:19:05.652382Z","caller":"traceutil/trace.go:171","msg":"trace[1296498159] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"131.119839ms","start":"2026-08-20T01:19:05.521256Z","end":"2026-08-20T01:19:05.652376Z","steps":["trace[1296498159] 'range keys from in-memory index tree' (duration: 129.237703ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:19:05.651439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.747126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-20T01:19:05.652675Z","caller":"traceutil/trace.go:171","msg":"trace[62897625] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"159.02174ms","start":"2026-08-20T01:19:05.493639Z","end":"2026-08-20T01:19:05.652661Z","steps":["trace[62897625] 'range keys from in-memory index tree' (duration: 157.559613ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:19:14.608213Z","caller":"traceutil/trace.go:171","msg":"trace[1587361118] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"182.05708ms","start":"2026-08-20T01:19:14.426129Z","end":"2026-08-20T01:19:14.608186Z","steps":["trace[1587361118] 'process raft request' (duration: 181.880535ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:19:14.828145Z","caller":"traceutil/trace.go:171","msg":"trace[1057880695] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"215.005626ms","start":"2026-08-20T01:19:14.613102Z","end":"2026-08-20T01:19:14.828108Z","steps":["trace[1057880695] 'process raft request' (duration: 210.591372ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:19:14.828147Z","caller":"traceutil/trace.go:171","msg":"trace[2049741877] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:377; }","duration":"118.697877ms","start":"2026-08-20T01:19:14.709425Z","end":"2026-08-20T01:19:14.828123Z","steps":["trace[2049741877] 'read index received' (duration: 114.294694ms)","trace[2049741877] 'applied index is now lower than readState.Index' (duration: 4.401953ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:19:14.828276Z","caller":"traceutil/trace.go:171","msg":"trace[1419211287] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"207.264811ms","start":"2026-08-20T01:19:14.620975Z","end":"2026-08-20T01:19:14.82824Z","steps":["trace[1419211287] 'process raft request' (duration: 207.093188ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:19:14.828371Z","caller":"traceutil/trace.go:171","msg":"trace[523212171] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"213.910186ms","start":"2026-08-20T01:19:14.61425Z","end":"2026-08-20T01:19:14.828161Z","steps":["trace[523212171] 'process raft request' (duration: 213.761173ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:19:14.828299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.855329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-08-20T01:19:14.828591Z","caller":"traceutil/trace.go:171","msg":"trace[550520118] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:367; }","duration":"119.208266ms","start":"2026-08-20T01:19:14.709369Z","end":"2026-08-20T01:19:14.828577Z","steps":["trace[550520118] 'agreement among raft nodes before linearized reading' (duration: 118.832359ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:19:15.080874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.018572ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T01:19:15.0812Z","caller":"traceutil/trace.go:171","msg":"trace[2119891238] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"247.753077ms","start":"2026-08-20T01:19:14.833412Z","end":"2026-08-20T01:19:15.081165Z","steps":["trace[2119891238] 'process raft request' (duration: 133.987522ms)","trace[2119891238] 'compare' (duration: 111.88356ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:19:15.081352Z","caller":"traceutil/trace.go:171","msg":"trace[119974429] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"247.721256ms","start":"2026-08-20T01:19:14.833609Z","end":"2026-08-20T01:19:15.08133Z","steps":["trace[119974429] 'process raft request' (duration: 247.534752ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:19:15.081381Z","caller":"traceutil/trace.go:171","msg":"trace[1385674223] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"247.856849ms","start":"2026-08-20T01:19:14.833475Z","end":"2026-08-20T01:19:15.081332Z","steps":["trace[1385674223] 'process raft request' (duration: 247.555933ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:19:15.081412Z","caller":"traceutil/trace.go:171","msg":"trace[361846484] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"247.421671ms","start":"2026-08-20T01:19:14.833952Z","end":"2026-08-20T01:19:15.081373Z","steps":["trace[361846484] 'process raft request' (duration: 247.246237ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:19:15.081617Z","caller":"traceutil/trace.go:171","msg":"trace[1784095668] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"243.501167ms","start":"2026-08-20T01:19:14.838092Z","end":"2026-08-20T01:19:15.081594Z","steps":["trace[1784095668] 'process raft request' (duration: 243.397666ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:19:15.081701Z","caller":"traceutil/trace.go:171","msg":"trace[1488033415] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"245.373094ms","start":"2026-08-20T01:19:14.836284Z","end":"2026-08-20T01:19:15.081657Z","steps":["trace[1488033415] 'process raft request' (duration: 244.999166ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:19:15.081424Z","caller":"traceutil/trace.go:171","msg":"trace[558946045] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"246.577386ms","start":"2026-08-20T01:19:14.834822Z","end":"2026-08-20T01:19:15.0814Z","steps":["trace[558946045] 'process raft request' (duration: 246.413002ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:19:40.836879Z","caller":"traceutil/trace.go:171","msg":"trace[1083601990] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"122.569559ms","start":"2026-08-20T01:19:40.714272Z","end":"2026-08-20T01:19:40.836842Z","steps":["trace[1083601990] 'process raft request' (duration: 113.577751ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:19:40.837149Z","caller":"traceutil/trace.go:171","msg":"trace[298159764] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"112.726145ms","start":"2026-08-20T01:19:40.724393Z","end":"2026-08-20T01:19:40.837119Z","steps":["trace[298159764] 'process raft request' (duration: 112.372058ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:19:40.83736Z","caller":"traceutil/trace.go:171","msg":"trace[387984763] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"103.536793ms","start":"2026-08-20T01:19:40.733795Z","end":"2026-08-20T01:19:40.837331Z","steps":["trace[387984763] 'process raft request' (duration: 103.435491ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:19:40.837384Z","caller":"traceutil/trace.go:171","msg":"trace[2044539920] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"111.393831ms","start":"2026-08-20T01:19:40.725964Z","end":"2026-08-20T01:19:40.837358Z","steps":["trace[2044539920] 'process raft request' (duration: 111.071505ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:19:41.914787Z","caller":"traceutil/trace.go:171","msg":"trace[1868739489] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"244.459035ms","start":"2026-08-20T01:19:41.670307Z","end":"2026-08-20T01:19:41.914766Z","steps":["trace[1868739489] 'process raft request' (duration: 244.057677ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:19:41.918588Z","caller":"traceutil/trace.go:171","msg":"trace[1909785277] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"140.072046ms","start":"2026-08-20T01:19:41.778482Z","end":"2026-08-20T01:19:41.918554Z","steps":["trace[1909785277] 'process raft request' (duration: 139.821361ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:19:46.910408Z","caller":"traceutil/trace.go:171","msg":"trace[20850853] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"167.173352ms","start":"2026-08-20T01:19:46.743201Z","end":"2026-08-20T01:19:46.910375Z","steps":["trace[20850853] 'process raft request' (duration: 166.399788ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:19:47.307532Z","caller":"traceutil/trace.go:171","msg":"trace[213398739] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"337.281709ms","start":"2026-08-20T01:19:46.970226Z","end":"2026-08-20T01:19:47.307508Z","steps":["trace[213398739] 'process raft request' (duration: 252.20637ms)","trace[213398739] 'compare' (duration: 84.781983ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:19:47.307683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:19:46.970197Z","time spent":"337.409372ms","remote":"127.0.0.1:46588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-20T01:19:47.423635Z","caller":"traceutil/trace.go:171","msg":"trace[1300031403] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"109.613247ms","start":"2026-08-20T01:19:47.313999Z","end":"2026-08-20T01:19:47.423613Z","steps":["trace[1300031403] 'process raft request' (duration: 93.601238ms)","trace[1300031403] 'compare' (duration: 15.850156ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:19:47.424126Z","caller":"traceutil/trace.go:171","msg":"trace[1031448727] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"110.082805ms","start":"2026-08-20T01:19:47.314023Z","end":"2026-08-20T01:19:47.424106Z","steps":["trace[1031448727] 'process raft request' (duration: 109.551706ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:19:47.424922Z","caller":"traceutil/trace.go:171","msg":"trace[460603445] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:641; }","duration":"105.853597ms","start":"2026-08-20T01:19:47.319038Z","end":"2026-08-20T01:19:47.424892Z","steps":["trace[460603445] 'read index received' (duration: 63.591998ms)","trace[460603445] 'applied index is now lower than readState.Index' (duration: 42.260269ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:19:47.425045Z","caller":"traceutil/trace.go:171","msg":"trace[1212974783] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"101.766751ms","start":"2026-08-20T01:19:47.32325Z","end":"2026-08-20T01:19:47.425016Z","steps":["trace[1212974783] 'process raft request' (duration: 101.534277ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:19:47.425242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.160112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:19:47.425301Z","caller":"traceutil/trace.go:171","msg":"trace[650008799] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:622; }","duration":"106.322206ms","start":"2026-08-20T01:19:47.318956Z","end":"2026-08-20T01:19:47.425278Z","steps":["trace[650008799] 'agreement among raft nodes before linearized reading' (duration: 106.204964ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:20:11.485597Z","caller":"traceutil/trace.go:171","msg":"trace[1881171456] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"124.161888ms","start":"2026-08-20T01:20:11.361387Z","end":"2026-08-20T01:20:11.485549Z","steps":["trace[1881171456] 'process raft request' (duration: 123.929124ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:21:23.037593Z","caller":"traceutil/trace.go:171","msg":"trace[1564588831] linearizableReadLoop","detail":"{readStateIndex:1924; appliedIndex:1923; }","duration":"166.692508ms","start":"2026-08-20T01:21:22.870873Z","end":"2026-08-20T01:21:23.037565Z","steps":["trace[1564588831] 'read index received' (duration: 166.620436ms)","trace[1564588831] 'applied index is now lower than readState.Index' (duration: 71.032µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:21:23.037646Z","caller":"traceutil/trace.go:171","msg":"trace[820887438] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"278.102393ms","start":"2026-08-20T01:21:22.759507Z","end":"2026-08-20T01:21:23.03761Z","steps":["trace[820887438] 'process raft request' (duration: 277.89999ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:21:23.037805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.912041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-11736932-52cb-4ccf-b5d1-5d43bc10cfae\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-20T01:21:23.037911Z","caller":"traceutil/trace.go:171","msg":"trace[723003385] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-11736932-52cb-4ccf-b5d1-5d43bc10cfae; range_end:; response_count:1; response_revision:1852; }","duration":"167.092345ms","start":"2026-08-20T01:21:22.870798Z","end":"2026-08-20T01:21:23.03789Z","steps":["trace[723003385] 'agreement among raft nodes before linearized reading' (duration: 166.84728ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:21:23.20326Z","caller":"traceutil/trace.go:171","msg":"trace[1623387782] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"105.784579ms","start":"2026-08-20T01:21:23.097445Z","end":"2026-08-20T01:21:23.203229Z","steps":["trace[1623387782] 'process raft request' (duration: 58.089763ms)","trace[1623387782] 'compare' (duration: 47.573314ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:21:23.260126Z","caller":"traceutil/trace.go:171","msg":"trace[1887740960] linearizableReadLoop","detail":"{readStateIndex:1931; appliedIndex:1929; }","duration":"139.209215ms","start":"2026-08-20T01:21:23.120894Z","end":"2026-08-20T01:21:23.260104Z","steps":["trace[1887740960] 'read index received' (duration: 34.628257ms)","trace[1887740960] 'applied index is now lower than readState.Index' (duration: 104.579948ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:21:23.260181Z","caller":"traceutil/trace.go:171","msg":"trace[859757809] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"159.020741ms","start":"2026-08-20T01:21:23.101133Z","end":"2026-08-20T01:21:23.260153Z","steps":["trace[859757809] 'process raft request' (duration: 158.858958ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:21:23.26033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.422989ms","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-08-20T01:21:23.260378Z","caller":"traceutil/trace.go:171","msg":"trace[1920443568] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1859; }","duration":"139.512191ms","start":"2026-08-20T01:21:23.120852Z","end":"2026-08-20T01:21:23.260364Z","steps":["trace[1920443568] 'agreement among raft nodes before linearized reading' (duration: 139.343558ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:22:12.796571Z","caller":"traceutil/trace.go:171","msg":"trace[465966434] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"120.058483ms","start":"2026-08-20T01:22:12.676481Z","end":"2026-08-20T01:22:12.796539Z","steps":["trace[465966434] 'process raft request' (duration: 119.8621ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:22:13.325142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.900976ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T01:22:13.325397Z","caller":"traceutil/trace.go:171","msg":"trace[133073573] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"272.582519ms","start":"2026-08-20T01:22:13.052769Z","end":"2026-08-20T01:22:13.325352Z","steps":["trace[133073573] 'process raft request' (duration: 87.375056ms)","trace[133073573] 'compare' (duration: 184.766544ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:22:13.623255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.422801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-20T01:22:13.623303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.829343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-20T01:22:13.623392Z","caller":"traceutil/trace.go:171","msg":"trace[1225874129] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2176; }","duration":"101.988325ms","start":"2026-08-20T01:22:13.521385Z","end":"2026-08-20T01:22:13.623373Z","steps":["trace[1225874129] 'range keys from in-memory index tree' (duration: 101.644789ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:22:13.623341Z","caller":"traceutil/trace.go:171","msg":"trace[1429452677] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:2176; }","duration":"178.584315ms","start":"2026-08-20T01:22:13.444735Z","end":"2026-08-20T01:22:13.623319Z","steps":["trace[1429452677] 'count revisions from in-memory index tree' (duration: 178.158606ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:23:05.646048Z","caller":"traceutil/trace.go:171","msg":"trace[487350891] transaction","detail":"{read_only:false; response_revision:2633; number_of_response:1; }","duration":"144.977652ms","start":"2026-08-20T01:23:05.501Z","end":"2026-08-20T01:23:05.645977Z","steps":["trace[487350891] 'process raft request' (duration: 144.784849ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:23:39.348689Z","caller":"traceutil/trace.go:171","msg":"trace[1973855233] transaction","detail":"{read_only:false; response_revision:2836; number_of_response:1; }","duration":"108.394668ms","start":"2026-08-20T01:23:39.240267Z","end":"2026-08-20T01:23:39.348661Z","steps":["trace[1973855233] 'process raft request' (duration: 108.226635ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:27:25.622829Z","caller":"traceutil/trace.go:171","msg":"trace[585294387] transaction","detail":"{read_only:false; response_revision:4950; number_of_response:1; }","duration":"102.43135ms","start":"2026-08-20T01:27:25.520331Z","end":"2026-08-20T01:27:25.622763Z","steps":["trace[585294387] 'process raft request' (duration: 102.242027ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:27:25.816963Z","caller":"traceutil/trace.go:171","msg":"trace[1551667766] transaction","detail":"{read_only:false; response_revision:4951; number_of_response:1; }","duration":"157.401839ms","start":"2026-08-20T01:27:25.65954Z","end":"2026-08-20T01:27:25.816942Z","steps":["trace[1551667766] 'process raft request' (duration: 157.272856ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:27:25.826822Z","caller":"traceutil/trace.go:171","msg":"trace[24855209] transaction","detail":"{read_only:false; response_revision:4952; number_of_response:1; }","duration":"166.122876ms","start":"2026-08-20T01:27:25.660661Z","end":"2026-08-20T01:27:25.826784Z","steps":["trace[24855209] 'process raft request' (duration: 165.968463ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:27:38.691388Z","caller":"traceutil/trace.go:171","msg":"trace[950719358] transaction","detail":"{read_only:false; response_revision:5202; number_of_response:1; }","duration":"103.256586ms","start":"2026-08-20T01:27:38.588098Z","end":"2026-08-20T01:27:38.691355Z","steps":["trace[950719358] 'process raft request' (duration: 102.98653ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:29:05.387821Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2980} {"level":"info","ts":"2026-08-20T01:29:05.467926Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2980,"took":"77.790796ms","hash":605034926,"current-db-size-bytes":59011072,"current-db-size":"59 MB","current-db-size-in-use-bytes":34463744,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-20T01:29:05.467999Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":605034926,"revision":2980,"compact-revision":-1} {"level":"info","ts":"2026-08-20T01:33:51.266287Z","caller":"traceutil/trace.go:171","msg":"trace[549746027] linearizableReadLoop","detail":"{readStateIndex:8500; appliedIndex:8499; }","duration":"162.378574ms","start":"2026-08-20T01:33:51.103881Z","end":"2026-08-20T01:33:51.26626Z","steps":["trace[549746027] 'read index received' (duration: 162.15396ms)","trace[549746027] 'applied index is now lower than readState.Index' (duration: 223.464µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:33:51.26635Z","caller":"traceutil/trace.go:171","msg":"trace[503768606] transaction","detail":"{read_only:false; response_revision:8208; number_of_response:1; }","duration":"211.119877ms","start":"2026-08-20T01:33:51.055193Z","end":"2026-08-20T01:33:51.266313Z","steps":["trace[503768606] 'process raft request' (duration: 210.927924ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:33:51.266541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.63113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9z822\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-08-20T01:33:51.266538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.091458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:33:51.266587Z","caller":"traceutil/trace.go:171","msg":"trace[1532502496] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9z822; range_end:; response_count:1; response_revision:8208; }","duration":"162.751881ms","start":"2026-08-20T01:33:51.103823Z","end":"2026-08-20T01:33:51.266575Z","steps":["trace[1532502496] 'agreement among raft nodes before linearized reading' (duration: 162.572768ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:33:51.266612Z","caller":"traceutil/trace.go:171","msg":"trace[842509853] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:8208; }","duration":"118.22042ms","start":"2026-08-20T01:33:51.148369Z","end":"2026-08-20T01:33:51.26659Z","steps":["trace[842509853] 'agreement among raft nodes before linearized reading' (duration: 118.095278ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:34:03.986446Z","caller":"traceutil/trace.go:171","msg":"trace[1663667192] transaction","detail":"{read_only:false; response_revision:8275; number_of_response:1; }","duration":"246.911071ms","start":"2026-08-20T01:34:03.739497Z","end":"2026-08-20T01:34:03.986408Z","steps":["trace[1663667192] 'process raft request' (duration: 246.567904ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:34:05.394883Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6110} {"level":"info","ts":"2026-08-20T01:34:05.496242Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6110,"took":"99.152885ms","hash":350810016,"current-db-size-bytes":59011072,"current-db-size":"59 MB","current-db-size-in-use-bytes":22900736,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-20T01:34:05.496306Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":350810016,"revision":6110,"compact-revision":2980} {"level":"info","ts":"2026-08-20T01:34:32.016408Z","caller":"traceutil/trace.go:171","msg":"trace[3219938] transaction","detail":"{read_only:false; response_revision:8476; number_of_response:1; }","duration":"365.308522ms","start":"2026-08-20T01:34:31.651054Z","end":"2026-08-20T01:34:32.016362Z","steps":["trace[3219938] 'process raft request' (duration: 364.801592ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:34:32.016609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.232306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-08-20T01:34:32.016623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:34:31.65101Z","time spent":"365.491596ms","remote":"127.0.0.1:47400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":414529,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:34:32.016674Z","caller":"traceutil/trace.go:171","msg":"trace[575668929] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8476; }","duration":"349.374039ms","start":"2026-08-20T01:34:31.667285Z","end":"2026-08-20T01:34:32.016659Z","steps":["trace[575668929] 'agreement among raft nodes before linearized reading' (duration: 349.160035ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:34:32.016708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:34:31.667256Z","time spent":"349.44551ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-20T01:34:32.016327Z","caller":"traceutil/trace.go:171","msg":"trace[258072129] linearizableReadLoop","detail":"{readStateIndex:8780; appliedIndex:8779; }","duration":"348.950631ms","start":"2026-08-20T01:34:31.667349Z","end":"2026-08-20T01:34:32.016299Z","steps":["trace[258072129] 'read index received' (duration: 348.343519ms)","trace[258072129] 'applied index is now lower than readState.Index' (duration: 606.242µs)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:34:32.01712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.346763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-20T01:34:32.017142Z","caller":"traceutil/trace.go:171","msg":"trace[206148582] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8476; }","duration":"343.437895ms","start":"2026-08-20T01:34:31.673698Z","end":"2026-08-20T01:34:32.017136Z","steps":["trace[206148582] 'agreement among raft nodes before linearized reading' (duration: 343.353703ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:34:32.017157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:34:31.673678Z","time spent":"343.476155ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-20T01:34:32.017252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.035954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:34:32.017339Z","caller":"traceutil/trace.go:171","msg":"trace[77463182] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:8476; }","duration":"116.133226ms","start":"2026-08-20T01:34:31.901189Z","end":"2026-08-20T01:34:32.017322Z","steps":["trace[77463182] 'agreement among raft nodes before linearized reading' (duration: 115.952213ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:34:32.017282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.550215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-57d4d5f7d6-7txt6.18cd5f7df7c1f638\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-08-20T01:34:32.017415Z","caller":"traceutil/trace.go:171","msg":"trace[899879448] range","detail":"{range_begin:/registry/events/openstack/barbican-api-57d4d5f7d6-7txt6.18cd5f7df7c1f638; range_end:; response_count:1; response_revision:8476; }","duration":"246.746849ms","start":"2026-08-20T01:34:31.770658Z","end":"2026-08-20T01:34:32.017405Z","steps":["trace[899879448] 'agreement among raft nodes before linearized reading' (duration: 246.574885ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:34:44.697299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.077153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-qkpc7\" ","response":"range_response_count:1 size:4642"} {"level":"info","ts":"2026-08-20T01:34:44.697434Z","caller":"traceutil/trace.go:171","msg":"trace[412134355] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-qkpc7; range_end:; response_count:1; response_revision:8587; }","duration":"207.273316ms","start":"2026-08-20T01:34:44.490136Z","end":"2026-08-20T01:34:44.697409Z","steps":["trace[412134355] 'range keys from in-memory index tree' (duration: 206.864548ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:34:44.697326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.307122ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-20T01:34:44.698206Z","caller":"traceutil/trace.go:171","msg":"trace[1812363425] transaction","detail":"{read_only:false; response_revision:8588; number_of_response:1; }","duration":"207.220585ms","start":"2026-08-20T01:34:44.49092Z","end":"2026-08-20T01:34:44.698141Z","steps":["trace[1812363425] 'process raft request' (duration: 93.011536ms)","trace[1812363425] 'compare' (duration: 113.018466ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:34:45.717576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.258333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-08-20T01:34:45.717686Z","caller":"traceutil/trace.go:171","msg":"trace[1541988345] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:8594; }","duration":"188.401465ms","start":"2026-08-20T01:34:45.529259Z","end":"2026-08-20T01:34:45.71766Z","steps":["trace[1541988345] 'range keys from in-memory index tree' (duration: 188.061409ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:34:45.717953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.525742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-20T01:34:45.718051Z","caller":"traceutil/trace.go:171","msg":"trace[524066695] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8594; }","duration":"107.682215ms","start":"2026-08-20T01:34:45.610351Z","end":"2026-08-20T01:34:45.718033Z","steps":["trace[524066695] 'range keys from in-memory index tree' (duration: 107.341438ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:35:31.676155Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000246f.wal"} {"level":"info","ts":"2026-08-20T01:36:46.526235Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"22478416d949d47d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-20T01:36:46.534346Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-20T01:36:46.534433Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-20T01:39:05.402977Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8287} {"level":"info","ts":"2026-08-20T01:39:05.481952Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8287,"took":"76.253804ms","hash":3027935193,"current-db-size-bytes":59011072,"current-db-size":"59 MB","current-db-size-in-use-bytes":25268224,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-08-20T01:39:05.48205Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3027935193,"revision":8287,"compact-revision":6110} {"level":"info","ts":"2026-08-20T01:39:28.692997Z","caller":"traceutil/trace.go:171","msg":"trace[345196649] transaction","detail":"{read_only:false; response_revision:11094; number_of_response:1; }","duration":"148.774554ms","start":"2026-08-20T01:39:28.54419Z","end":"2026-08-20T01:39:28.692964Z","steps":["trace[345196649] 'process raft request' (duration: 148.56027ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:39:46.984623Z","caller":"traceutil/trace.go:171","msg":"trace[1672502675] linearizableReadLoop","detail":"{readStateIndex:11580; appliedIndex:11579; }","duration":"175.537118ms","start":"2026-08-20T01:39:46.80905Z","end":"2026-08-20T01:39:46.984587Z","steps":["trace[1672502675] 'read index received' (duration: 171.493461ms)","trace[1672502675] 'applied index is now lower than readState.Index' (duration: 4.042147ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:39:46.984698Z","caller":"traceutil/trace.go:171","msg":"trace[2003388746] transaction","detail":"{read_only:false; response_revision:11194; number_of_response:1; }","duration":"211.486048ms","start":"2026-08-20T01:39:46.773171Z","end":"2026-08-20T01:39:46.984657Z","steps":["trace[2003388746] 'process raft request' (duration: 207.462871ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:39:46.984836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.765273ms","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-08-20T01:39:46.984954Z","caller":"traceutil/trace.go:171","msg":"trace[381488667] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:11194; }","duration":"175.896055ms","start":"2026-08-20T01:39:46.80903Z","end":"2026-08-20T01:39:46.984926Z","steps":["trace[381488667] 'agreement among raft nodes before linearized reading' (duration: 175.690941ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:44:05.409606Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10770} {"level":"info","ts":"2026-08-20T01:44:05.48721Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10770,"took":"74.357388ms","hash":1325370647,"current-db-size-bytes":59011072,"current-db-size":"59 MB","current-db-size-in-use-bytes":27635712,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-20T01:44:05.487283Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1325370647,"revision":10770,"compact-revision":8287} {"level":"info","ts":"2026-08-20T01:46:09.127709Z","caller":"traceutil/trace.go:171","msg":"trace[223638075] transaction","detail":"{read_only:false; response_revision:13777; number_of_response:1; }","duration":"221.475682ms","start":"2026-08-20T01:46:08.906183Z","end":"2026-08-20T01:46:09.127659Z","steps":["trace[223638075] 'process raft request' (duration: 221.221447ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:46:30.556947Z","caller":"traceutil/trace.go:171","msg":"trace[917241469] transaction","detail":"{read_only:false; response_revision:14081; number_of_response:1; }","duration":"137.160382ms","start":"2026-08-20T01:46:30.419764Z","end":"2026-08-20T01:46:30.556925Z","steps":["trace[917241469] 'process raft request' (duration: 136.982019ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:47:47.140647Z","caller":"traceutil/trace.go:171","msg":"trace[2096704056] linearizableReadLoop","detail":"{readStateIndex:15354; appliedIndex:15353; }","duration":"166.526906ms","start":"2026-08-20T01:47:46.974098Z","end":"2026-08-20T01:47:47.140625Z","steps":["trace[2096704056] 'read index received' (duration: 166.439285ms)","trace[2096704056] 'applied index is now lower than readState.Index' (duration: 86.231µs)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:47:47.14079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.670419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:47:47.140825Z","caller":"traceutil/trace.go:171","msg":"trace[1914814270] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:14842; }","duration":"166.736321ms","start":"2026-08-20T01:47:46.974076Z","end":"2026-08-20T01:47:47.140813Z","steps":["trace[1914814270] 'agreement among raft nodes before linearized reading' (duration: 166.657259ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:47:47.140942Z","caller":"traceutil/trace.go:171","msg":"trace[758689475] transaction","detail":"{read_only:false; response_revision:14842; number_of_response:1; }","duration":"177.566269ms","start":"2026-08-20T01:47:46.963346Z","end":"2026-08-20T01:47:47.140912Z","steps":["trace[758689475] 'process raft request' (duration: 177.15214ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:47:47.154405Z","caller":"traceutil/trace.go:171","msg":"trace[478960172] transaction","detail":"{read_only:false; response_revision:14843; number_of_response:1; }","duration":"162.970507ms","start":"2026-08-20T01:47:46.99141Z","end":"2026-08-20T01:47:47.154381Z","steps":["trace[478960172] 'process raft request' (duration: 162.830915ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:49:05.422364Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12958} {"level":"info","ts":"2026-08-20T01:49:05.508589Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12958,"took":"83.094055ms","hash":3946667387,"current-db-size-bytes":59011072,"current-db-size":"59 MB","current-db-size-in-use-bytes":30777344,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-20T01:49:05.508665Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3946667387,"revision":12958,"compact-revision":10770} {"level":"info","ts":"2026-08-20T01:53:49.280376Z","caller":"traceutil/trace.go:171","msg":"trace[932432153] linearizableReadLoop","detail":"{readStateIndex:18107; appliedIndex:18106; }","duration":"128.570917ms","start":"2026-08-20T01:53:49.151779Z","end":"2026-08-20T01:53:49.28035Z","steps":["trace[932432153] 'read index received' (duration: 128.324072ms)","trace[932432153] 'applied index is now lower than readState.Index' (duration: 245.905µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:53:49.280531Z","caller":"traceutil/trace.go:171","msg":"trace[2100846866] transaction","detail":"{read_only:false; response_revision:17507; number_of_response:1; }","duration":"187.212252ms","start":"2026-08-20T01:53:49.093274Z","end":"2026-08-20T01:53:49.280486Z","steps":["trace[2100846866] 'process raft request' (duration: 186.912867ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:53:49.2807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.900494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-2p5ch\" ","response":"range_response_count:1 size:9800"} {"level":"info","ts":"2026-08-20T01:53:49.280768Z","caller":"traceutil/trace.go:171","msg":"trace[669575221] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-2p5ch; range_end:; response_count:1; response_revision:17507; }","duration":"129.032115ms","start":"2026-08-20T01:53:49.151719Z","end":"2026-08-20T01:53:49.280751Z","steps":["trace[669575221] 'agreement among raft nodes before linearized reading' (duration: 128.789631ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:53:50.307568Z","caller":"traceutil/trace.go:171","msg":"trace[365453027] transaction","detail":"{read_only:false; response_revision:17509; number_of_response:1; }","duration":"232.46646ms","start":"2026-08-20T01:53:50.075078Z","end":"2026-08-20T01:53:50.307545Z","steps":["trace[365453027] 'process raft request' (duration: 232.321676ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:53:50.31835Z","caller":"traceutil/trace.go:171","msg":"trace[1285591763] transaction","detail":"{read_only:false; response_revision:17510; number_of_response:1; }","duration":"242.442681ms","start":"2026-08-20T01:53:50.075877Z","end":"2026-08-20T01:53:50.318319Z","steps":["trace[1285591763] 'process raft request' (duration: 242.293498ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:53:50.318569Z","caller":"traceutil/trace.go:171","msg":"trace[2146017821] linearizableReadLoop","detail":"{readStateIndex:18110; appliedIndex:18109; }","duration":"213.068588ms","start":"2026-08-20T01:53:50.105458Z","end":"2026-08-20T01:53:50.318527Z","steps":["trace[2146017821] 'read index received' (duration: 202.428454ms)","trace[2146017821] 'applied index is now lower than readState.Index' (duration: 10.636974ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:53:50.31877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.295313ms","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-08-20T01:53:50.318846Z","caller":"traceutil/trace.go:171","msg":"trace[1490227493] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17511; }","duration":"213.412985ms","start":"2026-08-20T01:53:50.105409Z","end":"2026-08-20T01:53:50.318822Z","steps":["trace[1490227493] 'agreement among raft nodes before linearized reading' (duration: 213.238811ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:53:50.318958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.967728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-08-20T01:53:50.319038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.159898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-20T01:53:50.31911Z","caller":"traceutil/trace.go:171","msg":"trace[377752972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17511; }","duration":"167.317702ms","start":"2026-08-20T01:53:50.151779Z","end":"2026-08-20T01:53:50.319096Z","steps":["trace[377752972] 'agreement among raft nodes before linearized reading' (duration: 166.985535ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:53:50.319193Z","caller":"traceutil/trace.go:171","msg":"trace[120346709] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17511; }","duration":"163.035149ms","start":"2026-08-20T01:53:50.155962Z","end":"2026-08-20T01:53:50.318997Z","steps":["trace[120346709] 'agreement among raft nodes before linearized reading' (duration: 162.818895ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:54:05.428158Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15343} {"level":"info","ts":"2026-08-20T01:54:05.529618Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15343,"took":"99.038459ms","hash":2040658783,"current-db-size-bytes":59011072,"current-db-size":"59 MB","current-db-size-in-use-bytes":32542720,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-20T01:54:05.529699Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2040658783,"revision":15343,"compact-revision":12958} {"level":"info","ts":"2026-08-20T01:57:31.48834Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"22478416d949d47d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-20T01:57:31.495037Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-20T01:57:31.495322Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-20T01:59:05.436474Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17747} {"level":"info","ts":"2026-08-20T01:59:05.536748Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17747,"took":"96.449248ms","hash":1649171771,"current-db-size-bytes":59011072,"current-db-size":"59 MB","current-db-size-in-use-bytes":33886208,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-20T01:59:05.536805Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1649171771,"revision":17747,"compact-revision":15343} {"level":"info","ts":"2026-08-20T01:59:10.191348Z","caller":"traceutil/trace.go:171","msg":"trace[43580355] transaction","detail":"{read_only:false; response_revision:20051; number_of_response:1; }","duration":"165.199785ms","start":"2026-08-20T01:59:10.026119Z","end":"2026-08-20T01:59:10.191319Z","steps":["trace[43580355] 'process raft request' (duration: 164.967731ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:59:10.208213Z","caller":"traceutil/trace.go:171","msg":"trace[1789464197] linearizableReadLoop","detail":"{readStateIndex:20738; appliedIndex:20735; }","duration":"180.13021ms","start":"2026-08-20T01:59:10.028068Z","end":"2026-08-20T01:59:10.208199Z","steps":["trace[1789464197] 'read index received' (duration: 163.118094ms)","trace[1789464197] 'applied index is now lower than readState.Index' (duration: 17.010876ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:59:10.208408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.330533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-08-20T01:59:10.208466Z","caller":"traceutil/trace.go:171","msg":"trace[624380383] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20056; }","duration":"180.446096ms","start":"2026-08-20T01:59:10.028009Z","end":"2026-08-20T01:59:10.208455Z","steps":["trace[624380383] 'agreement among raft nodes before linearized reading' (duration: 180.254272ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:59:10.20875Z","caller":"traceutil/trace.go:171","msg":"trace[253982285] transaction","detail":"{read_only:false; response_revision:20052; number_of_response:1; }","duration":"181.603039ms","start":"2026-08-20T01:59:10.027136Z","end":"2026-08-20T01:59:10.208739Z","steps":["trace[253982285] 'process raft request' (duration: 178.466638ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:59:10.208916Z","caller":"traceutil/trace.go:171","msg":"trace[492002340] transaction","detail":"{read_only:false; response_revision:20053; number_of_response:1; }","duration":"181.490447ms","start":"2026-08-20T01:59:10.027413Z","end":"2026-08-20T01:59:10.208904Z","steps":["trace[492002340] 'process raft request' (duration: 180.6392ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:59:10.209051Z","caller":"traceutil/trace.go:171","msg":"trace[1223483858] transaction","detail":"{read_only:false; response_revision:20054; number_of_response:1; }","duration":"180.474297ms","start":"2026-08-20T01:59:10.028568Z","end":"2026-08-20T01:59:10.209042Z","steps":["trace[1223483858] 'process raft request' (duration: 179.526638ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:59:10.209197Z","caller":"traceutil/trace.go:171","msg":"trace[913156277] transaction","detail":"{read_only:false; response_revision:20055; number_of_response:1; }","duration":"141.299956ms","start":"2026-08-20T01:59:10.067886Z","end":"2026-08-20T01:59:10.209186Z","steps":["trace[913156277] 'process raft request' (duration: 140.245526ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:59:26.629387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.034312ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T01:59:26.629562Z","caller":"traceutil/trace.go:171","msg":"trace[109327635] linearizableReadLoop","detail":"{readStateIndex:20823; appliedIndex:20822; }","duration":"291.450153ms","start":"2026-08-20T01:59:26.338056Z","end":"2026-08-20T01:59:26.629507Z","steps":["trace[109327635] 'read index received' (duration: 175.182996ms)","trace[109327635] 'applied index is now lower than readState.Index' (duration: 116.265137ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:59:26.629732Z","caller":"traceutil/trace.go:171","msg":"trace[1870465962] transaction","detail":"{read_only:false; response_revision:20134; number_of_response:1; }","duration":"364.985932ms","start":"2026-08-20T01:59:26.2647Z","end":"2026-08-20T01:59:26.629686Z","steps":["trace[1870465962] 'process raft request' (duration: 248.524981ms)","trace[1870465962] 'compare' (duration: 115.663255ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:59:26.629812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.467159ms","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-08-20T01:59:26.63008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.08278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-20T01:59:26.630124Z","caller":"traceutil/trace.go:171","msg":"trace[1169014477] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20134; }","duration":"331.838346ms","start":"2026-08-20T01:59:26.298258Z","end":"2026-08-20T01:59:26.630096Z","steps":["trace[1169014477] 'agreement among raft nodes before linearized reading' (duration: 331.318997ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:59:26.630147Z","caller":"traceutil/trace.go:171","msg":"trace[112925767] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20134; }","duration":"345.185061ms","start":"2026-08-20T01:59:26.284943Z","end":"2026-08-20T01:59:26.630128Z","steps":["trace[112925767] 'agreement among raft nodes before linearized reading' (duration: 344.810034ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:59:26.629815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.479508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-08-20T01:59:26.630173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:59:26.298239Z","time spent":"331.923737ms","remote":"127.0.0.1:46350","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-08-20T01:59:26.630185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:59:26.28493Z","time spent":"345.246753ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-20T01:59:26.630194Z","caller":"traceutil/trace.go:171","msg":"trace[113753873] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20134; }","duration":"333.914096ms","start":"2026-08-20T01:59:26.296266Z","end":"2026-08-20T01:59:26.63018Z","steps":["trace[113753873] 'agreement among raft nodes before linearized reading' (duration: 333.373406ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:59:26.630235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:59:26.296243Z","time spent":"333.984617ms","remote":"127.0.0.1:46350","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-08-20T01:59:26.630197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:59:26.264676Z","time spent":"365.402969ms","remote":"127.0.0.1:46350","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-08-20T01:59:26.647177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.952156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T01:59:26.647346Z","caller":"traceutil/trace.go:171","msg":"trace[1283053488] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:20135; }","duration":"274.165ms","start":"2026-08-20T01:59:26.373124Z","end":"2026-08-20T01:59:26.647289Z","steps":["trace[1283053488] 'agreement among raft nodes before linearized reading' (duration: 273.305434ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:59:26.647618Z","caller":"traceutil/trace.go:171","msg":"trace[495456133] transaction","detail":"{read_only:false; response_revision:20135; number_of_response:1; }","duration":"304.497313ms","start":"2026-08-20T01:59:26.343044Z","end":"2026-08-20T01:59:26.647541Z","steps":["trace[495456133] 'process raft request' (duration: 303.216868ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:59:26.647756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:59:26.34302Z","time spent":"304.662116ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:03:36.147508Z","caller":"traceutil/trace.go:171","msg":"trace[1303073660] transaction","detail":"{read_only:false; response_revision:22717; number_of_response:1; }","duration":"205.587018ms","start":"2026-08-20T02:03:35.94188Z","end":"2026-08-20T02:03:36.147467Z","steps":["trace[1303073660] 'process raft request' (duration: 205.305903ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:05.442586Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20027} {"level":"info","ts":"2026-08-20T02:04:05.555837Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20027,"took":"107.141683ms","hash":350168262,"current-db-size-bytes":59011072,"current-db-size":"59 MB","current-db-size-in-use-bytes":42258432,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-08-20T02:04:05.555898Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":350168262,"revision":20027,"compact-revision":17747} {"level":"info","ts":"2026-08-20T02:07:10.432448Z","caller":"traceutil/trace.go:171","msg":"trace[693917511] transaction","detail":"{read_only:false; response_revision:24925; number_of_response:1; }","duration":"149.571132ms","start":"2026-08-20T02:07:10.282856Z","end":"2026-08-20T02:07:10.432427Z","steps":["trace[693917511] 'process raft request' (duration: 147.670874ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:15.545355Z","caller":"traceutil/trace.go:171","msg":"trace[1398432916] linearizableReadLoop","detail":"{readStateIndex:25783; appliedIndex:25782; }","duration":"180.151467ms","start":"2026-08-20T02:07:15.365177Z","end":"2026-08-20T02:07:15.545328Z","steps":["trace[1398432916] 'read index received' (duration: 179.978213ms)","trace[1398432916] 'applied index is now lower than readState.Index' (duration: 172.134µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:07:15.545441Z","caller":"traceutil/trace.go:171","msg":"trace[2066536165] transaction","detail":"{read_only:false; response_revision:24958; number_of_response:1; }","duration":"214.660427ms","start":"2026-08-20T02:07:15.330751Z","end":"2026-08-20T02:07:15.545412Z","steps":["trace[2066536165] 'process raft request' (duration: 214.420672ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:15.54559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.402591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-08-20T02:07:15.54563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.126912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-08-20T02:07:15.545638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.670081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:07:15.545643Z","caller":"traceutil/trace.go:171","msg":"trace[307662707] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24958; }","duration":"180.502253ms","start":"2026-08-20T02:07:15.365127Z","end":"2026-08-20T02:07:15.545629Z","steps":["trace[307662707] 'agreement among raft nodes before linearized reading' (duration: 180.30327ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:15.545668Z","caller":"traceutil/trace.go:171","msg":"trace[1345483886] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24958; }","duration":"129.718562ms","start":"2026-08-20T02:07:15.415941Z","end":"2026-08-20T02:07:15.54566Z","steps":["trace[1345483886] 'agreement among raft nodes before linearized reading' (duration: 129.662671ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:15.545668Z","caller":"traceutil/trace.go:171","msg":"trace[923266548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24958; }","duration":"174.220664ms","start":"2026-08-20T02:07:15.371437Z","end":"2026-08-20T02:07:15.545658Z","steps":["trace[923266548] 'agreement among raft nodes before linearized reading' (duration: 174.049211ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:15.545678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.308899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-20T02:07:15.545749Z","caller":"traceutil/trace.go:171","msg":"trace[1407232959] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24958; }","duration":"158.40716ms","start":"2026-08-20T02:07:15.387321Z","end":"2026-08-20T02:07:15.545728Z","steps":["trace[1407232959] 'agreement among raft nodes before linearized reading' (duration: 158.180896ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:15.545828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.182082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-20T02:07:15.545877Z","caller":"traceutil/trace.go:171","msg":"trace[913483834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24958; }","duration":"174.269255ms","start":"2026-08-20T02:07:15.3716Z","end":"2026-08-20T02:07:15.54587Z","steps":["trace[913483834] 'agreement among raft nodes before linearized reading' (duration: 174.174813ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:15.545828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.930405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:07:15.545925Z","caller":"traceutil/trace.go:171","msg":"trace[1419980124] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:24958; }","duration":"166.056617ms","start":"2026-08-20T02:07:15.379851Z","end":"2026-08-20T02:07:15.545908Z","steps":["trace[1419980124] 'agreement among raft nodes before linearized reading' (duration: 165.921385ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:15.545965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.737432ms","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-08-20T02:07:15.546009Z","caller":"traceutil/trace.go:171","msg":"trace[1355669633] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24958; }","duration":"163.794203ms","start":"2026-08-20T02:07:15.382201Z","end":"2026-08-20T02:07:15.545995Z","steps":["trace[1355669633] 'agreement among raft nodes before linearized reading' (duration: 163.661141ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:15.664576Z","caller":"traceutil/trace.go:171","msg":"trace[1429826426] transaction","detail":"{read_only:false; response_revision:24959; number_of_response:1; }","duration":"114.134534ms","start":"2026-08-20T02:07:15.550425Z","end":"2026-08-20T02:07:15.66456Z","steps":["trace[1429826426] 'process raft request' (duration: 108.429825ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:15.842548Z","caller":"traceutil/trace.go:171","msg":"trace[376981794] transaction","detail":"{read_only:false; response_revision:24960; number_of_response:1; }","duration":"291.883945ms","start":"2026-08-20T02:07:15.550632Z","end":"2026-08-20T02:07:15.842516Z","steps":["trace[376981794] 'process raft request' (duration: 258.062088ms)","trace[376981794] 'compare' (duration: 33.45896ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:07:15.842786Z","caller":"traceutil/trace.go:171","msg":"trace[1177360367] transaction","detail":"{read_only:false; response_revision:24962; number_of_response:1; }","duration":"291.417305ms","start":"2026-08-20T02:07:15.551359Z","end":"2026-08-20T02:07:15.842776Z","steps":["trace[1177360367] 'process raft request' (duration: 291.120539ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:15.842961Z","caller":"traceutil/trace.go:171","msg":"trace[1703400866] transaction","detail":"{read_only:false; response_revision:24964; number_of_response:1; }","duration":"184.670734ms","start":"2026-08-20T02:07:15.658269Z","end":"2026-08-20T02:07:15.842939Z","steps":["trace[1703400866] 'process raft request' (duration: 184.303797ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:15.843015Z","caller":"traceutil/trace.go:171","msg":"trace[268852274] transaction","detail":"{read_only:false; response_revision:24961; number_of_response:1; }","duration":"292.291542ms","start":"2026-08-20T02:07:15.550714Z","end":"2026-08-20T02:07:15.843006Z","steps":["trace[268852274] 'process raft request' (duration: 291.62712ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:15.843138Z","caller":"traceutil/trace.go:171","msg":"trace[1501653731] transaction","detail":"{read_only:false; response_revision:24963; number_of_response:1; }","duration":"289.034079ms","start":"2026-08-20T02:07:15.554096Z","end":"2026-08-20T02:07:15.84313Z","steps":["trace[1501653731] 'process raft request' (duration: 288.434178ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:16.086131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.04937ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T02:07:16.086259Z","caller":"traceutil/trace.go:171","msg":"trace[961458435] transaction","detail":"{read_only:false; response_revision:24965; number_of_response:1; }","duration":"223.512736ms","start":"2026-08-20T02:07:15.862723Z","end":"2026-08-20T02:07:16.086236Z","steps":["trace[961458435] 'process raft request' (duration: 107.250662ms)","trace[961458435] 'compare' (duration: 115.837806ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:07:18.117334Z","caller":"traceutil/trace.go:171","msg":"trace[2040312358] transaction","detail":"{read_only:false; response_revision:24973; number_of_response:1; }","duration":"266.829025ms","start":"2026-08-20T02:07:17.850478Z","end":"2026-08-20T02:07:18.117307Z","steps":["trace[2040312358] 'process raft request' (duration: 266.672661ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:18.405693Z","caller":"traceutil/trace.go:171","msg":"trace[1724779267] transaction","detail":"{read_only:false; response_revision:24974; number_of_response:1; }","duration":"549.870659ms","start":"2026-08-20T02:07:17.855788Z","end":"2026-08-20T02:07:18.405658Z","steps":["trace[1724779267] 'process raft request' (duration: 546.144088ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:18.405778Z","caller":"traceutil/trace.go:171","msg":"trace[350597771] linearizableReadLoop","detail":"{readStateIndex:25802; appliedIndex:25797; }","duration":"423.723596ms","start":"2026-08-20T02:07:17.982021Z","end":"2026-08-20T02:07:18.405745Z","steps":["trace[350597771] 'read index received' (duration: 135.126585ms)","trace[350597771] 'applied index is now lower than readState.Index' (duration: 288.594941ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:07:18.405884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:17.855769Z","time spent":"550.002592ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:07:18.405939Z","caller":"traceutil/trace.go:171","msg":"trace[1701083122] transaction","detail":"{read_only:false; response_revision:24977; number_of_response:1; }","duration":"549.801388ms","start":"2026-08-20T02:07:17.856126Z","end":"2026-08-20T02:07:18.405928Z","steps":["trace[1701083122] 'process raft request' (duration: 549.540522ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:18.406078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:17.856105Z","time spent":"549.86218ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:07:18.405972Z","caller":"traceutil/trace.go:171","msg":"trace[1341659643] transaction","detail":"{read_only:false; response_revision:24976; number_of_response:1; }","duration":"549.936071ms","start":"2026-08-20T02:07:17.855992Z","end":"2026-08-20T02:07:18.405928Z","steps":["trace[1341659643] 'process raft request' (duration: 549.621754ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:18.407056Z","caller":"traceutil/trace.go:171","msg":"trace[2043137141] transaction","detail":"{read_only:false; response_revision:24975; number_of_response:1; }","duration":"550.280857ms","start":"2026-08-20T02:07:17.855817Z","end":"2026-08-20T02:07:18.406097Z","steps":["trace[2043137141] 'process raft request' (duration: 549.620814ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:18.407612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:17.855981Z","time spent":"551.097032ms","remote":"127.0.0.1:46350","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-08-20T02:07:18.407703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.671053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-20T02:07:18.40778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:17.855794Z","time spent":"551.741425ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:07:18.407802Z","caller":"traceutil/trace.go:171","msg":"trace[1508042658] transaction","detail":"{read_only:false; response_revision:24978; number_of_response:1; }","duration":"312.257593ms","start":"2026-08-20T02:07:18.093759Z","end":"2026-08-20T02:07:18.406017Z","steps":["trace[1508042658] 'process raft request' (duration: 311.938047ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:18.407955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:18.093742Z","time spent":"314.143159ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:07:18.408407Z","caller":"traceutil/trace.go:171","msg":"trace[829894414] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24978; }","duration":"426.354896ms","start":"2026-08-20T02:07:17.98196Z","end":"2026-08-20T02:07:18.408315Z","steps":["trace[829894414] 'agreement among raft nodes before linearized reading' (duration: 424.097473ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:18.408496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:17.981934Z","time spent":"426.538899ms","remote":"127.0.0.1:38256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"info","ts":"2026-08-20T02:07:18.562326Z","caller":"traceutil/trace.go:171","msg":"trace[955296503] transaction","detail":"{read_only:false; response_revision:24979; number_of_response:1; }","duration":"405.157381ms","start":"2026-08-20T02:07:18.157147Z","end":"2026-08-20T02:07:18.562304Z","steps":["trace[955296503] 'process raft request' (duration: 404.756123ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:18.562537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:18.15712Z","time spent":"405.276102ms","remote":"127.0.0.1:46350","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-08-20T02:07:18.562579Z","caller":"traceutil/trace.go:171","msg":"trace[1381733237] linearizableReadLoop","detail":"{readStateIndex:25806; appliedIndex:25803; }","duration":"156.704228ms","start":"2026-08-20T02:07:18.405853Z","end":"2026-08-20T02:07:18.562557Z","steps":["trace[1381733237] 'read index received' (duration: 156.144717ms)","trace[1381733237] 'applied index is now lower than readState.Index' (duration: 558.381µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:07:18.562625Z","caller":"traceutil/trace.go:171","msg":"trace[22281116] transaction","detail":"{read_only:false; response_revision:24981; number_of_response:1; }","duration":"225.962403ms","start":"2026-08-20T02:07:18.336638Z","end":"2026-08-20T02:07:18.5626Z","steps":["trace[22281116] 'process raft request' (duration: 225.796129ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:18.56272Z","caller":"traceutil/trace.go:171","msg":"trace[203705711] transaction","detail":"{read_only:false; response_revision:24980; number_of_response:1; }","duration":"286.39122ms","start":"2026-08-20T02:07:18.276284Z","end":"2026-08-20T02:07:18.562675Z","steps":["trace[203705711] 'process raft request' (duration: 285.966341ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:18.562749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.07669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-08-20T02:07:18.562788Z","caller":"traceutil/trace.go:171","msg":"trace[2118862223] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24981; }","duration":"486.14471ms","start":"2026-08-20T02:07:18.076633Z","end":"2026-08-20T02:07:18.562778Z","steps":["trace[2118862223] 'agreement among raft nodes before linearized reading' (duration: 486.019668ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:18.562811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:18.076618Z","time spent":"486.187072ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":583,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-08-20T02:07:18.563224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.008434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-08-20T02:07:18.563381Z","caller":"traceutil/trace.go:171","msg":"trace[1023074171] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24981; }","duration":"259.226699ms","start":"2026-08-20T02:07:18.304098Z","end":"2026-08-20T02:07:18.563325Z","steps":["trace[1023074171] 'agreement among raft nodes before linearized reading' (duration: 258.522236ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:18.57401Z","caller":"traceutil/trace.go:171","msg":"trace[1658560131] transaction","detail":"{read_only:false; response_revision:24982; number_of_response:1; }","duration":"114.535881ms","start":"2026-08-20T02:07:18.459449Z","end":"2026-08-20T02:07:18.573985Z","steps":["trace[1658560131] 'process raft request' (duration: 114.307277ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:18.574257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.463387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-20T02:07:18.574279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.87182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-20T02:07:18.574275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.932466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.186\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-08-20T02:07:18.57431Z","caller":"traceutil/trace.go:171","msg":"trace[101902071] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24983; }","duration":"158.930021ms","start":"2026-08-20T02:07:18.41537Z","end":"2026-08-20T02:07:18.5743Z","steps":["trace[101902071] 'agreement among raft nodes before linearized reading' (duration: 158.86509ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:18.574305Z","caller":"traceutil/trace.go:171","msg":"trace[1835973517] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24983; }","duration":"153.541758ms","start":"2026-08-20T02:07:18.420751Z","end":"2026-08-20T02:07:18.574293Z","steps":["trace[1835973517] 'agreement among raft nodes before linearized reading' (duration: 153.459976ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:18.574341Z","caller":"traceutil/trace.go:171","msg":"trace[1933491545] range","detail":"{range_begin:/registry/masterleases/199.19.213.186; range_end:; response_count:1; response_revision:24983; }","duration":"153.010577ms","start":"2026-08-20T02:07:18.421311Z","end":"2026-08-20T02:07:18.574322Z","steps":["trace[1933491545] 'agreement among raft nodes before linearized reading' (duration: 152.847074ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:18.841122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.133679ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T02:07:18.841525Z","caller":"traceutil/trace.go:171","msg":"trace[747916993] transaction","detail":"{read_only:false; response_revision:24984; number_of_response:1; }","duration":"273.068904ms","start":"2026-08-20T02:07:18.568434Z","end":"2026-08-20T02:07:18.841503Z","steps":["trace[747916993] 'process raft request' (duration: 130.441605ms)","trace[747916993] 'compare' (duration: 141.895544ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:07:18.841577Z","caller":"traceutil/trace.go:171","msg":"trace[1564943138] linearizableReadLoop","detail":"{readStateIndex:25809; appliedIndex:25808; }","duration":"267.401615ms","start":"2026-08-20T02:07:18.574139Z","end":"2026-08-20T02:07:18.841541Z","steps":["trace[1564943138] 'read index received' (duration: 124.918299ms)","trace[1564943138] 'applied index is now lower than readState.Index' (duration: 142.475956ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:07:18.84182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.320026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-20T02:07:18.841885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.826731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-08-20T02:07:18.841923Z","caller":"traceutil/trace.go:171","msg":"trace[1698554625] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24984; }","duration":"270.939753ms","start":"2026-08-20T02:07:18.570971Z","end":"2026-08-20T02:07:18.841911Z","steps":["trace[1698554625] 'agreement among raft nodes before linearized reading' (duration: 270.727999ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:18.841929Z","caller":"traceutil/trace.go:171","msg":"trace[1337016768] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24984; }","duration":"265.464749ms","start":"2026-08-20T02:07:18.576444Z","end":"2026-08-20T02:07:18.841909Z","steps":["trace[1337016768] 'agreement among raft nodes before linearized reading' (duration: 265.290486ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:18.841914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.771782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:07:18.842001Z","caller":"traceutil/trace.go:171","msg":"trace[472715811] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24984; }","duration":"257.894064ms","start":"2026-08-20T02:07:18.584085Z","end":"2026-08-20T02:07:18.841979Z","steps":["trace[472715811] 'agreement among raft nodes before linearized reading' (duration: 257.69864ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:18.842022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.869802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-20T02:07:18.842133Z","caller":"traceutil/trace.go:171","msg":"trace[1856817101] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24984; }","duration":"133.022815ms","start":"2026-08-20T02:07:18.709086Z","end":"2026-08-20T02:07:18.842108Z","steps":["trace[1856817101] 'agreement among raft nodes before linearized reading' (duration: 132.683919ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:19.046607Z","caller":"traceutil/trace.go:171","msg":"trace[370177406] linearizableReadLoop","detail":"{readStateIndex:25812; appliedIndex:25810; }","duration":"198.952886ms","start":"2026-08-20T02:07:18.847624Z","end":"2026-08-20T02:07:19.046577Z","steps":["trace[370177406] 'read index received' (duration: 6.685178ms)","trace[370177406] 'applied index is now lower than readState.Index' (duration: 192.266418ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:07:19.046657Z","caller":"traceutil/trace.go:171","msg":"trace[547371592] transaction","detail":"{read_only:false; response_revision:24986; number_of_response:1; }","duration":"200.521326ms","start":"2026-08-20T02:07:18.846103Z","end":"2026-08-20T02:07:19.046624Z","steps":["trace[547371592] 'process raft request' (duration: 178.437433ms)","trace[547371592] 'compare' (duration: 21.765107ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:07:19.046728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.089159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:07:19.046772Z","caller":"traceutil/trace.go:171","msg":"trace[909326957] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24986; }","duration":"199.15365ms","start":"2026-08-20T02:07:18.847606Z","end":"2026-08-20T02:07:19.04676Z","steps":["trace[909326957] 'agreement among raft nodes before linearized reading' (duration: 199.047858ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:19.046883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.677448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-08-20T02:07:19.046913Z","caller":"traceutil/trace.go:171","msg":"trace[1918496734] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24986; }","duration":"190.742929ms","start":"2026-08-20T02:07:18.856163Z","end":"2026-08-20T02:07:19.046906Z","steps":["trace[1918496734] 'agreement among raft nodes before linearized reading' (duration: 190.592126ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:19.41372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.39154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-08-20T02:07:19.413859Z","caller":"traceutil/trace.go:171","msg":"trace[135863820] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24986; }","duration":"261.559623ms","start":"2026-08-20T02:07:19.152247Z","end":"2026-08-20T02:07:19.413806Z","steps":["trace[135863820] 'range keys from in-memory index tree' (duration: 261.203007ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:19.464713Z","caller":"traceutil/trace.go:171","msg":"trace[1525101427] transaction","detail":"{read_only:false; response_revision:24987; number_of_response:1; }","duration":"173.074551ms","start":"2026-08-20T02:07:19.291604Z","end":"2026-08-20T02:07:19.464679Z","steps":["trace[1525101427] 'process raft request' (duration: 172.793466ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:20.041414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.363111ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T02:07:20.041539Z","caller":"traceutil/trace.go:171","msg":"trace[175301128] linearizableReadLoop","detail":"{readStateIndex:25815; appliedIndex:25814; }","duration":"130.657219ms","start":"2026-08-20T02:07:19.910865Z","end":"2026-08-20T02:07:20.041522Z","steps":["trace[175301128] 'read index received' (duration: 41.711µs)","trace[175301128] 'applied index is now lower than readState.Index' (duration: 130.614058ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:07:20.041822Z","caller":"traceutil/trace.go:171","msg":"trace[757052066] transaction","detail":"{read_only:false; response_revision:24989; number_of_response:1; }","duration":"361.280291ms","start":"2026-08-20T02:07:19.680508Z","end":"2026-08-20T02:07:20.041788Z","steps":["trace[757052066] 'process raft request' (duration: 123.450331ms)","trace[757052066] 'compare' (duration: 236.876032ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:07:20.041937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.069348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-08-20T02:07:20.041988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:19.680489Z","time spent":"361.423234ms","remote":"127.0.0.1:46350","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-08-20T02:07:20.042001Z","caller":"traceutil/trace.go:171","msg":"trace[812271737] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24989; }","duration":"131.17057ms","start":"2026-08-20T02:07:19.910812Z","end":"2026-08-20T02:07:20.041982Z","steps":["trace[812271737] 'agreement among raft nodes before linearized reading' (duration: 130.993366ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:20.279293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.609421ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T02:07:20.279411Z","caller":"traceutil/trace.go:171","msg":"trace[2031835006] linearizableReadLoop","detail":"{readStateIndex:25816; appliedIndex:25815; }","duration":"230.931338ms","start":"2026-08-20T02:07:20.04846Z","end":"2026-08-20T02:07:20.279392Z","steps":["trace[2031835006] 'read index received' (duration: 114.137883ms)","trace[2031835006] 'applied index is now lower than readState.Index' (duration: 116.791385ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:07:20.279498Z","caller":"traceutil/trace.go:171","msg":"trace[780866795] transaction","detail":"{read_only:false; response_revision:24990; number_of_response:1; }","duration":"232.147121ms","start":"2026-08-20T02:07:20.047311Z","end":"2026-08-20T02:07:20.279458Z","steps":["trace[780866795] 'process raft request' (duration: 115.267945ms)","trace[780866795] 'compare' (duration: 116.369616ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:07:20.279621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.160592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18230"} {"level":"info","ts":"2026-08-20T02:07:20.279657Z","caller":"traceutil/trace.go:171","msg":"trace[471193871] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24990; }","duration":"231.250524ms","start":"2026-08-20T02:07:20.048395Z","end":"2026-08-20T02:07:20.279645Z","steps":["trace[471193871] 'agreement among raft nodes before linearized reading' (duration: 231.07243ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:20.413929Z","caller":"traceutil/trace.go:171","msg":"trace[745230779] linearizableReadLoop","detail":"{readStateIndex:25817; appliedIndex:25816; }","duration":"134.469412ms","start":"2026-08-20T02:07:20.279437Z","end":"2026-08-20T02:07:20.413907Z","steps":["trace[745230779] 'read index received' (duration: 134.299179ms)","trace[745230779] 'applied index is now lower than readState.Index' (duration: 169.083µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:07:20.413983Z","caller":"traceutil/trace.go:171","msg":"trace[1602500995] transaction","detail":"{read_only:false; response_revision:24991; number_of_response:1; }","duration":"289.300434ms","start":"2026-08-20T02:07:20.124661Z","end":"2026-08-20T02:07:20.413962Z","steps":["trace[1602500995] 'process raft request' (duration: 289.08737ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:20.414086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.333535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:07:20.414119Z","caller":"traceutil/trace.go:171","msg":"trace[1402326070] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:24991; }","duration":"324.403986ms","start":"2026-08-20T02:07:20.089704Z","end":"2026-08-20T02:07:20.414108Z","steps":["trace[1402326070] 'agreement among raft nodes before linearized reading' (duration: 324.317305ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:20.414146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:20.089694Z","time spent":"324.445778ms","remote":"127.0.0.1:47420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-08-20T02:07:20.414256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.299163ms","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:1936"} {"level":"info","ts":"2026-08-20T02:07:20.414321Z","caller":"traceutil/trace.go:171","msg":"trace[736511737] 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:24991; }","duration":"222.364755ms","start":"2026-08-20T02:07:20.191931Z","end":"2026-08-20T02:07:20.414296Z","steps":["trace[736511737] 'agreement among raft nodes before linearized reading' (duration: 222.045879ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:20.414363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.343046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:07:20.414394Z","caller":"traceutil/trace.go:171","msg":"trace[1500512388] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:24991; }","duration":"357.412257ms","start":"2026-08-20T02:07:20.056973Z","end":"2026-08-20T02:07:20.414385Z","steps":["trace[1500512388] 'agreement among raft nodes before linearized reading' (duration: 357.346936ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:20.414413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:20.056957Z","time spent":"357.450358ms","remote":"127.0.0.1:38008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true "} {"level":"info","ts":"2026-08-20T02:07:20.772463Z","caller":"traceutil/trace.go:171","msg":"trace[225182229] transaction","detail":"{read_only:false; response_revision:24997; number_of_response:1; }","duration":"203.671117ms","start":"2026-08-20T02:07:20.56876Z","end":"2026-08-20T02:07:20.772431Z","steps":["trace[225182229] 'process raft request' (duration: 203.492514ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:20.792423Z","caller":"traceutil/trace.go:171","msg":"trace[1792086121] transaction","detail":"{read_only:false; response_revision:24999; number_of_response:1; }","duration":"213.727519ms","start":"2026-08-20T02:07:20.578666Z","end":"2026-08-20T02:07:20.792393Z","steps":["trace[1792086121] 'process raft request' (duration: 213.615137ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:20.792472Z","caller":"traceutil/trace.go:171","msg":"trace[436952331] transaction","detail":"{read_only:false; response_revision:24998; number_of_response:1; }","duration":"223.402763ms","start":"2026-08-20T02:07:20.569004Z","end":"2026-08-20T02:07:20.792406Z","steps":["trace[436952331] 'process raft request' (duration: 223.106507ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:20.792622Z","caller":"traceutil/trace.go:171","msg":"trace[1841620021] transaction","detail":"{read_only:false; response_revision:25000; number_of_response:1; }","duration":"212.357862ms","start":"2026-08-20T02:07:20.580234Z","end":"2026-08-20T02:07:20.792592Z","steps":["trace[1841620021] 'process raft request' (duration: 212.108268ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:21.090178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.873147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-20T02:07:21.090264Z","caller":"traceutil/trace.go:171","msg":"trace[205366994] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:25000; }","duration":"139.02785ms","start":"2026-08-20T02:07:20.951214Z","end":"2026-08-20T02:07:21.090242Z","steps":["trace[205366994] 'count revisions from in-memory index tree' (duration: 138.710374ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:21.539509Z","caller":"traceutil/trace.go:171","msg":"trace[1919675673] linearizableReadLoop","detail":"{readStateIndex:25828; appliedIndex:25827; }","duration":"123.284598ms","start":"2026-08-20T02:07:21.416191Z","end":"2026-08-20T02:07:21.539475Z","steps":["trace[1919675673] 'read index received' (duration: 123.034163ms)","trace[1919675673] 'applied index is now lower than readState.Index' (duration: 248.955µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:07:21.539606Z","caller":"traceutil/trace.go:171","msg":"trace[664954008] transaction","detail":"{read_only:false; response_revision:25002; number_of_response:1; }","duration":"160.754736ms","start":"2026-08-20T02:07:21.378815Z","end":"2026-08-20T02:07:21.53957Z","steps":["trace[664954008] 'process raft request' (duration: 160.44652ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:21.539729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.516672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-20T02:07:21.539942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.558395ms","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-08-20T02:07:21.539989Z","caller":"traceutil/trace.go:171","msg":"trace[710257624] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25002; }","duration":"123.796507ms","start":"2026-08-20T02:07:21.416176Z","end":"2026-08-20T02:07:21.539972Z","steps":["trace[710257624] 'agreement among raft nodes before linearized reading' (duration: 123.460731ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:21.54001Z","caller":"traceutil/trace.go:171","msg":"trace[30485692] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:25002; }","duration":"121.860451ms","start":"2026-08-20T02:07:21.418115Z","end":"2026-08-20T02:07:21.539976Z","steps":["trace[30485692] 'agreement among raft nodes before linearized reading' (duration: 121.544066ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:21.69949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.182292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:07:21.699568Z","caller":"traceutil/trace.go:171","msg":"trace[1797280940] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:25002; }","duration":"106.691011ms","start":"2026-08-20T02:07:21.592858Z","end":"2026-08-20T02:07:21.699549Z","steps":["trace[1797280940] 'count revisions from in-memory index tree' (duration: 106.083459ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:22.00863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.043579ms","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:8"} {"level":"info","ts":"2026-08-20T02:07:22.008737Z","caller":"traceutil/trace.go:171","msg":"trace[202940272] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:25003; }","duration":"129.243402ms","start":"2026-08-20T02:07:21.879472Z","end":"2026-08-20T02:07:22.008716Z","steps":["trace[202940272] 'count revisions from in-memory index tree' (duration: 128.897055ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:22.636864Z","caller":"traceutil/trace.go:171","msg":"trace[1111415554] transaction","detail":"{read_only:false; response_revision:25006; number_of_response:1; }","duration":"216.719885ms","start":"2026-08-20T02:07:22.420115Z","end":"2026-08-20T02:07:22.636835Z","steps":["trace[1111415554] 'process raft request' (duration: 216.508651ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:22.640586Z","caller":"traceutil/trace.go:171","msg":"trace[1403153822] linearizableReadLoop","detail":"{readStateIndex:25833; appliedIndex:25831; }","duration":"161.364617ms","start":"2026-08-20T02:07:22.479197Z","end":"2026-08-20T02:07:22.640561Z","steps":["trace[1403153822] 'read index received' (duration: 157.375561ms)","trace[1403153822] 'applied index is now lower than readState.Index' (duration: 3.988116ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:07:22.640636Z","caller":"traceutil/trace.go:171","msg":"trace[2127919477] transaction","detail":"{read_only:false; response_revision:25007; number_of_response:1; }","duration":"209.679222ms","start":"2026-08-20T02:07:22.430925Z","end":"2026-08-20T02:07:22.640604Z","steps":["trace[2127919477] 'process raft request' (duration: 209.486848ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:22.640883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.604961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-20T02:07:22.640885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.657782ms","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-08-20T02:07:22.640929Z","caller":"traceutil/trace.go:171","msg":"trace[1010549804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25007; }","duration":"161.706613ms","start":"2026-08-20T02:07:22.47921Z","end":"2026-08-20T02:07:22.640916Z","steps":["trace[1010549804] 'agreement among raft nodes before linearized reading' (duration: 161.52075ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:22.640931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.601771ms","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-08-20T02:07:22.640966Z","caller":"traceutil/trace.go:171","msg":"trace[920711652] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25007; }","duration":"161.788965ms","start":"2026-08-20T02:07:22.479155Z","end":"2026-08-20T02:07:22.640944Z","steps":["trace[920711652] 'agreement among raft nodes before linearized reading' (duration: 161.54347ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:22.640985Z","caller":"traceutil/trace.go:171","msg":"trace[1202978727] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25007; }","duration":"161.699892ms","start":"2026-08-20T02:07:22.479268Z","end":"2026-08-20T02:07:22.640968Z","steps":["trace[1202978727] 'agreement among raft nodes before linearized reading' (duration: 161.515679ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:22.641105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.796065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-20T02:07:22.641148Z","caller":"traceutil/trace.go:171","msg":"trace[529862382] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25007; }","duration":"161.889547ms","start":"2026-08-20T02:07:22.479245Z","end":"2026-08-20T02:07:22.641134Z","steps":["trace[529862382] 'agreement among raft nodes before linearized reading' (duration: 161.796845ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:22.939093Z","caller":"traceutil/trace.go:171","msg":"trace[964833886] transaction","detail":"{read_only:false; response_revision:25008; number_of_response:1; }","duration":"293.085156ms","start":"2026-08-20T02:07:22.645975Z","end":"2026-08-20T02:07:22.93906Z","steps":["trace[964833886] 'process raft request' (duration: 255.905215ms)","trace[964833886] 'compare' (duration: 36.938317ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:07:23.08324Z","caller":"traceutil/trace.go:171","msg":"trace[2077717940] transaction","detail":"{read_only:false; response_revision:25009; number_of_response:1; }","duration":"436.99715ms","start":"2026-08-20T02:07:22.646216Z","end":"2026-08-20T02:07:23.083213Z","steps":["trace[2077717940] 'process raft request' (duration: 436.765006ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:23.083435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:22.646192Z","time spent":"437.138662ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:07:23.083524Z","caller":"traceutil/trace.go:171","msg":"trace[1903067849] transaction","detail":"{read_only:false; response_revision:25010; number_of_response:1; }","duration":"437.01489ms","start":"2026-08-20T02:07:22.646473Z","end":"2026-08-20T02:07:23.083488Z","steps":["trace[1903067849] 'process raft request' (duration: 436.674694ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:23.083624Z","caller":"traceutil/trace.go:171","msg":"trace[1159359318] transaction","detail":"{read_only:false; response_revision:25011; number_of_response:1; }","duration":"437.106223ms","start":"2026-08-20T02:07:22.646488Z","end":"2026-08-20T02:07:23.083594Z","steps":["trace[1159359318] 'process raft request' (duration: 436.883978ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:23.083669Z","caller":"traceutil/trace.go:171","msg":"trace[1524021066] transaction","detail":"{read_only:false; response_revision:25013; number_of_response:1; }","duration":"280.011217ms","start":"2026-08-20T02:07:22.803637Z","end":"2026-08-20T02:07:23.083648Z","steps":["trace[1524021066] 'process raft request' (duration: 279.909204ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:23.083787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:22.646452Z","time spent":"437.238946ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:07:23.083791Z","caller":"traceutil/trace.go:171","msg":"trace[1539505492] transaction","detail":"{read_only:false; response_revision:25014; number_of_response:1; }","duration":"280.001736ms","start":"2026-08-20T02:07:22.803774Z","end":"2026-08-20T02:07:23.083775Z","steps":["trace[1539505492] 'process raft request' (duration: 279.828203ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:23.08368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:22.646464Z","time spent":"437.128993ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:07:23.0839Z","caller":"traceutil/trace.go:171","msg":"trace[1279543717] linearizableReadLoop","detail":"{readStateIndex:25841; appliedIndex:25834; }","duration":"113.853769ms","start":"2026-08-20T02:07:22.970034Z","end":"2026-08-20T02:07:23.083888Z","steps":["trace[1279543717] 'read index received' (duration: 112.87343ms)","trace[1279543717] 'applied index is now lower than readState.Index' (duration: 979.079µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:07:23.083953Z","caller":"traceutil/trace.go:171","msg":"trace[712044197] transaction","detail":"{read_only:false; response_revision:25015; number_of_response:1; }","duration":"280.131009ms","start":"2026-08-20T02:07:22.803809Z","end":"2026-08-20T02:07:23.08394Z","steps":["trace[712044197] 'process raft request' (duration: 279.901465ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:23.083978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.934841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:07:23.084013Z","caller":"traceutil/trace.go:171","msg":"trace[1195450121] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:25015; }","duration":"114.004261ms","start":"2026-08-20T02:07:22.969998Z","end":"2026-08-20T02:07:23.084002Z","steps":["trace[1195450121] 'agreement among raft nodes before linearized reading' (duration: 113.93523ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:23.08391Z","caller":"traceutil/trace.go:171","msg":"trace[1484584699] transaction","detail":"{read_only:false; response_revision:25012; number_of_response:1; }","duration":"305.186239ms","start":"2026-08-20T02:07:22.778564Z","end":"2026-08-20T02:07:23.08375Z","steps":["trace[1484584699] 'process raft request' (duration: 304.920353ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:23.08529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:22.778537Z","time spent":"306.090865ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:07:23.304785Z","caller":"traceutil/trace.go:171","msg":"trace[1153597175] linearizableReadLoop","detail":"{readStateIndex:25842; appliedIndex:25841; }","duration":"186.006038ms","start":"2026-08-20T02:07:23.118744Z","end":"2026-08-20T02:07:23.30475Z","steps":["trace[1153597175] 'read index received' (duration: 123.205617ms)","trace[1153597175] 'applied index is now lower than readState.Index' (duration: 62.799191ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:07:23.305165Z","caller":"traceutil/trace.go:171","msg":"trace[1818236673] transaction","detail":"{read_only:false; response_revision:25016; number_of_response:1; }","duration":"194.569612ms","start":"2026-08-20T02:07:23.110576Z","end":"2026-08-20T02:07:23.305145Z","steps":["trace[1818236673] 'process raft request' (duration: 131.379853ms)","trace[1818236673] 'compare' (duration: 62.659388ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:07:23.305331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.558049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-20T02:07:23.305372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.118791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2083"} {"level":"info","ts":"2026-08-20T02:07:23.305402Z","caller":"traceutil/trace.go:171","msg":"trace[764434872] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:25016; }","duration":"186.66636ms","start":"2026-08-20T02:07:23.118719Z","end":"2026-08-20T02:07:23.305385Z","steps":["trace[764434872] 'agreement among raft nodes before linearized reading' (duration: 186.532918ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:23.305592Z","caller":"traceutil/trace.go:171","msg":"trace[359829698] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:25016; }","duration":"128.222533ms","start":"2026-08-20T02:07:23.177186Z","end":"2026-08-20T02:07:23.305409Z","steps":["trace[359829698] 'agreement among raft nodes before linearized reading' (duration: 128.020699ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:23.811611Z","caller":"traceutil/trace.go:171","msg":"trace[1241533200] transaction","detail":"{read_only:false; response_revision:25017; number_of_response:1; }","duration":"106.776293ms","start":"2026-08-20T02:07:23.704809Z","end":"2026-08-20T02:07:23.811586Z","steps":["trace[1241533200] 'process raft request' (duration: 106.61161ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:24.236602Z","caller":"traceutil/trace.go:171","msg":"trace[1458618805] transaction","detail":"{read_only:false; response_revision:25018; number_of_response:1; }","duration":"100.989461ms","start":"2026-08-20T02:07:24.135581Z","end":"2026-08-20T02:07:24.236571Z","steps":["trace[1458618805] 'process raft request' (duration: 100.745677ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:24.596242Z","caller":"traceutil/trace.go:171","msg":"trace[1128803653] transaction","detail":"{read_only:false; response_revision:25019; number_of_response:1; }","duration":"123.517112ms","start":"2026-08-20T02:07:24.472685Z","end":"2026-08-20T02:07:24.596202Z","steps":["trace[1128803653] 'process raft request' (duration: 123.212827ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:27.494485Z","caller":"traceutil/trace.go:171","msg":"trace[1176057130] transaction","detail":"{read_only:false; response_revision:25035; number_of_response:1; }","duration":"537.203937ms","start":"2026-08-20T02:07:26.957254Z","end":"2026-08-20T02:07:27.494458Z","steps":["trace[1176057130] 'process raft request' (duration: 537.036124ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:27.494732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:26.957225Z","time spent":"537.336319ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:07:27.496884Z","caller":"traceutil/trace.go:171","msg":"trace[497138838] transaction","detail":"{read_only:false; response_revision:25036; number_of_response:1; }","duration":"400.336929ms","start":"2026-08-20T02:07:27.096521Z","end":"2026-08-20T02:07:27.496858Z","steps":["trace[497138838] 'process raft request' (duration: 400.060953ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:27.496886Z","caller":"traceutil/trace.go:171","msg":"trace[1749362646] transaction","detail":"{read_only:false; response_revision:25038; number_of_response:1; }","duration":"386.817871ms","start":"2026-08-20T02:07:27.110044Z","end":"2026-08-20T02:07:27.496862Z","steps":["trace[1749362646] 'process raft request' (duration: 386.719778ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:27.496918Z","caller":"traceutil/trace.go:171","msg":"trace[1125698427] transaction","detail":"{read_only:false; response_revision:25037; number_of_response:1; }","duration":"387.33288ms","start":"2026-08-20T02:07:27.109561Z","end":"2026-08-20T02:07:27.496894Z","steps":["trace[1125698427] 'process raft request' (duration: 387.158686ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:27.497026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:27.109549Z","time spent":"387.422222ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:07:27.497062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:27.096483Z","time spent":"400.471951ms","remote":"127.0.0.1:46350","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-08-20T02:07:27.497102Z","caller":"traceutil/trace.go:171","msg":"trace[882041463] linearizableReadLoop","detail":"{readStateIndex:25865; appliedIndex:25861; }","duration":"385.449495ms","start":"2026-08-20T02:07:27.111623Z","end":"2026-08-20T02:07:27.497073Z","steps":["trace[882041463] 'read index received' (duration: 382.718652ms)","trace[882041463] 'applied index is now lower than readState.Index' (duration: 2.728723ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:07:27.497165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:27.109993Z","time spent":"386.973004ms","remote":"127.0.0.1:46350","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-08-20T02:07:27.497393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.213889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-20T02:07:27.497956Z","caller":"traceutil/trace.go:171","msg":"trace[1202539605] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25039; }","duration":"385.784039ms","start":"2026-08-20T02:07:27.112149Z","end":"2026-08-20T02:07:27.497933Z","steps":["trace[1202539605] 'agreement among raft nodes before linearized reading' (duration: 385.133637ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:27.498019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:27.112141Z","time spent":"385.860591ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-20T02:07:27.497362Z","caller":"traceutil/trace.go:171","msg":"trace[1744994784] transaction","detail":"{read_only:false; response_revision:25039; number_of_response:1; }","duration":"383.550328ms","start":"2026-08-20T02:07:27.113648Z","end":"2026-08-20T02:07:27.497199Z","steps":["trace[1744994784] 'process raft request' (duration: 383.156991ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:27.497424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.230863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:07:27.498258Z","caller":"traceutil/trace.go:171","msg":"trace[145049513] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:25039; }","duration":"140.108111ms","start":"2026-08-20T02:07:27.358135Z","end":"2026-08-20T02:07:27.498243Z","steps":["trace[145049513] 'agreement among raft nodes before linearized reading' (duration: 139.244784ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:27.498291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:27.113631Z","time spent":"384.517335ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:07:27.497426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.776081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-20T02:07:27.498383Z","caller":"traceutil/trace.go:171","msg":"trace[1373018526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25039; }","duration":"386.79185ms","start":"2026-08-20T02:07:27.11157Z","end":"2026-08-20T02:07:27.498362Z","steps":["trace[1373018526] 'agreement among raft nodes before linearized reading' (duration: 385.660269ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:27.498455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:27.11155Z","time spent":"386.885142ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-20T02:07:27.497499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.507027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-20T02:07:27.498557Z","caller":"traceutil/trace.go:171","msg":"trace[1282701565] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25039; }","duration":"176.578728ms","start":"2026-08-20T02:07:27.321947Z","end":"2026-08-20T02:07:27.498526Z","steps":["trace[1282701565] 'agreement among raft nodes before linearized reading' (duration: 175.472217ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:27.497578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.72031ms","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-08-20T02:07:27.498667Z","caller":"traceutil/trace.go:171","msg":"trace[1157880674] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25039; }","duration":"385.808921ms","start":"2026-08-20T02:07:27.112828Z","end":"2026-08-20T02:07:27.498637Z","steps":["trace[1157880674] 'agreement among raft nodes before linearized reading' (duration: 384.636089ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:27.498738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:27.112819Z","time spent":"385.889763ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-20T02:07:40.520572Z","caller":"traceutil/trace.go:171","msg":"trace[99695865] linearizableReadLoop","detail":"{readStateIndex:26024; appliedIndex:26023; }","duration":"104.06949ms","start":"2026-08-20T02:07:40.416473Z","end":"2026-08-20T02:07:40.520543Z","steps":["trace[99695865] 'read index received' (duration: 103.885177ms)","trace[99695865] 'applied index is now lower than readState.Index' (duration: 183.283µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:07:40.520714Z","caller":"traceutil/trace.go:171","msg":"trace[905189753] transaction","detail":"{read_only:false; response_revision:25194; number_of_response:1; }","duration":"183.222964ms","start":"2026-08-20T02:07:40.33744Z","end":"2026-08-20T02:07:40.520663Z","steps":["trace[905189753] 'process raft request' (duration: 182.924509ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:40.520771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.267344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:07:40.520818Z","caller":"traceutil/trace.go:171","msg":"trace[1089434242] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25194; }","duration":"104.390376ms","start":"2026-08-20T02:07:40.416415Z","end":"2026-08-20T02:07:40.520806Z","steps":["trace[1089434242] 'agreement among raft nodes before linearized reading' (duration: 104.282484ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:40.520848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.457451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:07:40.520933Z","caller":"traceutil/trace.go:171","msg":"trace[1557163767] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:25194; }","duration":"100.592194ms","start":"2026-08-20T02:07:40.420324Z","end":"2026-08-20T02:07:40.520916Z","steps":["trace[1557163767] 'agreement among raft nodes before linearized reading' (duration: 100.41936ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:40.836551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.072144ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T02:07:40.83674Z","caller":"traceutil/trace.go:171","msg":"trace[792482518] transaction","detail":"{read_only:false; response_revision:25195; number_of_response:1; }","duration":"291.458515ms","start":"2026-08-20T02:07:40.545224Z","end":"2026-08-20T02:07:40.836683Z","steps":["trace[792482518] 'process raft request' (duration: 132.709859ms)","trace[792482518] 'compare' (duration: 157.928161ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:07:41.138072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.898301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:07:41.138192Z","caller":"traceutil/trace.go:171","msg":"trace[1800403165] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25197; }","duration":"203.083195ms","start":"2026-08-20T02:07:40.935089Z","end":"2026-08-20T02:07:41.138172Z","steps":["trace[1800403165] 'range keys from in-memory index tree' (duration: 202.82236ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:41.138348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.499677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-925924d7-c0e3-4ad7-a1e7-cbca3a6af4c1\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-08-20T02:07:41.138446Z","caller":"traceutil/trace.go:171","msg":"trace[1738952234] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-925924d7-c0e3-4ad7-a1e7-cbca3a6af4c1; range_end:; response_count:1; response_revision:25197; }","duration":"199.626979ms","start":"2026-08-20T02:07:40.9388Z","end":"2026-08-20T02:07:41.138427Z","steps":["trace[1738952234] 'range keys from in-memory index tree' (duration: 199.438076ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:41.805146Z","caller":"traceutil/trace.go:171","msg":"trace[1471665068] transaction","detail":"{read_only:false; response_revision:25198; number_of_response:1; }","duration":"236.739569ms","start":"2026-08-20T02:07:41.568378Z","end":"2026-08-20T02:07:41.805118Z","steps":["trace[1471665068] 'process raft request' (duration: 236.542196ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:41.970613Z","caller":"traceutil/trace.go:171","msg":"trace[764581351] linearizableReadLoop","detail":"{readStateIndex:26031; appliedIndex:26027; }","duration":"376.775238ms","start":"2026-08-20T02:07:41.593809Z","end":"2026-08-20T02:07:41.970585Z","steps":["trace[764581351] 'read index received' (duration: 211.245071ms)","trace[764581351] 'applied index is now lower than readState.Index' (duration: 165.529087ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:07:41.970694Z","caller":"traceutil/trace.go:171","msg":"trace[1207469892] transaction","detail":"{read_only:false; response_revision:25201; number_of_response:1; }","duration":"387.119626ms","start":"2026-08-20T02:07:41.583528Z","end":"2026-08-20T02:07:41.970648Z","steps":["trace[1207469892] 'process raft request' (duration: 386.863971ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:41.97089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.061783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-08-20T02:07:41.9709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:41.583506Z","time spent":"387.288579ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:07:41.970945Z","caller":"traceutil/trace.go:171","msg":"trace[671789160] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25201; }","duration":"377.172305ms","start":"2026-08-20T02:07:41.593754Z","end":"2026-08-20T02:07:41.970926Z","steps":["trace[671789160] 'agreement among raft nodes before linearized reading' (duration: 376.963481ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:41.970988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:41.593735Z","time spent":"377.243386ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-20T02:07:41.970517Z","caller":"traceutil/trace.go:171","msg":"trace[911837626] transaction","detail":"{read_only:false; response_revision:25199; number_of_response:1; }","duration":"401.028603ms","start":"2026-08-20T02:07:41.569452Z","end":"2026-08-20T02:07:41.97048Z","steps":["trace[911837626] 'process raft request' (duration: 400.665676ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:41.971228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:41.569438Z","time spent":"401.709275ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:07:41.970481Z","caller":"traceutil/trace.go:171","msg":"trace[377575150] transaction","detail":"{read_only:false; response_revision:25200; number_of_response:1; }","duration":"390.881618ms","start":"2026-08-20T02:07:41.579566Z","end":"2026-08-20T02:07:41.970448Z","steps":["trace[377575150] 'process raft request' (duration: 390.757396ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:41.97163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:41.579556Z","time spent":"391.955799ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:07:41.998444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.056903ms","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-08-20T02:07:41.998494Z","caller":"traceutil/trace.go:171","msg":"trace[801653318] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25202; }","duration":"400.134455ms","start":"2026-08-20T02:07:41.598346Z","end":"2026-08-20T02:07:41.998481Z","steps":["trace[801653318] 'agreement among raft nodes before linearized reading' (duration: 399.996413ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:41.998529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:41.598339Z","time spent":"400.182776ms","remote":"127.0.0.1:46350","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-08-20T02:07:41.998469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.633834ms","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-08-20T02:07:41.998628Z","caller":"traceutil/trace.go:171","msg":"trace[370772922] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25202; }","duration":"401.831828ms","start":"2026-08-20T02:07:41.596778Z","end":"2026-08-20T02:07:41.99861Z","steps":["trace[370772922] 'agreement among raft nodes before linearized reading' (duration: 401.530682ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:41.998681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:41.596761Z","time spent":"401.911159ms","remote":"127.0.0.1:46350","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-08-20T02:07:41.998754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.338739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-20T02:07:41.998797Z","caller":"traceutil/trace.go:171","msg":"trace[458341663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25202; }","duration":"400.39148ms","start":"2026-08-20T02:07:41.598394Z","end":"2026-08-20T02:07:41.998786Z","steps":["trace[458341663] 'agreement among raft nodes before linearized reading' (duration: 400.261287ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:41.998836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:41.598389Z","time spent":"400.440911ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-20T02:07:41.999105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.382908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-20T02:07:41.999228Z","caller":"traceutil/trace.go:171","msg":"trace[778555508] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25202; }","duration":"401.634663ms","start":"2026-08-20T02:07:41.597568Z","end":"2026-08-20T02:07:41.999203Z","steps":["trace[778555508] 'agreement among raft nodes before linearized reading' (duration: 400.841258ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:41.999289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:41.597558Z","time spent":"401.719234ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-20T02:07:42.319916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.042538ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T02:07:42.320111Z","caller":"traceutil/trace.go:171","msg":"trace[2145878216] transaction","detail":"{read_only:false; response_revision:25203; number_of_response:1; }","duration":"343.510182ms","start":"2026-08-20T02:07:41.97656Z","end":"2026-08-20T02:07:42.32007Z","steps":["trace[2145878216] 'process raft request' (duration: 65.163617ms)","trace[2145878216] 'compare' (duration: 277.866035ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:07:42.320194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:41.976529Z","time spent":"343.623194ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:07:42.320882Z","caller":"traceutil/trace.go:171","msg":"trace[761036150] transaction","detail":"{read_only:false; response_revision:25204; number_of_response:1; }","duration":"317.583455ms","start":"2026-08-20T02:07:42.003276Z","end":"2026-08-20T02:07:42.32086Z","steps":["trace[761036150] 'process raft request' (duration: 317.345891ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:42.320977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:42.00326Z","time spent":"317.674938ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:07:42.320945Z","caller":"traceutil/trace.go:171","msg":"trace[1878334486] transaction","detail":"{read_only:false; response_revision:25205; number_of_response:1; }","duration":"317.020074ms","start":"2026-08-20T02:07:42.003892Z","end":"2026-08-20T02:07:42.320912Z","steps":["trace[1878334486] 'process raft request' (duration: 316.888892ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:42.320958Z","caller":"traceutil/trace.go:171","msg":"trace[118405061] linearizableReadLoop","detail":"{readStateIndex:26037; appliedIndex:26033; }","duration":"170.769867ms","start":"2026-08-20T02:07:42.150159Z","end":"2026-08-20T02:07:42.320929Z","steps":["trace[118405061] 'read index received' (duration: 170.38283ms)","trace[118405061] 'applied index is now lower than readState.Index' (duration: 385.847µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:07:42.321115Z","caller":"traceutil/trace.go:171","msg":"trace[2049407685] transaction","detail":"{read_only:false; response_revision:25206; number_of_response:1; }","duration":"317.122126ms","start":"2026-08-20T02:07:42.003982Z","end":"2026-08-20T02:07:42.321104Z","steps":["trace[2049407685] 'process raft request' (duration: 316.872071ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:42.321162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:42.003868Z","time spent":"317.151896ms","remote":"127.0.0.1:46350","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-08-20T02:07:42.321241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:42.003943Z","time spent":"317.210158ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:07:42.321356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.562093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:07:42.321384Z","caller":"traceutil/trace.go:171","msg":"trace[2134242249] transaction","detail":"{read_only:false; response_revision:25207; number_of_response:1; }","duration":"317.101126ms","start":"2026-08-20T02:07:42.004271Z","end":"2026-08-20T02:07:42.321372Z","steps":["trace[2134242249] 'process raft request' (duration: 316.619037ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:42.321401Z","caller":"traceutil/trace.go:171","msg":"trace[893124115] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:25207; }","duration":"157.641005ms","start":"2026-08-20T02:07:42.16374Z","end":"2026-08-20T02:07:42.321382Z","steps":["trace[893124115] 'agreement among raft nodes before linearized reading' (duration: 157.568164ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:07:42.321434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:07:42.00426Z","time spent":"317.145358ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:07:42.321465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.243856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-925924d7-c0e3-4ad7-a1e7-cbca3a6af4c1\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-08-20T02:07:42.32156Z","caller":"traceutil/trace.go:171","msg":"trace[1664031529] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-925924d7-c0e3-4ad7-a1e7-cbca3a6af4c1; range_end:; response_count:1; response_revision:25207; }","duration":"171.43815ms","start":"2026-08-20T02:07:42.150092Z","end":"2026-08-20T02:07:42.32153Z","steps":["trace[1664031529] 'agreement among raft nodes before linearized reading' (duration: 171.127273ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:44.805317Z","caller":"traceutil/trace.go:171","msg":"trace[1581711130] transaction","detail":"{read_only:false; response_revision:25228; number_of_response:1; }","duration":"154.477006ms","start":"2026-08-20T02:07:44.6508Z","end":"2026-08-20T02:07:44.805277Z","steps":["trace[1581711130] 'process raft request' (duration: 57.867938ms)","trace[1581711130] 'compare' (duration: 96.129168ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:07:46.122055Z","caller":"traceutil/trace.go:171","msg":"trace[1670367181] transaction","detail":"{read_only:false; response_revision:25239; number_of_response:1; }","duration":"218.939848ms","start":"2026-08-20T02:07:45.903082Z","end":"2026-08-20T02:07:46.122022Z","steps":["trace[1670367181] 'process raft request' (duration: 218.732944ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:46.132631Z","caller":"traceutil/trace.go:171","msg":"trace[515433311] transaction","detail":"{read_only:false; response_revision:25240; number_of_response:1; }","duration":"147.860029ms","start":"2026-08-20T02:07:45.984756Z","end":"2026-08-20T02:07:46.132616Z","steps":["trace[515433311] 'process raft request' (duration: 147.731786ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:46.1328Z","caller":"traceutil/trace.go:171","msg":"trace[200517375] transaction","detail":"{read_only:false; response_revision:25241; number_of_response:1; }","duration":"145.881211ms","start":"2026-08-20T02:07:45.98691Z","end":"2026-08-20T02:07:46.132791Z","steps":["trace[200517375] 'process raft request' (duration: 145.667717ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:46.133055Z","caller":"traceutil/trace.go:171","msg":"trace[32972868] transaction","detail":"{read_only:false; response_revision:25242; number_of_response:1; }","duration":"145.987442ms","start":"2026-08-20T02:07:45.987058Z","end":"2026-08-20T02:07:46.133046Z","steps":["trace[32972868] 'process raft request' (duration: 145.695627ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:46.133159Z","caller":"traceutil/trace.go:171","msg":"trace[1228546384] transaction","detail":"{read_only:false; response_revision:25243; number_of_response:1; }","duration":"121.764929ms","start":"2026-08-20T02:07:46.011386Z","end":"2026-08-20T02:07:46.133151Z","steps":["trace[1228546384] 'process raft request' (duration: 121.598606ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:07:46.677717Z","caller":"traceutil/trace.go:171","msg":"trace[1145566774] transaction","detail":"{read_only:false; response_revision:25252; number_of_response:1; }","duration":"105.029659ms","start":"2026-08-20T02:07:46.57262Z","end":"2026-08-20T02:07:46.677649Z","steps":["trace[1145566774] 'process raft request' (duration: 40.848211ms)","trace[1145566774] 'compare' (duration: 64.002165ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:07:51.094216Z","caller":"traceutil/trace.go:171","msg":"trace[1291117828] transaction","detail":"{read_only:false; response_revision:25298; number_of_response:1; }","duration":"191.555774ms","start":"2026-08-20T02:07:50.902619Z","end":"2026-08-20T02:07:51.094175Z","steps":["trace[1291117828] 'process raft request' (duration: 191.244898ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:08:12.371959Z","caller":"traceutil/trace.go:171","msg":"trace[280942887] transaction","detail":"{read_only:false; response_revision:25463; number_of_response:1; }","duration":"129.574398ms","start":"2026-08-20T02:08:12.242354Z","end":"2026-08-20T02:08:12.371928Z","steps":["trace[280942887] 'process raft request' (duration: 129.310013ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:08:12.447752Z","caller":"traceutil/trace.go:171","msg":"trace[1101208555] transaction","detail":"{read_only:false; response_revision:25464; number_of_response:1; }","duration":"202.970423ms","start":"2026-08-20T02:08:12.244748Z","end":"2026-08-20T02:08:12.447718Z","steps":["trace[1101208555] 'process raft request' (duration: 194.421829ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:08:12.447773Z","caller":"traceutil/trace.go:171","msg":"trace[1086662674] transaction","detail":"{read_only:false; response_revision:25465; number_of_response:1; }","duration":"199.18819ms","start":"2026-08-20T02:08:12.248552Z","end":"2026-08-20T02:08:12.44774Z","steps":["trace[1086662674] 'process raft request' (duration: 199.049088ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:08:12.447864Z","caller":"traceutil/trace.go:171","msg":"trace[135814294] transaction","detail":"{read_only:false; response_revision:25466; number_of_response:1; }","duration":"198.539568ms","start":"2026-08-20T02:08:12.24929Z","end":"2026-08-20T02:08:12.44783Z","steps":["trace[135814294] 'process raft request' (duration: 198.394345ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:08:12.633637Z","caller":"traceutil/trace.go:171","msg":"trace[552868217] linearizableReadLoop","detail":"{readStateIndex:26305; appliedIndex:26304; }","duration":"151.861574ms","start":"2026-08-20T02:08:12.481744Z","end":"2026-08-20T02:08:12.633606Z","steps":["trace[552868217] 'read index received' (duration: 140.67289ms)","trace[552868217] 'applied index is now lower than readState.Index' (duration: 11.187524ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:08:12.634021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.226011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-20T02:08:12.634048Z","caller":"traceutil/trace.go:171","msg":"trace[758641453] transaction","detail":"{read_only:false; response_revision:25467; number_of_response:1; }","duration":"154.780721ms","start":"2026-08-20T02:08:12.479228Z","end":"2026-08-20T02:08:12.634009Z","steps":["trace[758641453] 'process raft request' (duration: 143.283831ms)","trace[758641453] 'compare' (duration: 10.96335ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:08:12.634116Z","caller":"traceutil/trace.go:171","msg":"trace[1595382003] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25467; }","duration":"152.379844ms","start":"2026-08-20T02:08:12.481721Z","end":"2026-08-20T02:08:12.634101Z","steps":["trace[1595382003] 'agreement among raft nodes before linearized reading' (duration: 152.044808ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:08:12.634145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.284137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-20T02:08:12.634039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.19949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-20T02:08:12.634233Z","caller":"traceutil/trace.go:171","msg":"trace[1607217114] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25467; }","duration":"147.405159ms","start":"2026-08-20T02:08:12.486807Z","end":"2026-08-20T02:08:12.634213Z","steps":["trace[1607217114] 'agreement among raft nodes before linearized reading' (duration: 147.110884ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:08:12.634224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.856307ms","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-08-20T02:08:12.634264Z","caller":"traceutil/trace.go:171","msg":"trace[143991477] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25467; }","duration":"152.459926ms","start":"2026-08-20T02:08:12.481783Z","end":"2026-08-20T02:08:12.634243Z","steps":["trace[143991477] 'agreement among raft nodes before linearized reading' (duration: 152.022148ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:08:12.634302Z","caller":"traceutil/trace.go:171","msg":"trace[87383123] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25467; }","duration":"149.99797ms","start":"2026-08-20T02:08:12.484287Z","end":"2026-08-20T02:08:12.634285Z","steps":["trace[87383123] 'agreement among raft nodes before linearized reading' (duration: 149.703004ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:08:12.903057Z","caller":"traceutil/trace.go:171","msg":"trace[1474435654] transaction","detail":"{read_only:false; response_revision:25468; number_of_response:1; }","duration":"264.439727ms","start":"2026-08-20T02:08:12.638568Z","end":"2026-08-20T02:08:12.903007Z","steps":["trace[1474435654] 'process raft request' (duration: 203.686226ms)","trace[1474435654] 'compare' (duration: 60.505887ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:08:12.905592Z","caller":"traceutil/trace.go:171","msg":"trace[646135792] transaction","detail":"{read_only:false; response_revision:25470; number_of_response:1; }","duration":"265.041699ms","start":"2026-08-20T02:08:12.64052Z","end":"2026-08-20T02:08:12.905562Z","steps":["trace[646135792] 'process raft request' (duration: 264.941628ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:08:12.905603Z","caller":"traceutil/trace.go:171","msg":"trace[1618941090] transaction","detail":"{read_only:false; response_revision:25469; number_of_response:1; }","duration":"266.220262ms","start":"2026-08-20T02:08:12.639342Z","end":"2026-08-20T02:08:12.905562Z","steps":["trace[1618941090] 'process raft request' (duration: 265.977328ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:08:12.905895Z","caller":"traceutil/trace.go:171","msg":"trace[386718067] transaction","detail":"{read_only:false; response_revision:25471; number_of_response:1; }","duration":"263.905258ms","start":"2026-08-20T02:08:12.641962Z","end":"2026-08-20T02:08:12.905867Z","steps":["trace[386718067] 'process raft request' (duration: 263.559782ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:08:12.905975Z","caller":"traceutil/trace.go:171","msg":"trace[1006174588] linearizableReadLoop","detail":"{readStateIndex:26309; appliedIndex:26305; }","duration":"223.548256ms","start":"2026-08-20T02:08:12.682397Z","end":"2026-08-20T02:08:12.905945Z","steps":["trace[1006174588] 'read index received' (duration: 159.98895ms)","trace[1006174588] 'applied index is now lower than readState.Index' (duration: 63.557946ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:08:12.906037Z","caller":"traceutil/trace.go:171","msg":"trace[346554799] transaction","detail":"{read_only:false; response_revision:25472; number_of_response:1; }","duration":"101.133175ms","start":"2026-08-20T02:08:12.804857Z","end":"2026-08-20T02:08:12.90599Z","steps":["trace[346554799] 'process raft request' (duration: 100.88779ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:08:12.906203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.784392ms","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-08-20T02:08:12.906243Z","caller":"traceutil/trace.go:171","msg":"trace[1177942818] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25472; }","duration":"223.873033ms","start":"2026-08-20T02:08:12.682357Z","end":"2026-08-20T02:08:12.90623Z","steps":["trace[1177942818] 'agreement among raft nodes before linearized reading' (duration: 223.70752ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:08:13.080736Z","caller":"traceutil/trace.go:171","msg":"trace[173973853] transaction","detail":"{read_only:false; response_revision:25473; number_of_response:1; }","duration":"167.961153ms","start":"2026-08-20T02:08:12.912735Z","end":"2026-08-20T02:08:13.080696Z","steps":["trace[173973853] 'process raft request' (duration: 155.27243ms)","trace[173973853] 'compare' (duration: 12.330216ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:08:13.299251Z","caller":"traceutil/trace.go:171","msg":"trace[1525224054] transaction","detail":"{read_only:false; response_revision:25474; number_of_response:1; }","duration":"294.81917ms","start":"2026-08-20T02:08:13.004379Z","end":"2026-08-20T02:08:13.299198Z","steps":["trace[1525224054] 'process raft request' (duration: 243.397067ms)","trace[1525224054] 'compare' (duration: 51.171938ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:08:13.299403Z","caller":"traceutil/trace.go:171","msg":"trace[1736089105] transaction","detail":"{read_only:false; response_revision:25475; number_of_response:1; }","duration":"123.806769ms","start":"2026-08-20T02:08:13.175569Z","end":"2026-08-20T02:08:13.299376Z","steps":["trace[1736089105] 'process raft request' (duration: 123.739917ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:08:13.72118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.317093ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-20T02:08:14.191528Z","caller":"traceutil/trace.go:171","msg":"trace[496340476] transaction","detail":"{read_only:false; response_revision:25477; number_of_response:1; }","duration":"102.232335ms","start":"2026-08-20T02:08:14.089256Z","end":"2026-08-20T02:08:14.191489Z","steps":["trace[496340476] 'process raft request' (duration: 37.469616ms)","trace[496340476] 'compare' (duration: 64.470143ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:08:14.594338Z","caller":"traceutil/trace.go:171","msg":"trace[273164982] transaction","detail":"{read_only:false; response_revision:25480; number_of_response:1; }","duration":"140.964586ms","start":"2026-08-20T02:08:14.453346Z","end":"2026-08-20T02:08:14.59431Z","steps":["trace[273164982] 'process raft request' (duration: 140.889675ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:08:14.594439Z","caller":"traceutil/trace.go:171","msg":"trace[1053533778] transaction","detail":"{read_only:false; response_revision:25479; number_of_response:1; }","duration":"141.023847ms","start":"2026-08-20T02:08:14.453346Z","end":"2026-08-20T02:08:14.59437Z","steps":["trace[1053533778] 'process raft request' (duration: 140.710771ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:08:14.636629Z","caller":"traceutil/trace.go:171","msg":"trace[252426627] linearizableReadLoop","detail":"{readStateIndex:26320; appliedIndex:26317; }","duration":"134.428922ms","start":"2026-08-20T02:08:14.502173Z","end":"2026-08-20T02:08:14.636602Z","steps":["trace[252426627] 'read index received' (duration: 92.01129ms)","trace[252426627] 'applied index is now lower than readState.Index' (duration: 42.416452ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:08:14.63673Z","caller":"traceutil/trace.go:171","msg":"trace[1328253172] transaction","detail":"{read_only:false; response_revision:25481; number_of_response:1; }","duration":"183.182125ms","start":"2026-08-20T02:08:14.453476Z","end":"2026-08-20T02:08:14.636658Z","steps":["trace[1328253172] 'process raft request' (duration: 182.98291ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:08:14.637124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.87077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-20T02:08:14.637203Z","caller":"traceutil/trace.go:171","msg":"trace[560581904] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:25481; }","duration":"135.074383ms","start":"2026-08-20T02:08:14.502109Z","end":"2026-08-20T02:08:14.637183Z","steps":["trace[560581904] 'agreement among raft nodes before linearized reading' (duration: 134.640185ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:08:14.912979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.856704ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T02:08:14.913302Z","caller":"traceutil/trace.go:171","msg":"trace[1362829458] linearizableReadLoop","detail":"{readStateIndex:26322; appliedIndex:26320; }","duration":"200.110128ms","start":"2026-08-20T02:08:14.713165Z","end":"2026-08-20T02:08:14.913275Z","steps":["trace[1362829458] 'read index received' (duration: 844.086µs)","trace[1362829458] 'applied index is now lower than readState.Index' (duration: 199.264272ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:08:14.913461Z","caller":"traceutil/trace.go:171","msg":"trace[1274548678] transaction","detail":"{read_only:false; response_revision:25482; number_of_response:1; }","duration":"267.736102ms","start":"2026-08-20T02:08:14.645706Z","end":"2026-08-20T02:08:14.913442Z","steps":["trace[1274548678] 'process raft request' (duration: 68.292537ms)","trace[1274548678] 'compare' (duration: 198.62227ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:08:14.913599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.414904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:08:14.913642Z","caller":"traceutil/trace.go:171","msg":"trace[1930415740] transaction","detail":"{read_only:false; response_revision:25483; number_of_response:1; }","duration":"252.021431ms","start":"2026-08-20T02:08:14.661583Z","end":"2026-08-20T02:08:14.913605Z","steps":["trace[1930415740] 'process raft request' (duration: 251.545982ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:08:14.913679Z","caller":"traceutil/trace.go:171","msg":"trace[2146863282] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:25483; }","duration":"200.550827ms","start":"2026-08-20T02:08:14.713108Z","end":"2026-08-20T02:08:14.913659Z","steps":["trace[2146863282] 'agreement among raft nodes before linearized reading' (duration: 200.355883ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:08:15.150934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.495312ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T02:08:15.151205Z","caller":"traceutil/trace.go:171","msg":"trace[1231779689] transaction","detail":"{read_only:false; response_revision:25485; number_of_response:1; }","duration":"233.304413ms","start":"2026-08-20T02:08:14.91788Z","end":"2026-08-20T02:08:15.151184Z","steps":["trace[1231779689] 'process raft request' (duration: 233.171911ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:08:15.151193Z","caller":"traceutil/trace.go:171","msg":"trace[564004264] transaction","detail":"{read_only:false; response_revision:25484; number_of_response:1; }","duration":"233.281473ms","start":"2026-08-20T02:08:14.917877Z","end":"2026-08-20T02:08:15.151158Z","steps":["trace[564004264] 'process raft request' (duration: 120.454535ms)","trace[564004264] 'compare' (duration: 112.352409ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:08:15.151432Z","caller":"traceutil/trace.go:171","msg":"trace[1140712651] transaction","detail":"{read_only:false; response_revision:25486; number_of_response:1; }","duration":"233.412714ms","start":"2026-08-20T02:08:14.917993Z","end":"2026-08-20T02:08:15.151405Z","steps":["trace[1140712651] 'process raft request' (duration: 233.142909ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:08:15.151578Z","caller":"traceutil/trace.go:171","msg":"trace[1472458392] transaction","detail":"{read_only:false; response_revision:25487; number_of_response:1; }","duration":"233.351313ms","start":"2026-08-20T02:08:14.918213Z","end":"2026-08-20T02:08:15.151564Z","steps":["trace[1472458392] 'process raft request' (duration: 233.109479ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:08:15.151542Z","caller":"traceutil/trace.go:171","msg":"trace[1625424595] linearizableReadLoop","detail":"{readStateIndex:26326; appliedIndex:26322; }","duration":"177.403363ms","start":"2026-08-20T02:08:14.974102Z","end":"2026-08-20T02:08:15.151505Z","steps":["trace[1625424595] 'read index received' (duration: 64.139186ms)","trace[1625424595] 'applied index is now lower than readState.Index' (duration: 113.262937ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:08:15.151691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.557886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:08:15.151746Z","caller":"traceutil/trace.go:171","msg":"trace[1296818350] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:25487; }","duration":"177.7196ms","start":"2026-08-20T02:08:14.974014Z","end":"2026-08-20T02:08:15.151733Z","steps":["trace[1296818350] 'agreement among raft nodes before linearized reading' (duration: 177.592967ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:08:15.406956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.167097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:08:15.407073Z","caller":"traceutil/trace.go:171","msg":"trace[1736004463] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:25488; }","duration":"135.35001ms","start":"2026-08-20T02:08:15.271686Z","end":"2026-08-20T02:08:15.407036Z","steps":["trace[1736004463] 'count revisions from in-memory index tree' (duration: 134.973582ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:08:15.407136Z","caller":"traceutil/trace.go:171","msg":"trace[43257129] transaction","detail":"{read_only:false; response_revision:25490; number_of_response:1; }","duration":"100.232147ms","start":"2026-08-20T02:08:15.306874Z","end":"2026-08-20T02:08:15.407106Z","steps":["trace[43257129] 'process raft request' (duration: 100.124785ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:08:15.407165Z","caller":"traceutil/trace.go:171","msg":"trace[1061492151] transaction","detail":"{read_only:false; response_revision:25489; number_of_response:1; }","duration":"102.571962ms","start":"2026-08-20T02:08:15.304543Z","end":"2026-08-20T02:08:15.407115Z","steps":["trace[1061492151] 'process raft request' (duration: 16.384152ms)","trace[1061492151] 'compare' (duration: 85.817293ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:08:26.839801Z","caller":"traceutil/trace.go:171","msg":"trace[573589136] transaction","detail":"{read_only:false; response_revision:25577; number_of_response:1; }","duration":"200.467724ms","start":"2026-08-20T02:08:26.639314Z","end":"2026-08-20T02:08:26.839782Z","steps":["trace[573589136] 'process raft request' (duration: 171.694755ms)","trace[573589136] 'compare' (duration: 28.561356ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:08:27.011141Z","caller":"traceutil/trace.go:171","msg":"trace[1975058875] transaction","detail":"{read_only:false; response_revision:25578; number_of_response:1; }","duration":"371.010817ms","start":"2026-08-20T02:08:26.640112Z","end":"2026-08-20T02:08:27.011123Z","steps":["trace[1975058875] 'process raft request' (duration: 357.999417ms)","trace[1975058875] 'compare' (duration: 12.846357ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:08:27.011253Z","caller":"traceutil/trace.go:171","msg":"trace[1215532080] transaction","detail":"{read_only:false; response_revision:25579; number_of_response:1; }","duration":"319.790348ms","start":"2026-08-20T02:08:26.691444Z","end":"2026-08-20T02:08:27.011235Z","steps":["trace[1215532080] 'process raft request' (duration: 319.647006ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:08:27.011301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:08:26.640084Z","time spent":"371.1169ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:08:27.011364Z","caller":"traceutil/trace.go:171","msg":"trace[1401561688] linearizableReadLoop","detail":"{readStateIndex:26419; appliedIndex:26417; }","duration":"324.536119ms","start":"2026-08-20T02:08:26.686682Z","end":"2026-08-20T02:08:27.011218Z","steps":["trace[1401561688] 'read index received' (duration: 124.599384ms)","trace[1401561688] 'applied index is now lower than readState.Index' (duration: 199.935345ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:08:27.01149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.758283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:08:27.011534Z","caller":"traceutil/trace.go:171","msg":"trace[1709250477] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:25579; }","duration":"324.892786ms","start":"2026-08-20T02:08:26.686631Z","end":"2026-08-20T02:08:27.011524Z","steps":["trace[1709250477] 'agreement among raft nodes before linearized reading' (duration: 324.757073ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:08:27.011564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.741719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-20T02:08:27.01157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:08:26.686588Z","time spent":"324.974817ms","remote":"127.0.0.1:37834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true "} {"level":"info","ts":"2026-08-20T02:08:27.011642Z","caller":"traceutil/trace.go:171","msg":"trace[1452301773] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:25579; }","duration":"213.793761ms","start":"2026-08-20T02:08:26.797791Z","end":"2026-08-20T02:08:27.011585Z","steps":["trace[1452301773] 'agreement among raft nodes before linearized reading' (duration: 213.708739ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:08:27.011805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:08:26.691424Z","time spent":"319.901291ms","remote":"127.0.0.1:46350","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-08-20T02:08:27.345293Z","caller":"traceutil/trace.go:171","msg":"trace[1221440469] transaction","detail":"{read_only:false; response_revision:25582; number_of_response:1; }","duration":"133.142378ms","start":"2026-08-20T02:08:27.212114Z","end":"2026-08-20T02:08:27.345257Z","steps":["trace[1221440469] 'process raft request' (duration: 132.822972ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:08:27.345322Z","caller":"traceutil/trace.go:171","msg":"trace[125925103] transaction","detail":"{read_only:false; response_revision:25583; number_of_response:1; }","duration":"130.911844ms","start":"2026-08-20T02:08:27.214366Z","end":"2026-08-20T02:08:27.345278Z","steps":["trace[125925103] 'process raft request' (duration: 130.746201ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:08:31.786065Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000675d.wal"} {"level":"info","ts":"2026-08-20T02:09:05.44959Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22988} {"level":"info","ts":"2026-08-20T02:09:05.582157Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22988,"took":"127.609971ms","hash":3088378081,"current-db-size-bytes":60276736,"current-db-size":"60 MB","current-db-size-in-use-bytes":37330944,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-08-20T02:09:05.582275Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3088378081,"revision":22988,"compact-revision":20027} {"level":"info","ts":"2026-08-20T02:10:17.966332Z","caller":"traceutil/trace.go:171","msg":"trace[1704038747] transaction","detail":"{read_only:false; response_revision:26703; number_of_response:1; }","duration":"121.749189ms","start":"2026-08-20T02:10:17.844554Z","end":"2026-08-20T02:10:17.966304Z","steps":["trace[1704038747] 'process raft request' (duration: 115.315775ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:10:17.974102Z","caller":"traceutil/trace.go:171","msg":"trace[855274745] linearizableReadLoop","detail":"{readStateIndex:27571; appliedIndex:27569; }","duration":"105.5866ms","start":"2026-08-20T02:10:17.868473Z","end":"2026-08-20T02:10:17.974059Z","steps":["trace[855274745] 'read index received' (duration: 91.415549ms)","trace[855274745] 'applied index is now lower than readState.Index' (duration: 14.169981ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:10:17.974377Z","caller":"traceutil/trace.go:171","msg":"trace[40664198] transaction","detail":"{read_only:false; response_revision:26704; number_of_response:1; }","duration":"117.180942ms","start":"2026-08-20T02:10:17.857186Z","end":"2026-08-20T02:10:17.974367Z","steps":["trace[40664198] 'process raft request' (duration: 116.816094ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:10:17.974657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.161581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-20T02:10:17.974733Z","caller":"traceutil/trace.go:171","msg":"trace[1514064147] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26704; }","duration":"106.279453ms","start":"2026-08-20T02:10:17.86844Z","end":"2026-08-20T02:10:17.97472Z","steps":["trace[1514064147] 'agreement among raft nodes before linearized reading' (duration: 106.11263ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:10:17.974971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.334179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-20T02:10:17.97518Z","caller":"traceutil/trace.go:171","msg":"trace[373303986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26704; }","duration":"100.565434ms","start":"2026-08-20T02:10:17.874602Z","end":"2026-08-20T02:10:17.975168Z","steps":["trace[373303986] 'agreement among raft nodes before linearized reading' (duration: 100.305139ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:12:34.222577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.171874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-20T02:12:34.222656Z","caller":"traceutil/trace.go:171","msg":"trace[462995449] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:28050; }","duration":"206.273137ms","start":"2026-08-20T02:12:34.016367Z","end":"2026-08-20T02:12:34.22264Z","steps":["trace[462995449] 'count revisions from in-memory index tree' (duration: 206.074302ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:12:34.223162Z","caller":"traceutil/trace.go:171","msg":"trace[1521857887] transaction","detail":"{read_only:false; response_revision:28051; number_of_response:1; }","duration":"189.665749ms","start":"2026-08-20T02:12:34.033477Z","end":"2026-08-20T02:12:34.223142Z","steps":["trace[1521857887] 'process raft request' (duration: 189.482216ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:12:34.223152Z","caller":"traceutil/trace.go:171","msg":"trace[1560628263] linearizableReadLoop","detail":"{readStateIndex:28954; appliedIndex:28953; }","duration":"189.591518ms","start":"2026-08-20T02:12:34.033537Z","end":"2026-08-20T02:12:34.223129Z","steps":["trace[1560628263] 'read index received' (duration: 189.209371ms)","trace[1560628263] 'applied index is now lower than readState.Index' (duration: 381.047µs)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:12:34.223311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.772271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:12:34.223339Z","caller":"traceutil/trace.go:171","msg":"trace[1365662928] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:28051; }","duration":"189.849843ms","start":"2026-08-20T02:12:34.033482Z","end":"2026-08-20T02:12:34.223332Z","steps":["trace[1365662928] 'agreement among raft nodes before linearized reading' (duration: 189.743951ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:12:34.224354Z","caller":"traceutil/trace.go:171","msg":"trace[649445415] transaction","detail":"{read_only:false; response_revision:28052; number_of_response:1; }","duration":"189.313703ms","start":"2026-08-20T02:12:34.034993Z","end":"2026-08-20T02:12:34.224307Z","steps":["trace[649445415] 'process raft request' (duration: 189.18377ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:12:34.224404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.57979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-20T02:12:34.22445Z","caller":"traceutil/trace.go:171","msg":"trace[823061714] range","detail":"{range_begin:/registry/clusterctl.cluster.x-k8s.io/providers/; range_end:/registry/clusterctl.cluster.x-k8s.io/providers0; response_count:0; response_revision:28052; }","duration":"140.704532ms","start":"2026-08-20T02:12:34.083735Z","end":"2026-08-20T02:12:34.224439Z","steps":["trace[823061714] 'agreement among raft nodes before linearized reading' (duration: 140.58316ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:12:34.224579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.839607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-08-20T02:12:34.224614Z","caller":"traceutil/trace.go:171","msg":"trace[1845368205] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:28052; }","duration":"148.914688ms","start":"2026-08-20T02:12:34.075691Z","end":"2026-08-20T02:12:34.224605Z","steps":["trace[1845368205] 'agreement among raft nodes before linearized reading' (duration: 148.597032ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:13:24.705768Z","caller":"traceutil/trace.go:171","msg":"trace[1590922257] transaction","detail":"{read_only:false; response_revision:28428; number_of_response:1; }","duration":"313.585557ms","start":"2026-08-20T02:13:24.392152Z","end":"2026-08-20T02:13:24.705737Z","steps":["trace[1590922257] 'process raft request' (duration: 313.427044ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:13:24.705994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:13:24.392127Z","time spent":"313.770241ms","remote":"127.0.0.1:46350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:13:24.706865Z","caller":"traceutil/trace.go:171","msg":"trace[899796565] linearizableReadLoop","detail":"{readStateIndex:29342; appliedIndex:29341; }","duration":"291.479976ms","start":"2026-08-20T02:13:24.415365Z","end":"2026-08-20T02:13:24.706845Z","steps":["trace[899796565] 'read index received' (duration: 290.777104ms)","trace[899796565] 'applied index is now lower than readState.Index' (duration: 701.862µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:13:24.706968Z","caller":"traceutil/trace.go:171","msg":"trace[1266884192] transaction","detail":"{read_only:false; response_revision:28429; number_of_response:1; }","duration":"311.380164ms","start":"2026-08-20T02:13:24.395555Z","end":"2026-08-20T02:13:24.706935Z","steps":["trace[1266884192] 'process raft request' (duration: 311.16203ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:13:24.707017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.658049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:13:24.707127Z","caller":"traceutil/trace.go:171","msg":"trace[1847833193] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28429; }","duration":"291.822172ms","start":"2026-08-20T02:13:24.415292Z","end":"2026-08-20T02:13:24.707114Z","steps":["trace[1847833193] 'agreement among raft nodes before linearized reading' (duration: 291.67909ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:13:24.707141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:13:24.395528Z","time spent":"311.523737ms","remote":"127.0.0.1:46350","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-08-20T02:13:25.067965Z","caller":"traceutil/trace.go:171","msg":"trace[1444412865] transaction","detail":"{read_only:false; response_revision:28430; number_of_response:1; }","duration":"270.967225ms","start":"2026-08-20T02:13:24.796957Z","end":"2026-08-20T02:13:25.067924Z","steps":["trace[1444412865] 'process raft request' (duration: 270.72813ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:13:25.069444Z","caller":"traceutil/trace.go:171","msg":"trace[1241229602] linearizableReadLoop","detail":"{readStateIndex:29344; appliedIndex:29342; }","duration":"210.385153ms","start":"2026-08-20T02:13:24.859029Z","end":"2026-08-20T02:13:25.069414Z","steps":["trace[1241229602] 'read index received' (duration: 208.868314ms)","trace[1241229602] 'applied index is now lower than readState.Index' (duration: 1.515379ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:13:25.06955Z","caller":"traceutil/trace.go:171","msg":"trace[794725029] transaction","detail":"{read_only:false; response_revision:28431; number_of_response:1; }","duration":"271.098518ms","start":"2026-08-20T02:13:24.798429Z","end":"2026-08-20T02:13:25.069527Z","steps":["trace[794725029] 'process raft request' (duration: 270.872294ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:13:25.069692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.658327ms","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-08-20T02:13:25.069777Z","caller":"traceutil/trace.go:171","msg":"trace[456072937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28431; }","duration":"210.76394ms","start":"2026-08-20T02:13:24.858961Z","end":"2026-08-20T02:13:25.069725Z","steps":["trace[456072937] 'agreement among raft nodes before linearized reading' (duration: 210.594346ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:14:05.455602Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26033} {"level":"info","ts":"2026-08-20T02:14:05.605342Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26033,"took":"145.624721ms","hash":2728778127,"current-db-size-bytes":60276736,"current-db-size":"60 MB","current-db-size-in-use-bytes":34930688,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-20T02:14:05.605412Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2728778127,"revision":26033,"compact-revision":22988} {"level":"info","ts":"2026-08-20T02:14:51.496764Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"22478416d949d47d","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-20T02:14:51.50445Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-20T02:14:51.504604Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-20T02:19:05.461773Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28734} {"level":"info","ts":"2026-08-20T02:19:05.596033Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28734,"took":"130.593624ms","hash":1364698568,"current-db-size-bytes":60276736,"current-db-size":"60 MB","current-db-size-in-use-bytes":30412800,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-20T02:19:05.596115Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1364698568,"revision":28734,"compact-revision":26033} {"level":"info","ts":"2026-08-20T02:24:05.467296Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30952} {"level":"info","ts":"2026-08-20T02:24:05.581369Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30952,"took":"110.056735ms","hash":1508149043,"current-db-size-bytes":60276736,"current-db-size":"60 MB","current-db-size-in-use-bytes":31260672,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-20T02:24:05.581429Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1508149043,"revision":30952,"compact-revision":28734} {"level":"info","ts":"2026-08-20T02:29:05.47518Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33215} {"level":"info","ts":"2026-08-20T02:29:05.619826Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33215,"took":"140.251663ms","hash":3156913098,"current-db-size-bytes":60276736,"current-db-size":"60 MB","current-db-size-in-use-bytes":31469568,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-20T02:29:05.619878Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3156913098,"revision":33215,"compact-revision":30952} {"level":"info","ts":"2026-08-20T02:34:05.481568Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35508} {"level":"info","ts":"2026-08-20T02:34:05.629567Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35508,"took":"143.271552ms","hash":1770415240,"current-db-size-bytes":60276736,"current-db-size":"60 MB","current-db-size-in-use-bytes":31731712,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-20T02:34:05.629647Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1770415240,"revision":35508,"compact-revision":33215} {"level":"info","ts":"2026-08-20T02:36:18.033738Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"22478416d949d47d","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-20T02:36:18.039825Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-08-20T02:36:18.040017Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-08-20T02:39:05.48885Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37738} {"level":"info","ts":"2026-08-20T02:39:05.604005Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37738,"took":"111.617527ms","hash":4005529576,"current-db-size-bytes":61120512,"current-db-size":"61 MB","current-db-size-in-use-bytes":33693696,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-20T02:39:05.604133Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4005529576,"revision":37738,"compact-revision":35508}