{"level":"warn","ts":"2026-10-02T09:10:29.725259Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-02T09:10:29.725399Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.182:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.182:2380","--initial-cluster=instance=https://199.204.45.182:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.182:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.182: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-10-02T09:10:29.725536Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-02T09:10:29.725554Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.182:2380"]} {"level":"info","ts":"2026-10-02T09:10:29.72558Z","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-10-02T09:10:29.726531Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.182:2379"]} {"level":"info","ts":"2026-10-02T09:10:29.726637Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.182:2380"],"listen-peer-urls":["https://199.204.45.182:2380"],"advertise-client-urls":["https://199.204.45.182:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.182:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.182: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-10-02T09:10:29.731346Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.414995ms"} {"level":"info","ts":"2026-10-02T09:10:29.737925Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e44ae1545340c14b","cluster-id":"7284b69afd367451"} {"level":"info","ts":"2026-10-02T09:10:29.738002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b switched to configuration voters=()"} {"level":"info","ts":"2026-10-02T09:10:29.738024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b became follower at term 0"} {"level":"info","ts":"2026-10-02T09:10:29.738039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e44ae1545340c14b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-02T09:10:29.73805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b became follower at term 1"} {"level":"info","ts":"2026-10-02T09:10:29.738091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b switched to configuration voters=(16450208341214413131)"} {"level":"warn","ts":"2026-10-02T09:10:29.744859Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-02T09:10:29.746222Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-02T09:10:29.74747Z","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-10-02T09:10:29.74953Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e44ae1545340c14b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-02T09:10:29.749814Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e44ae1545340c14b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-02T09:10:29.752764Z","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-10-02T09:10:29.753569Z","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-10-02T09:10:29.753616Z","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-10-02T09:10:29.758253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b switched to configuration voters=(16450208341214413131)"} {"level":"info","ts":"2026-10-02T09:10:29.758508Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7284b69afd367451","local-member-id":"e44ae1545340c14b","added-peer-id":"e44ae1545340c14b","added-peer-peer-urls":["https://199.204.45.182:2380"]} {"level":"info","ts":"2026-10-02T09:10:29.759355Z","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-10-02T09:10:29.75951Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.182:2380"} {"level":"info","ts":"2026-10-02T09:10:29.759551Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.182:2380"} {"level":"info","ts":"2026-10-02T09:10:29.759812Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e44ae1545340c14b","initial-advertise-peer-urls":["https://199.204.45.182:2380"],"listen-peer-urls":["https://199.204.45.182:2380"],"advertise-client-urls":["https://199.204.45.182:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.182:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-02T09:10:29.759845Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-02T09:10:30.238976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b is starting a new election at term 1"} {"level":"info","ts":"2026-10-02T09:10:30.239079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b became pre-candidate at term 1"} {"level":"info","ts":"2026-10-02T09:10:30.239129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b received MsgPreVoteResp from e44ae1545340c14b at term 1"} {"level":"info","ts":"2026-10-02T09:10:30.239151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b became candidate at term 2"} {"level":"info","ts":"2026-10-02T09:10:30.23916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b received MsgVoteResp from e44ae1545340c14b at term 2"} {"level":"info","ts":"2026-10-02T09:10:30.23917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b became leader at term 2"} {"level":"info","ts":"2026-10-02T09:10:30.239178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e44ae1545340c14b elected leader e44ae1545340c14b at term 2"} {"level":"info","ts":"2026-10-02T09:10:30.240143Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T09:10:30.241383Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7284b69afd367451","local-member-id":"e44ae1545340c14b","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T09:10:30.241437Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T09:10:30.241452Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T09:10:30.241475Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e44ae1545340c14b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.182:2379]}","request-path":"/0/members/e44ae1545340c14b/attributes","cluster-id":"7284b69afd367451","publish-timeout":"7s"} {"level":"info","ts":"2026-10-02T09:10:30.241664Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-02T09:10:30.242061Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-02T09:10:30.242129Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-02T09:10:30.2422Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-02T09:10:30.244127Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.182:2379"} {"level":"info","ts":"2026-10-02T09:10:30.245562Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-02T09:10:31.988085Z","caller":"traceutil/trace.go:171","msg":"trace[235970762] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"142.385769ms","start":"2026-10-02T09:10:31.845676Z","end":"2026-10-02T09:10:31.988062Z","steps":["trace[235970762] 'process raft request' (duration: 142.18161ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:10:32.426138Z","caller":"traceutil/trace.go:171","msg":"trace[77464104] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"580.011588ms","start":"2026-10-02T09:10:31.846099Z","end":"2026-10-02T09:10:32.42611Z","steps":["trace[77464104] 'process raft request' (duration: 509.601325ms)","trace[77464104] 'compare' (duration: 70.014815ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:10:32.426229Z","caller":"traceutil/trace.go:171","msg":"trace[2054896880] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"579.323667ms","start":"2026-10-02T09:10:31.846871Z","end":"2026-10-02T09:10:32.426194Z","steps":["trace[2054896880] 'read index received' (duration: 140.923343ms)","trace[2054896880] 'applied index is now lower than readState.Index' (duration: 438.399014ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:10:32.426702Z","caller":"traceutil/trace.go:171","msg":"trace[1859186412] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"499.235306ms","start":"2026-10-02T09:10:31.927454Z","end":"2026-10-02T09:10:32.42669Z","steps":["trace[1859186412] 'process raft request' (duration: 498.626659ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:10:32.426651Z","caller":"traceutil/trace.go:171","msg":"trace[761164901] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"499.217465ms","start":"2026-10-02T09:10:31.927341Z","end":"2026-10-02T09:10:32.426559Z","steps":["trace[761164901] 'process raft request' (duration: 498.715422ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:10:32.426939Z","caller":"traceutil/trace.go:171","msg":"trace[387473005] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"500.175168ms","start":"2026-10-02T09:10:31.926718Z","end":"2026-10-02T09:10:32.426893Z","steps":["trace[387473005] 'process raft request' (duration: 499.249066ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:10:32.426952Z","caller":"traceutil/trace.go:171","msg":"trace[308664445] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"500.134656ms","start":"2026-10-02T09:10:31.926804Z","end":"2026-10-02T09:10:32.426938Z","steps":["trace[308664445] 'process raft request' (duration: 499.197824ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:10:32.427062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:10:31.846069Z","time spent":"580.143083ms","remote":"127.0.0.1:48496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T09:10:32.427116Z","caller":"traceutil/trace.go:171","msg":"trace[1891121515] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"496.317245ms","start":"2026-10-02T09:10:31.930744Z","end":"2026-10-02T09:10:32.427061Z","steps":["trace[1891121515] 'process raft request' (duration: 495.372902ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:10:32.427123Z","caller":"traceutil/trace.go:171","msg":"trace[180110181] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"501.578031ms","start":"2026-10-02T09:10:31.925448Z","end":"2026-10-02T09:10:32.427026Z","steps":["trace[180110181] 'process raft request' (duration: 500.471461ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:10:32.427209Z","caller":"traceutil/trace.go:171","msg":"trace[2141677066] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"500.296874ms","start":"2026-10-02T09:10:31.926896Z","end":"2026-10-02T09:10:32.427192Z","steps":["trace[2141677066] 'process raft request' (duration: 499.133711ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:10:32.427332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:10:31.927331Z","time spent":"499.480198ms","remote":"127.0.0.1:48922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-02T09:10:32.427335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:10:31.925428Z","time spent":"501.806902ms","remote":"127.0.0.1:48838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-02T09:10:32.427323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:10:31.926887Z","time spent":"500.399509ms","remote":"127.0.0.1:48922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-02T09:10:32.427298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:10:31.927445Z","time spent":"499.302379ms","remote":"127.0.0.1:48922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-02T09:10:32.427454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.494747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-02T09:10:32.427485Z","caller":"traceutil/trace.go:171","msg":"trace[218383332] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"430.538449ms","start":"2026-10-02T09:10:31.996936Z","end":"2026-10-02T09:10:32.427474Z","steps":["trace[218383332] 'agreement among raft nodes before linearized reading' (duration: 430.493207ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:10:32.427468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.551112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"warn","ts":"2026-10-02T09:10:32.427549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.682307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-02T09:10:32.427605Z","caller":"traceutil/trace.go:171","msg":"trace[1675641697] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"450.765761ms","start":"2026-10-02T09:10:31.976831Z","end":"2026-10-02T09:10:32.427596Z","steps":["trace[1675641697] 'agreement among raft nodes before linearized reading' (duration: 450.702408ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:10:32.427598Z","caller":"traceutil/trace.go:171","msg":"trace[1087385648] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"580.703029ms","start":"2026-10-02T09:10:31.846845Z","end":"2026-10-02T09:10:32.427548Z","steps":["trace[1087385648] 'agreement among raft nodes before linearized reading' (duration: 580.349213ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:10:32.427637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:10:31.976812Z","time spent":"450.816544ms","remote":"127.0.0.1:48540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-10-02T09:10:32.427348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.04328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-10-02T09:10:32.427683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:10:31.846837Z","time spent":"580.817275ms","remote":"127.0.0.1:48592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3194,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-10-02T09:10:32.427216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:10:31.930733Z","time spent":"496.417059ms","remote":"127.0.0.1:48644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-02T09:10:32.427716Z","caller":"traceutil/trace.go:171","msg":"trace[506810038] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"529.399146ms","start":"2026-10-02T09:10:31.898285Z","end":"2026-10-02T09:10:32.427684Z","steps":["trace[506810038] 'agreement among raft nodes before linearized reading' (duration: 528.971977ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:10:32.427786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:10:31.898277Z","time spent":"529.486641ms","remote":"127.0.0.1:48540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-10-02T09:10:32.427107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:10:31.926786Z","time spent":"500.234311ms","remote":"127.0.0.1:48922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-02T09:10:32.427857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.726824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-02T09:10:32.42789Z","caller":"traceutil/trace.go:171","msg":"trace[918371902] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"500.807137ms","start":"2026-10-02T09:10:31.927072Z","end":"2026-10-02T09:10:32.427879Z","steps":["trace[918371902] 'agreement among raft nodes before linearized reading' (duration: 500.760165ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:10:32.427513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:10:31.996925Z","time spent":"430.582261ms","remote":"127.0.0.1:48540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-10-02T09:10:32.427917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:10:31.927065Z","time spent":"500.842349ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-10-02T09:10:32.427073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:10:31.926707Z","time spent":"500.289784ms","remote":"127.0.0.1:48922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-02T09:10:32.427991Z","caller":"traceutil/trace.go:171","msg":"trace[2137174076] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"495.282279ms","start":"2026-10-02T09:10:31.932694Z","end":"2026-10-02T09:10:32.427976Z","steps":["trace[2137174076] 'process raft request' (duration: 493.456636ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:10:32.428051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:10:31.932681Z","time spent":"495.340481ms","remote":"127.0.0.1:48592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-02T09:10:32.428069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.369513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-02T09:10:32.428101Z","caller":"traceutil/trace.go:171","msg":"trace[550296937] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"503.466747ms","start":"2026-10-02T09:10:31.924626Z","end":"2026-10-02T09:10:32.428093Z","steps":["trace[550296937] 'agreement among raft nodes before linearized reading' (duration: 503.417335ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:10:32.428124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:10:31.924616Z","time spent":"503.502129ms","remote":"127.0.0.1:48540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-10-02T09:10:32.428336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.066634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.182\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-02T09:10:32.428408Z","caller":"traceutil/trace.go:171","msg":"trace[250202099] range","detail":"{range_begin:/registry/masterleases/199.204.45.182; range_end:; response_count:0; response_revision:11; }","duration":"504.167689ms","start":"2026-10-02T09:10:31.924226Z","end":"2026-10-02T09:10:32.428394Z","steps":["trace[250202099] 'agreement among raft nodes before linearized reading' (duration: 503.993761ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:10:32.4284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.998944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-02T09:10:32.428502Z","caller":"traceutil/trace.go:171","msg":"trace[10751242] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"530.210944ms","start":"2026-10-02T09:10:31.898274Z","end":"2026-10-02T09:10:32.428485Z","steps":["trace[10751242] 'agreement among raft nodes before linearized reading' (duration: 529.955272ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:10:32.428605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:10:31.898248Z","time spent":"530.34371ms","remote":"127.0.0.1:48540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-10-02T09:10:32.42844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:10:31.924212Z","time spent":"504.220701ms","remote":"127.0.0.1:48480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.182\" "} {"level":"info","ts":"2026-10-02T09:10:32.709425Z","caller":"traceutil/trace.go:171","msg":"trace[286794061] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"273.289191ms","start":"2026-10-02T09:10:32.436106Z","end":"2026-10-02T09:10:32.709395Z","steps":["trace[286794061] 'process raft request' (duration: 264.93407ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:10:32.710346Z","caller":"traceutil/trace.go:171","msg":"trace[986936394] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"274.114178ms","start":"2026-10-02T09:10:32.436212Z","end":"2026-10-02T09:10:32.710327Z","steps":["trace[986936394] 'process raft request' (duration: 273.676968ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:10:32.710415Z","caller":"traceutil/trace.go:171","msg":"trace[910330480] linearizableReadLoop","detail":"{readStateIndex:18; appliedIndex:14; }","duration":"273.764442ms","start":"2026-10-02T09:10:32.436637Z","end":"2026-10-02T09:10:32.710401Z","steps":["trace[910330480] 'read index received' (duration: 264.524042ms)","trace[910330480] 'applied index is now lower than readState.Index' (duration: 9.23968ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:10:32.710806Z","caller":"traceutil/trace.go:171","msg":"trace[471750930] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"272.688373ms","start":"2026-10-02T09:10:32.438102Z","end":"2026-10-02T09:10:32.71079Z","steps":["trace[471750930] 'process raft request' (duration: 272.223552ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:10:32.710857Z","caller":"traceutil/trace.go:171","msg":"trace[1367449846] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"274.513406ms","start":"2026-10-02T09:10:32.436327Z","end":"2026-10-02T09:10:32.71084Z","steps":["trace[1367449846] 'process raft request' (duration: 273.682029ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:10:32.711261Z","caller":"traceutil/trace.go:171","msg":"trace[2034829397] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"274.708655ms","start":"2026-10-02T09:10:32.436532Z","end":"2026-10-02T09:10:32.71124Z","steps":["trace[2034829397] 'process raft request' (duration: 273.536461ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:10:32.711651Z","caller":"traceutil/trace.go:171","msg":"trace[452010462] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"274.901704ms","start":"2026-10-02T09:10:32.436734Z","end":"2026-10-02T09:10:32.711635Z","steps":["trace[452010462] 'process raft request' (duration: 273.435537ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:10:32.711274Z","caller":"traceutil/trace.go:171","msg":"trace[1126967143] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"268.374076ms","start":"2026-10-02T09:10:32.442884Z","end":"2026-10-02T09:10:32.711258Z","steps":["trace[1126967143] 'process raft request' (duration: 267.473165ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:10:32.711791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.292214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-10-02T09:10:32.711878Z","caller":"traceutil/trace.go:171","msg":"trace[1995300039] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"275.104263ms","start":"2026-10-02T09:10:32.436764Z","end":"2026-10-02T09:10:32.711869Z","steps":["trace[1995300039] 'process raft request' (duration: 273.458288ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:10:32.711372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.710925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-fqr99\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-10-02T09:10:32.712103Z","caller":"traceutil/trace.go:171","msg":"trace[1991397614] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"219.42888ms","start":"2026-10-02T09:10:32.492447Z","end":"2026-10-02T09:10:32.711876Z","steps":["trace[1991397614] 'agreement among raft nodes before linearized reading' (duration: 218.961049ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:10:32.712165Z","caller":"traceutil/trace.go:171","msg":"trace[913693216] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"275.238479ms","start":"2026-10-02T09:10:32.4369Z","end":"2026-10-02T09:10:32.712139Z","steps":["trace[913693216] 'process raft request' (duration: 273.372105ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:10:32.711804Z","caller":"traceutil/trace.go:171","msg":"trace[1058608576] transaction","detail":"{read_only:false; number_of_response:0; response_revision:17; }","duration":"274.924444ms","start":"2026-10-02T09:10:32.436842Z","end":"2026-10-02T09:10:32.711766Z","steps":["trace[1058608576] 'process raft request' (duration: 273.408176ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:10:32.712452Z","caller":"traceutil/trace.go:171","msg":"trace[1388451398] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-fqr99; range_end:; response_count:1; response_revision:20; }","duration":"275.48554ms","start":"2026-10-02T09:10:32.436561Z","end":"2026-10-02T09:10:32.712047Z","steps":["trace[1388451398] 'agreement among raft nodes before linearized reading' (duration: 274.742006ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:10:37.892565Z","caller":"traceutil/trace.go:171","msg":"trace[1796683718] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"128.897768ms","start":"2026-10-02T09:10:37.76364Z","end":"2026-10-02T09:10:37.892538Z","steps":["trace[1796683718] 'process raft request' (duration: 128.709249ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:10:37.898423Z","caller":"traceutil/trace.go:171","msg":"trace[1392795216] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"126.993908ms","start":"2026-10-02T09:10:37.771396Z","end":"2026-10-02T09:10:37.89839Z","steps":["trace[1392795216] 'process raft request' (duration: 126.855332ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:10:40.733122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.067387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-10-02T09:10:40.733231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.853664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-10-02T09:10:40.733272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.556681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5336"} {"level":"info","ts":"2026-10-02T09:10:40.733295Z","caller":"traceutil/trace.go:171","msg":"trace[842144668] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:305; }","duration":"161.953648ms","start":"2026-10-02T09:10:40.571321Z","end":"2026-10-02T09:10:40.733274Z","steps":["trace[842144668] 'range keys from in-memory index tree' (duration: 161.716137ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:10:40.733321Z","caller":"traceutil/trace.go:171","msg":"trace[1716937140] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:305; }","duration":"277.599653ms","start":"2026-10-02T09:10:40.455705Z","end":"2026-10-02T09:10:40.733304Z","steps":["trace[1716937140] 'range keys from in-memory index tree' (duration: 277.400963ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:10:40.733239Z","caller":"traceutil/trace.go:171","msg":"trace[125413956] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:305; }","duration":"212.230555ms","start":"2026-10-02T09:10:40.520983Z","end":"2026-10-02T09:10:40.733213Z","steps":["trace[125413956] 'range keys from in-memory index tree' (duration: 211.944561ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:10:40.733431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.515883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-10-02T09:10:40.733158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.656461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-10-02T09:10:40.733497Z","caller":"traceutil/trace.go:171","msg":"trace[973895389] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:305; }","duration":"313.627538ms","start":"2026-10-02T09:10:40.419853Z","end":"2026-10-02T09:10:40.73348Z","steps":["trace[973895389] 'range keys from in-memory index tree' (duration: 313.342035ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:10:40.733543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:10:40.419829Z","time spent":"313.702862ms","remote":"127.0.0.1:48610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"info","ts":"2026-10-02T09:10:40.733563Z","caller":"traceutil/trace.go:171","msg":"trace[299561733] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:305; }","duration":"112.086342ms","start":"2026-10-02T09:10:40.621439Z","end":"2026-10-02T09:10:40.733526Z","steps":["trace[299561733] 'range keys from in-memory index tree' (duration: 111.535075ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:10:40.733699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.67072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-10-02T09:10:40.73379Z","caller":"traceutil/trace.go:171","msg":"trace[1530951414] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:305; }","duration":"262.747373ms","start":"2026-10-02T09:10:40.47099Z","end":"2026-10-02T09:10:40.733738Z","steps":["trace[1530951414] 'range keys from in-memory index tree' (duration: 262.215387ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:10:40.889123Z","caller":"traceutil/trace.go:171","msg":"trace[83890614] linearizableReadLoop","detail":"{readStateIndex:323; appliedIndex:317; }","duration":"118.622629ms","start":"2026-10-02T09:10:40.770474Z","end":"2026-10-02T09:10:40.889096Z","steps":["trace[83890614] 'read index received' (duration: 58.262874ms)","trace[83890614] 'applied index is now lower than readState.Index' (duration: 60.358675ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:10:40.889441Z","caller":"traceutil/trace.go:171","msg":"trace[1698660480] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"142.717526ms","start":"2026-10-02T09:10:40.746657Z","end":"2026-10-02T09:10:40.889375Z","steps":["trace[1698660480] 'process raft request' (duration: 142.348228ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:10:40.889449Z","caller":"traceutil/trace.go:171","msg":"trace[707011799] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"144.117003ms","start":"2026-10-02T09:10:40.745302Z","end":"2026-10-02T09:10:40.889419Z","steps":["trace[707011799] 'process raft request' (duration: 143.583868ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:10:40.889855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.345423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-10-02T09:10:40.889913Z","caller":"traceutil/trace.go:171","msg":"trace[934742714] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:311; }","duration":"119.459839ms","start":"2026-10-02T09:10:40.770436Z","end":"2026-10-02T09:10:40.889896Z","steps":["trace[934742714] 'agreement among raft nodes before linearized reading' (duration: 119.345654ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:10:40.890113Z","caller":"traceutil/trace.go:171","msg":"trace[994040751] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"141.498057ms","start":"2026-10-02T09:10:40.748602Z","end":"2026-10-02T09:10:40.8901Z","steps":["trace[994040751] 'process raft request' (duration: 140.452657ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:10:40.890199Z","caller":"traceutil/trace.go:171","msg":"trace[1413326975] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"145.178285ms","start":"2026-10-02T09:10:40.744999Z","end":"2026-10-02T09:10:40.890177Z","steps":["trace[1413326975] 'process raft request' (duration: 143.687973ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:10:40.890342Z","caller":"traceutil/trace.go:171","msg":"trace[856550911] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"142.908945ms","start":"2026-10-02T09:10:40.746589Z","end":"2026-10-02T09:10:40.889498Z","steps":["trace[856550911] 'process raft request' (duration: 142.367149ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:11:11.737976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.388546ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-02T09:11:11.738123Z","caller":"traceutil/trace.go:171","msg":"trace[1184372898] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"323.375191ms","start":"2026-10-02T09:11:11.414721Z","end":"2026-10-02T09:11:11.738096Z","steps":["trace[1184372898] 'process raft request' (duration: 51.55664ms)","trace[1184372898] 'compare' (duration: 271.172644ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:11:11.738203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:11:11.414708Z","time spent":"323.460306ms","remote":"127.0.0.1:48922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":986,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-02T09:11:11.959895Z","caller":"traceutil/trace.go:171","msg":"trace[912691338] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"469.979411ms","start":"2026-10-02T09:11:11.489888Z","end":"2026-10-02T09:11:11.959867Z","steps":["trace[912691338] 'process raft request' (duration: 407.084405ms)","trace[912691338] 'compare' (duration: 62.758198ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:11:11.960019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:11:11.489865Z","time spent":"470.094507ms","remote":"127.0.0.1:48490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-02T09:11:11.961275Z","caller":"traceutil/trace.go:171","msg":"trace[718916914] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:567; }","duration":"400.752003ms","start":"2026-10-02T09:11:11.560502Z","end":"2026-10-02T09:11:11.961254Z","steps":["trace[718916914] 'read index received' (duration: 336.486159ms)","trace[718916914] 'applied index is now lower than readState.Index' (duration: 64.264344ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:11:11.961579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.05016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-02T09:11:11.961665Z","caller":"traceutil/trace.go:171","msg":"trace[1520924455] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"441.118625ms","start":"2026-10-02T09:11:11.520534Z","end":"2026-10-02T09:11:11.961653Z","steps":["trace[1520924455] 'process raft request' (duration: 440.596035ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:11:11.961672Z","caller":"traceutil/trace.go:171","msg":"trace[1427503365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:544; }","duration":"401.185248ms","start":"2026-10-02T09:11:11.560468Z","end":"2026-10-02T09:11:11.961653Z","steps":["trace[1427503365] 'agreement among raft nodes before linearized reading' (duration: 400.973696ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:11:11.961717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:11:11.56044Z","time spent":"401.267542ms","remote":"127.0.0.1:48656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-02T09:11:11.961706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.386946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-02T09:11:11.961827Z","caller":"traceutil/trace.go:171","msg":"trace[1816597208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:544; }","duration":"281.565026ms","start":"2026-10-02T09:11:11.680233Z","end":"2026-10-02T09:11:11.961798Z","steps":["trace[1816597208] 'agreement among raft nodes before linearized reading' (duration: 281.28879ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:11:11.96176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:11:11.520497Z","time spent":"441.188699ms","remote":"127.0.0.1:48490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18032,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T09:11:17.782653Z","caller":"traceutil/trace.go:171","msg":"trace[856211803] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"185.559668ms","start":"2026-10-02T09:11:17.597055Z","end":"2026-10-02T09:11:17.782615Z","steps":["trace[856211803] 'process raft request' (duration: 185.344125ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:11:17.827255Z","caller":"traceutil/trace.go:171","msg":"trace[1024637414] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"130.003533ms","start":"2026-10-02T09:11:17.697222Z","end":"2026-10-02T09:11:17.827225Z","steps":["trace[1024637414] 'process raft request' (duration: 129.310172ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:11:18.457989Z","caller":"traceutil/trace.go:171","msg":"trace[891349257] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:625; }","duration":"503.178859ms","start":"2026-10-02T09:11:17.954779Z","end":"2026-10-02T09:11:18.457958Z","steps":["trace[891349257] 'read index received' (duration: 472.385939ms)","trace[891349257] 'applied index is now lower than readState.Index' (duration: 30.791159ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:11:18.458219Z","caller":"traceutil/trace.go:171","msg":"trace[1578825831] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"559.296628ms","start":"2026-10-02T09:11:17.898907Z","end":"2026-10-02T09:11:18.458203Z","steps":["trace[1578825831] 'process raft request' (duration: 528.35692ms)","trace[1578825831] 'compare' (duration: 30.078128ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:11:18.458323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:11:17.89888Z","time spent":"559.375373ms","remote":"127.0.0.1:48758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-02T09:11:18.45862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.738647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-10-02T09:11:18.458644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.839378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-02T09:11:18.458641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.935315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-02T09:11:18.458677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.144626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-02T09:11:18.458723Z","caller":"traceutil/trace.go:171","msg":"trace[518637621] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:600; }","duration":"503.964925ms","start":"2026-10-02T09:11:17.954736Z","end":"2026-10-02T09:11:18.458701Z","steps":["trace[518637621] 'agreement among raft nodes before linearized reading' (duration: 503.811236ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:11:18.458731Z","caller":"traceutil/trace.go:171","msg":"trace[81889104] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:600; }","duration":"188.232401ms","start":"2026-10-02T09:11:18.270485Z","end":"2026-10-02T09:11:18.458718Z","steps":["trace[81889104] 'agreement among raft nodes before linearized reading' (duration: 188.097363ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:11:18.458686Z","caller":"traceutil/trace.go:171","msg":"trace[1169576660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:600; }","duration":"448.874334ms","start":"2026-10-02T09:11:18.009792Z","end":"2026-10-02T09:11:18.458666Z","steps":["trace[1169576660] 'agreement among raft nodes before linearized reading' (duration: 448.554285ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:11:18.45876Z","caller":"traceutil/trace.go:171","msg":"trace[1922654122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:600; }","duration":"168.075793ms","start":"2026-10-02T09:11:18.290638Z","end":"2026-10-02T09:11:18.458713Z","steps":["trace[1922654122] 'agreement among raft nodes before linearized reading' (duration: 167.896672ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:11:18.458775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:11:17.954717Z","time spent":"504.05052ms","remote":"127.0.0.1:48462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-02T09:11:18.458788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:11:18.009768Z","time spent":"449.008982ms","remote":"127.0.0.1:48656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-02T09:11:18.458943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.939571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-02T09:11:18.459043Z","caller":"traceutil/trace.go:171","msg":"trace[913297913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:600; }","duration":"396.103351ms","start":"2026-10-02T09:11:18.062916Z","end":"2026-10-02T09:11:18.459019Z","steps":["trace[913297913] 'agreement among raft nodes before linearized reading' (duration: 395.75723ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:11:18.459094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:11:18.062891Z","time spent":"396.193655ms","remote":"127.0.0.1:48656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-02T09:11:19.084055Z","caller":"traceutil/trace.go:171","msg":"trace[2039436006] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"137.424447ms","start":"2026-10-02T09:11:18.946597Z","end":"2026-10-02T09:11:19.084021Z","steps":["trace[2039436006] 'process raft request' (duration: 135.203587ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:11:19.272176Z","caller":"traceutil/trace.go:171","msg":"trace[725052384] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"110.696241ms","start":"2026-10-02T09:11:19.161448Z","end":"2026-10-02T09:11:19.272144Z","steps":["trace[725052384] 'process raft request' (duration: 99.243589ms)","trace[725052384] 'compare' (duration: 11.304233ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:11:19.905Z","caller":"traceutil/trace.go:171","msg":"trace[1406647987] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"120.113443ms","start":"2026-10-02T09:11:19.784791Z","end":"2026-10-02T09:11:19.904905Z","steps":["trace[1406647987] 'process raft request' (duration: 29.994699ms)","trace[1406647987] 'compare' (duration: 89.929243ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:11:35.334105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.056968ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T09:11:35.334339Z","caller":"traceutil/trace.go:171","msg":"trace[1016608252] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:772; }","duration":"149.329235ms","start":"2026-10-02T09:11:35.184986Z","end":"2026-10-02T09:11:35.334316Z","steps":["trace[1016608252] 'range keys from in-memory index tree' (duration: 149.027977ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:11:35.334389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.326432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T09:11:35.334515Z","caller":"traceutil/trace.go:171","msg":"trace[912835954] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:772; }","duration":"339.839263ms","start":"2026-10-02T09:11:34.994652Z","end":"2026-10-02T09:11:35.334491Z","steps":["trace[912835954] 'count revisions from in-memory index tree' (duration: 339.12526ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:11:35.33463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:11:34.99457Z","time spent":"340.023375ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-10-02T09:12:25.290931Z","caller":"traceutil/trace.go:171","msg":"trace[663621271] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1341; }","duration":"136.499132ms","start":"2026-10-02T09:12:25.154392Z","end":"2026-10-02T09:12:25.290891Z","steps":["trace[663621271] 'read index received' (duration: 95.841883ms)","trace[663621271] 'applied index is now lower than readState.Index' (duration: 40.656149ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:12:25.291122Z","caller":"traceutil/trace.go:171","msg":"trace[1376213736] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"169.688755ms","start":"2026-10-02T09:12:25.121414Z","end":"2026-10-02T09:12:25.291103Z","steps":["trace[1376213736] 'process raft request' (duration: 128.775679ms)","trace[1376213736] 'compare' (duration: 40.51449ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:12:25.291407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.789251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-02T09:12:25.291476Z","caller":"traceutil/trace.go:171","msg":"trace[1273882944] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1295; }","duration":"125.970203ms","start":"2026-10-02T09:12:25.165487Z","end":"2026-10-02T09:12:25.291457Z","steps":["trace[1273882944] 'agreement among raft nodes before linearized reading' (duration: 125.795031ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:12:25.291832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.430364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-10-02T09:12:25.291904Z","caller":"traceutil/trace.go:171","msg":"trace[1630882029] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1295; }","duration":"137.53532ms","start":"2026-10-02T09:12:25.154354Z","end":"2026-10-02T09:12:25.291889Z","steps":["trace[1630882029] 'agreement among raft nodes before linearized reading' (duration: 137.314696ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:12:26.852161Z","caller":"traceutil/trace.go:171","msg":"trace[2117036513] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"109.837529ms","start":"2026-10-02T09:12:26.742282Z","end":"2026-10-02T09:12:26.85212Z","steps":["trace[2117036513] 'process raft request' (duration: 109.488516ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:12:26.963997Z","caller":"traceutil/trace.go:171","msg":"trace[486471] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"106.062548ms","start":"2026-10-02T09:12:26.857902Z","end":"2026-10-02T09:12:26.963964Z","steps":["trace[486471] 'process raft request' (duration: 105.862355ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:12:39.198951Z","caller":"traceutil/trace.go:171","msg":"trace[1723284077] transaction","detail":"{read_only:false; response_revision:1472; number_of_response:1; }","duration":"113.998954ms","start":"2026-10-02T09:12:39.084898Z","end":"2026-10-02T09:12:39.198897Z","steps":["trace[1723284077] 'process raft request' (duration: 44.494221ms)","trace[1723284077] 'compare' (duration: 69.091895ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:12:39.198784Z","caller":"traceutil/trace.go:171","msg":"trace[2015286769] transaction","detail":"{read_only:false; response_revision:1473; number_of_response:1; }","duration":"110.9681ms","start":"2026-10-02T09:12:39.087775Z","end":"2026-10-02T09:12:39.198743Z","steps":["trace[2015286769] 'process raft request' (duration: 110.901846ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:12:53.102542Z","caller":"traceutil/trace.go:171","msg":"trace[2117936095] transaction","detail":"{read_only:false; response_revision:1682; number_of_response:1; }","duration":"112.81275ms","start":"2026-10-02T09:12:52.989661Z","end":"2026-10-02T09:12:53.102474Z","steps":["trace[2117936095] 'process raft request' (duration: 112.444355ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:13:04.33583Z","caller":"traceutil/trace.go:171","msg":"trace[894228184] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"129.581216ms","start":"2026-10-02T09:13:04.206211Z","end":"2026-10-02T09:13:04.335793Z","steps":["trace[894228184] 'process raft request' (duration: 129.375042ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:13:04.923676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.560725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-606a846c-af4a-47be-892f-0182b6571656\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-10-02T09:13:04.923768Z","caller":"traceutil/trace.go:171","msg":"trace[443427094] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-606a846c-af4a-47be-892f-0182b6571656; range_end:; response_count:1; response_revision:1873; }","duration":"393.719215ms","start":"2026-10-02T09:13:04.530027Z","end":"2026-10-02T09:13:04.923747Z","steps":["trace[443427094] 'range keys from in-memory index tree' (duration: 393.451877ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:13:04.92381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:13:04.530007Z","time spent":"393.79448ms","remote":"127.0.0.1:48602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3770,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-606a846c-af4a-47be-892f-0182b6571656\" "} {"level":"warn","ts":"2026-10-02T09:13:04.924098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.359839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"warn","ts":"2026-10-02T09:13:04.924132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.527197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-10-02T09:13:04.924171Z","caller":"traceutil/trace.go:171","msg":"trace[1848027179] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1873; }","duration":"292.474837ms","start":"2026-10-02T09:13:04.631676Z","end":"2026-10-02T09:13:04.924151Z","steps":["trace[1848027179] 'range keys from in-memory index tree' (duration: 292.168056ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:13:04.924206Z","caller":"traceutil/trace.go:171","msg":"trace[1091278289] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1873; }","duration":"174.643765ms","start":"2026-10-02T09:13:04.749544Z","end":"2026-10-02T09:13:04.924188Z","steps":["trace[1091278289] 'range keys from in-memory index tree' (duration: 174.347704ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:13:04.924091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.449038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-10-02T09:13:04.924577Z","caller":"traceutil/trace.go:171","msg":"trace[507541843] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1873; }","duration":"362.75114ms","start":"2026-10-02T09:13:04.561584Z","end":"2026-10-02T09:13:04.924335Z","steps":["trace[507541843] 'range keys from in-memory index tree' (duration: 362.244734ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:13:04.925746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:13:04.561573Z","time spent":"363.088712ms","remote":"127.0.0.1:39512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4614,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-10-02T09:13:05.086595Z","caller":"traceutil/trace.go:171","msg":"trace[1903883630] linearizableReadLoop","detail":"{readStateIndex:1937; appliedIndex:1935; }","duration":"123.936012ms","start":"2026-10-02T09:13:04.962633Z","end":"2026-10-02T09:13:05.086569Z","steps":["trace[1903883630] 'read index received' (duration: 16.24528ms)","trace[1903883630] 'applied index is now lower than readState.Index' (duration: 107.689812ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:13:05.086709Z","caller":"traceutil/trace.go:171","msg":"trace[2113203749] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"153.228433ms","start":"2026-10-02T09:13:04.933438Z","end":"2026-10-02T09:13:05.086666Z","steps":["trace[2113203749] 'process raft request' (duration: 93.625298ms)","trace[2113203749] 'compare' (duration: 59.394871ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:13:05.086807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.157507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-02T09:13:05.086849Z","caller":"traceutil/trace.go:171","msg":"trace[276317577] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1875; }","duration":"124.250054ms","start":"2026-10-02T09:13:04.962585Z","end":"2026-10-02T09:13:05.086835Z","steps":["trace[276317577] 'agreement among raft nodes before linearized reading' (duration: 124.125555ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:13:05.087226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.72888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:567"} {"level":"info","ts":"2026-10-02T09:13:05.087284Z","caller":"traceutil/trace.go:171","msg":"trace[1704799206] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1875; }","duration":"104.814665ms","start":"2026-10-02T09:13:04.982451Z","end":"2026-10-02T09:13:05.087265Z","steps":["trace[1704799206] 'agreement among raft nodes before linearized reading' (duration: 104.542547ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:13:05.457712Z","caller":"traceutil/trace.go:171","msg":"trace[303986385] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"363.082804ms","start":"2026-10-02T09:13:05.094608Z","end":"2026-10-02T09:13:05.457691Z","steps":["trace[303986385] 'process raft request' (duration: 342.512658ms)","trace[303986385] 'compare' (duration: 20.457239ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:13:05.457843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:13:05.094585Z","time spent":"363.191251ms","remote":"127.0.0.1:48656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":537,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T09:13:05.54806Z","caller":"traceutil/trace.go:171","msg":"trace[520529619] linearizableReadLoop","detail":"{readStateIndex:1941; appliedIndex:1938; }","duration":"258.361444ms","start":"2026-10-02T09:13:05.289648Z","end":"2026-10-02T09:13:05.548009Z","steps":["trace[520529619] 'read index received' (duration: 147.480533ms)","trace[520529619] 'applied index is now lower than readState.Index' (duration: 110.879671ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:13:05.548134Z","caller":"traceutil/trace.go:171","msg":"trace[1951032451] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"398.197725ms","start":"2026-10-02T09:13:05.149882Z","end":"2026-10-02T09:13:05.548079Z","steps":["trace[1951032451] 'process raft request' (duration: 396.37086ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:13:05.548183Z","caller":"traceutil/trace.go:171","msg":"trace[608673934] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"357.723888ms","start":"2026-10-02T09:13:05.19041Z","end":"2026-10-02T09:13:05.548134Z","steps":["trace[608673934] 'process raft request' (duration: 357.515294ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:13:05.548471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:13:05.190389Z","time spent":"357.906601ms","remote":"127.0.0.1:48656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T09:13:05.548567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.124138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-02T09:13:05.548614Z","caller":"traceutil/trace.go:171","msg":"trace[412249745] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1879; }","duration":"246.188193ms","start":"2026-10-02T09:13:05.30241Z","end":"2026-10-02T09:13:05.548598Z","steps":["trace[412249745] 'agreement among raft nodes before linearized reading' (duration: 246.099487ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:13:05.54842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.59541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-02T09:13:05.548777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.288352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-10-02T09:13:05.548533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:13:05.149824Z","time spent":"398.372127ms","remote":"127.0.0.1:48656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T09:13:05.548465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.138259ms","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-10-02T09:13:05.548964Z","caller":"traceutil/trace.go:171","msg":"trace[749900971] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1879; }","duration":"246.706288ms","start":"2026-10-02T09:13:05.302248Z","end":"2026-10-02T09:13:05.548954Z","steps":["trace[749900971] 'agreement among raft nodes before linearized reading' (duration: 246.00124ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:13:05.548742Z","caller":"traceutil/trace.go:171","msg":"trace[1635212917] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1879; }","duration":"259.134037ms","start":"2026-10-02T09:13:05.289589Z","end":"2026-10-02T09:13:05.548723Z","steps":["trace[1635212917] 'agreement among raft nodes before linearized reading' (duration: 258.609351ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:13:05.548804Z","caller":"traceutil/trace.go:171","msg":"trace[60335033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1879; }","duration":"122.337754ms","start":"2026-10-02T09:13:05.426458Z","end":"2026-10-02T09:13:05.548796Z","steps":["trace[60335033] 'agreement among raft nodes before linearized reading' (duration: 122.252548ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:13:05.548936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.263097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-02T09:13:05.549612Z","caller":"traceutil/trace.go:171","msg":"trace[1316614641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1879; }","duration":"124.017949ms","start":"2026-10-02T09:13:05.425582Z","end":"2026-10-02T09:13:05.5496Z","steps":["trace[1316614641] 'agreement among raft nodes before linearized reading' (duration: 122.882422ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:13:37.708022Z","caller":"traceutil/trace.go:171","msg":"trace[1742796750] linearizableReadLoop","detail":"{readStateIndex:2217; appliedIndex:2216; }","duration":"130.391637ms","start":"2026-10-02T09:13:37.577612Z","end":"2026-10-02T09:13:37.708004Z","steps":["trace[1742796750] 'read index received' (duration: 130.224668ms)","trace[1742796750] 'applied index is now lower than readState.Index' (duration: 166.089µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:13:37.708089Z","caller":"traceutil/trace.go:171","msg":"trace[480565153] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"158.513796ms","start":"2026-10-02T09:13:37.549553Z","end":"2026-10-02T09:13:37.708066Z","steps":["trace[480565153] 'process raft request' (duration: 158.302898ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:13:37.7082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.542436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-02T09:13:37.708247Z","caller":"traceutil/trace.go:171","msg":"trace[1481045893] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:2143; }","duration":"130.655565ms","start":"2026-10-02T09:13:37.577578Z","end":"2026-10-02T09:13:37.708233Z","steps":["trace[1481045893] 'agreement among raft nodes before linearized reading' (duration: 130.544046ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:15:08.258129Z","caller":"traceutil/trace.go:171","msg":"trace[1460980369] transaction","detail":"{read_only:false; response_revision:2865; number_of_response:1; }","duration":"126.028758ms","start":"2026-10-02T09:15:08.132059Z","end":"2026-10-02T09:15:08.258088Z","steps":["trace[1460980369] 'process raft request' (duration: 125.76234ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:17:41.76885Z","caller":"traceutil/trace.go:171","msg":"trace[790295536] transaction","detail":"{read_only:false; response_revision:4507; number_of_response:1; }","duration":"144.657168ms","start":"2026-10-02T09:17:41.624173Z","end":"2026-10-02T09:17:41.76883Z","steps":["trace[790295536] 'process raft request' (duration: 144.544383ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:17:41.769074Z","caller":"traceutil/trace.go:171","msg":"trace[1376720409] linearizableReadLoop","detail":"{readStateIndex:4705; appliedIndex:4705; }","duration":"133.865774ms","start":"2026-10-02T09:17:41.635186Z","end":"2026-10-02T09:17:41.769052Z","steps":["trace[1376720409] 'read index received' (duration: 133.854944ms)","trace[1376720409] 'applied index is now lower than readState.Index' (duration: 8.99µs)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:17:41.769289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.058545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c548785b-2434-48fa-93d0-8a1ff414111e\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-10-02T09:17:41.769317Z","caller":"traceutil/trace.go:171","msg":"trace[2044558810] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c548785b-2434-48fa-93d0-8a1ff414111e; range_end:; response_count:1; response_revision:4507; }","duration":"134.157949ms","start":"2026-10-02T09:17:41.635149Z","end":"2026-10-02T09:17:41.769307Z","steps":["trace[2044558810] 'agreement among raft nodes before linearized reading' (duration: 133.98026ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:17:41.834102Z","caller":"traceutil/trace.go:171","msg":"trace[134560478] transaction","detail":"{read_only:false; response_revision:4508; number_of_response:1; }","duration":"194.501261ms","start":"2026-10-02T09:17:41.639572Z","end":"2026-10-02T09:17:41.834073Z","steps":["trace[134560478] 'process raft request' (duration: 155.79655ms)","trace[134560478] 'compare' (duration: 38.505731ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:17:46.22341Z","caller":"traceutil/trace.go:171","msg":"trace[1468613557] transaction","detail":"{read_only:false; response_revision:4616; number_of_response:1; }","duration":"118.651994ms","start":"2026-10-02T09:17:46.104733Z","end":"2026-10-02T09:17:46.223385Z","steps":["trace[1468613557] 'process raft request' (duration: 118.426252ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:17:48.730035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.71684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bf341fc0-729f-400f-8aeb-1abfb15d79c8\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-02T09:17:48.730123Z","caller":"traceutil/trace.go:171","msg":"trace[715294954] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bf341fc0-729f-400f-8aeb-1abfb15d79c8; range_end:; response_count:1; response_revision:4700; }","duration":"168.862207ms","start":"2026-10-02T09:17:48.561241Z","end":"2026-10-02T09:17:48.730103Z","steps":["trace[715294954] 'range keys from in-memory index tree' (duration: 168.447486ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:17:51.135127Z","caller":"traceutil/trace.go:171","msg":"trace[1418075013] transaction","detail":"{read_only:false; response_revision:4720; number_of_response:1; }","duration":"118.3263ms","start":"2026-10-02T09:17:51.016772Z","end":"2026-10-02T09:17:51.135098Z","steps":["trace[1418075013] 'process raft request' (duration: 118.143271ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:18:03.047631Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13928403276247504446,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-02T09:18:03.219554Z","caller":"traceutil/trace.go:171","msg":"trace[861173143] transaction","detail":"{read_only:false; response_revision:4881; number_of_response:1; }","duration":"675.329914ms","start":"2026-10-02T09:18:02.544197Z","end":"2026-10-02T09:18:03.219526Z","steps":["trace[861173143] 'process raft request' (duration: 675.006558ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:18:03.220139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:18:02.544181Z","time spent":"675.668612ms","remote":"127.0.0.1:48502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":679,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T09:18:03.221854Z","caller":"traceutil/trace.go:171","msg":"trace[372084184] linearizableReadLoop","detail":"{readStateIndex:5096; appliedIndex:5095; }","duration":"674.624708ms","start":"2026-10-02T09:18:02.54721Z","end":"2026-10-02T09:18:03.221834Z","steps":["trace[372084184] 'read index received' (duration: 672.937931ms)","trace[372084184] 'applied index is now lower than readState.Index' (duration: 1.685327ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:18:03.221937Z","caller":"traceutil/trace.go:171","msg":"trace[927532894] transaction","detail":"{read_only:false; response_revision:4882; number_of_response:1; }","duration":"250.223977ms","start":"2026-10-02T09:18:02.971681Z","end":"2026-10-02T09:18:03.221905Z","steps":["trace[927532894] 'process raft request' (duration: 250.029117ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:18:03.222528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.188669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:2102"} {"level":"info","ts":"2026-10-02T09:18:03.222615Z","caller":"traceutil/trace.go:171","msg":"trace[1374314310] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:4882; }","duration":"143.324276ms","start":"2026-10-02T09:18:03.07927Z","end":"2026-10-02T09:18:03.222594Z","steps":["trace[1374314310] 'agreement among raft nodes before linearized reading' (duration: 142.732945ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:18:03.222243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.919264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-02T09:18:03.222727Z","caller":"traceutil/trace.go:171","msg":"trace[1087993504] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4882; }","duration":"527.598189ms","start":"2026-10-02T09:18:02.695105Z","end":"2026-10-02T09:18:03.222703Z","steps":["trace[1087993504] 'agreement among raft nodes before linearized reading' (duration: 526.872642ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:18:03.222823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:18:02.695045Z","time spent":"527.727726ms","remote":"127.0.0.1:39442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-10-02T09:18:03.222393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.632539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-10-02T09:18:03.223015Z","caller":"traceutil/trace.go:171","msg":"trace[752557698] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4882; }","duration":"158.191318ms","start":"2026-10-02T09:18:03.064704Z","end":"2026-10-02T09:18:03.222896Z","steps":["trace[752557698] 'agreement among raft nodes before linearized reading' (duration: 157.566876ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:18:03.224528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.312017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-10-02T09:18:03.224606Z","caller":"traceutil/trace.go:171","msg":"trace[1988040871] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4882; }","duration":"677.404742ms","start":"2026-10-02T09:18:02.547183Z","end":"2026-10-02T09:18:03.224588Z","steps":["trace[1988040871] 'agreement among raft nodes before linearized reading' (duration: 674.761915ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:18:03.224647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:18:02.547174Z","time spent":"677.460575ms","remote":"127.0.0.1:48584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-10-02T09:18:07.483388Z","caller":"traceutil/trace.go:171","msg":"trace[1801173871] linearizableReadLoop","detail":"{readStateIndex:5148; appliedIndex:5147; }","duration":"236.961801ms","start":"2026-10-02T09:18:07.246402Z","end":"2026-10-02T09:18:07.483364Z","steps":["trace[1801173871] 'read index received' (duration: 236.797463ms)","trace[1801173871] 'applied index is now lower than readState.Index' (duration: 162.958µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:18:07.483515Z","caller":"traceutil/trace.go:171","msg":"trace[366153784] transaction","detail":"{read_only:false; response_revision:4932; number_of_response:1; }","duration":"245.111337ms","start":"2026-10-02T09:18:07.238388Z","end":"2026-10-02T09:18:07.4835Z","steps":["trace[366153784] 'process raft request' (duration: 244.821511ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:18:07.48378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.14715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-10-02T09:18:07.483859Z","caller":"traceutil/trace.go:171","msg":"trace[1608615842] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4932; }","duration":"146.258416ms","start":"2026-10-02T09:18:07.337581Z","end":"2026-10-02T09:18:07.483839Z","steps":["trace[1608615842] 'agreement among raft nodes before linearized reading' (duration: 146.088637ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:18:07.483789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.337561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3198"} {"level":"info","ts":"2026-10-02T09:18:07.484057Z","caller":"traceutil/trace.go:171","msg":"trace[1865748398] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4932; }","duration":"237.671728ms","start":"2026-10-02T09:18:07.246361Z","end":"2026-10-02T09:18:07.484033Z","steps":["trace[1865748398] 'agreement among raft nodes before linearized reading' (duration: 237.249336ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:18:07.484204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.159223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-02T09:18:07.484285Z","caller":"traceutil/trace.go:171","msg":"trace[252575756] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4932; }","duration":"138.29482ms","start":"2026-10-02T09:18:07.345973Z","end":"2026-10-02T09:18:07.484268Z","steps":["trace[252575756] 'agreement among raft nodes before linearized reading' (duration: 138.046967ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:18:07.605561Z","caller":"traceutil/trace.go:171","msg":"trace[132755861] transaction","detail":"{read_only:false; response_revision:4933; number_of_response:1; }","duration":"116.620129ms","start":"2026-10-02T09:18:07.488903Z","end":"2026-10-02T09:18:07.605523Z","steps":["trace[132755861] 'process raft request' (duration: 43.924823ms)","trace[132755861] 'compare' (duration: 72.554288ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:18:07.62658Z","caller":"traceutil/trace.go:171","msg":"trace[1661617127] transaction","detail":"{read_only:false; response_revision:4934; number_of_response:1; }","duration":"137.12085ms","start":"2026-10-02T09:18:07.489434Z","end":"2026-10-02T09:18:07.626555Z","steps":["trace[1661617127] 'process raft request' (duration: 136.885207ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:18:07.627478Z","caller":"traceutil/trace.go:171","msg":"trace[427682532] transaction","detail":"{read_only:false; response_revision:4937; number_of_response:1; }","duration":"134.45676ms","start":"2026-10-02T09:18:07.492996Z","end":"2026-10-02T09:18:07.627453Z","steps":["trace[427682532] 'process raft request' (duration: 134.266351ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:18:07.627562Z","caller":"traceutil/trace.go:171","msg":"trace[703940906] transaction","detail":"{read_only:false; response_revision:4938; number_of_response:1; }","duration":"134.546965ms","start":"2026-10-02T09:18:07.492997Z","end":"2026-10-02T09:18:07.627544Z","steps":["trace[703940906] 'process raft request' (duration: 134.308173ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:18:07.627661Z","caller":"traceutil/trace.go:171","msg":"trace[799497117] transaction","detail":"{read_only:false; response_revision:4935; number_of_response:1; }","duration":"137.32384ms","start":"2026-10-02T09:18:07.49031Z","end":"2026-10-02T09:18:07.627634Z","steps":["trace[799497117] 'process raft request' (duration: 136.162809ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:18:07.627689Z","caller":"traceutil/trace.go:171","msg":"trace[1126419044] transaction","detail":"{read_only:false; response_revision:4936; number_of_response:1; }","duration":"135.075963ms","start":"2026-10-02T09:18:07.492595Z","end":"2026-10-02T09:18:07.627671Z","steps":["trace[1126419044] 'process raft request' (duration: 134.561766ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:18:07.628195Z","caller":"traceutil/trace.go:171","msg":"trace[1516181292] linearizableReadLoop","detail":"{readStateIndex:5154; appliedIndex:5148; }","duration":"125.08088ms","start":"2026-10-02T09:18:07.502272Z","end":"2026-10-02T09:18:07.627353Z","steps":["trace[1516181292] 'read index received' (duration: 30.549335ms)","trace[1516181292] 'applied index is now lower than readState.Index' (duration: 94.530135ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:18:07.628319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.981558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-02T09:18:07.628474Z","caller":"traceutil/trace.go:171","msg":"trace[1840605953] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4938; }","duration":"126.185948ms","start":"2026-10-02T09:18:07.502239Z","end":"2026-10-02T09:18:07.628425Z","steps":["trace[1840605953] 'agreement among raft nodes before linearized reading' (duration: 125.948726ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:18:07.628493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.355095ms","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-10-02T09:18:07.628547Z","caller":"traceutil/trace.go:171","msg":"trace[149182918] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4938; }","duration":"125.44735ms","start":"2026-10-02T09:18:07.503084Z","end":"2026-10-02T09:18:07.628532Z","steps":["trace[149182918] 'agreement among raft nodes before linearized reading' (duration: 125.201127ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:18:07.629568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.41204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-10-02T09:18:07.629622Z","caller":"traceutil/trace.go:171","msg":"trace[1968123078] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4938; }","duration":"126.489544ms","start":"2026-10-02T09:18:07.503123Z","end":"2026-10-02T09:18:07.629613Z","steps":["trace[1968123078] 'agreement among raft nodes before linearized reading' (duration: 125.289722ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:18:48.012859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.98044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T09:18:48.012938Z","caller":"traceutil/trace.go:171","msg":"trace[98767824] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5342; }","duration":"128.104657ms","start":"2026-10-02T09:18:47.884818Z","end":"2026-10-02T09:18:48.012923Z","steps":["trace[98767824] 'range keys from in-memory index tree' (duration: 127.916576ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:18:48.013044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.922157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T09:18:48.01312Z","caller":"traceutil/trace.go:171","msg":"trace[634535490] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5342; }","duration":"128.055074ms","start":"2026-10-02T09:18:47.885048Z","end":"2026-10-02T09:18:48.013103Z","steps":["trace[634535490] 'range keys from in-memory index tree' (duration: 127.880344ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:18:48.013229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.456287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-10-02T09:18:48.013344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.779508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-10-02T09:18:48.01337Z","caller":"traceutil/trace.go:171","msg":"trace[2123020817] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:5342; }","duration":"129.8232ms","start":"2026-10-02T09:18:47.88354Z","end":"2026-10-02T09:18:48.013364Z","steps":["trace[2123020817] 'range keys from in-memory index tree' (duration: 129.699713ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:18:48.013687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.246625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6156"} {"level":"info","ts":"2026-10-02T09:18:48.01374Z","caller":"traceutil/trace.go:171","msg":"trace[1072730032] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5342; }","duration":"126.318109ms","start":"2026-10-02T09:18:47.887409Z","end":"2026-10-02T09:18:48.013727Z","steps":["trace[1072730032] 'range keys from in-memory index tree' (duration: 126.14481ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:18:48.013898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.698277ms","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-10-02T09:18:48.014013Z","caller":"traceutil/trace.go:171","msg":"trace[2073018568] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5342; }","duration":"107.844145ms","start":"2026-10-02T09:18:47.906141Z","end":"2026-10-02T09:18:48.013985Z","steps":["trace[2073018568] 'range keys from in-memory index tree' (duration: 107.519438ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:18:48.014359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.379475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2360980"} {"level":"info","ts":"2026-10-02T09:18:48.014397Z","caller":"traceutil/trace.go:171","msg":"trace[2121674787] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5342; }","duration":"172.459229ms","start":"2026-10-02T09:18:47.841929Z","end":"2026-10-02T09:18:48.014388Z","steps":["trace[2121674787] 'range keys from in-memory index tree' (duration: 171.286636ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:18:48.01326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.346687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-02T09:18:48.014956Z","caller":"traceutil/trace.go:171","msg":"trace[874044453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5342; }","duration":"143.028109ms","start":"2026-10-02T09:18:47.871886Z","end":"2026-10-02T09:18:48.014914Z","steps":["trace[874044453] 'range keys from in-memory index tree' (duration: 141.252532ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:18:48.013259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.690394ms","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-10-02T09:18:48.015168Z","caller":"traceutil/trace.go:171","msg":"trace[532067512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5342; }","duration":"145.641201ms","start":"2026-10-02T09:18:47.869514Z","end":"2026-10-02T09:18:48.015155Z","steps":["trace[532067512] 'range keys from in-memory index tree' (duration: 143.357936ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:18:48.013294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.891983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T09:18:48.015324Z","caller":"traceutil/trace.go:171","msg":"trace[56174754] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:5342; }","duration":"133.929433ms","start":"2026-10-02T09:18:47.881382Z","end":"2026-10-02T09:18:48.015312Z","steps":["trace[56174754] 'count revisions from in-memory index tree' (duration: 131.84048ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:18:48.015292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.716257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-02T09:18:48.015458Z","caller":"traceutil/trace.go:171","msg":"trace[1554514985] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5342; }","duration":"164.899467ms","start":"2026-10-02T09:18:47.850537Z","end":"2026-10-02T09:18:48.015437Z","steps":["trace[1554514985] 'range keys from in-memory index tree' (duration: 163.087739ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:18:48.013347Z","caller":"traceutil/trace.go:171","msg":"trace[1699687542] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5342; }","duration":"174.612876ms","start":"2026-10-02T09:18:47.838714Z","end":"2026-10-02T09:18:48.013327Z","steps":["trace[1699687542] 'range keys from in-memory index tree' (duration: 174.172142ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:18:48.291551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.937673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3465"} {"level":"info","ts":"2026-10-02T09:18:48.291646Z","caller":"traceutil/trace.go:171","msg":"trace[1435839802] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5348; }","duration":"155.07695ms","start":"2026-10-02T09:18:48.136544Z","end":"2026-10-02T09:18:48.291621Z","steps":["trace[1435839802] 'range keys from in-memory index tree' (duration: 154.750163ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:18:48.558792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.125166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1346"} {"level":"info","ts":"2026-10-02T09:18:48.558878Z","caller":"traceutil/trace.go:171","msg":"trace[1987841106] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:5351; }","duration":"167.249423ms","start":"2026-10-02T09:18:48.39161Z","end":"2026-10-02T09:18:48.558859Z","steps":["trace[1987841106] 'range keys from in-memory index tree' (duration: 166.955787ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:18:48.558949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.086677ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T09:18:48.559252Z","caller":"traceutil/trace.go:171","msg":"trace[1244346474] transaction","detail":"{read_only:false; response_revision:5353; number_of_response:1; }","duration":"165.035583ms","start":"2026-10-02T09:18:48.394203Z","end":"2026-10-02T09:18:48.559238Z","steps":["trace[1244346474] 'process raft request' (duration: 164.935187ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:18:48.55932Z","caller":"traceutil/trace.go:171","msg":"trace[965923216] transaction","detail":"{read_only:false; response_revision:5352; number_of_response:1; }","duration":"165.574332ms","start":"2026-10-02T09:18:48.393723Z","end":"2026-10-02T09:18:48.559297Z","steps":["trace[965923216] 'process raft request' (duration: 20.02753ms)","trace[965923216] 'compare' (duration: 144.96411ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:18:48.715084Z","caller":"traceutil/trace.go:171","msg":"trace[345738555] transaction","detail":"{read_only:false; response_revision:5354; number_of_response:1; }","duration":"116.373564ms","start":"2026-10-02T09:18:48.598639Z","end":"2026-10-02T09:18:48.715012Z","steps":["trace[345738555] 'process raft request' (duration: 102.578193ms)","trace[345738555] 'compare' (duration: 13.526686ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:19:01.649534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.582902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T09:19:01.649633Z","caller":"traceutil/trace.go:171","msg":"trace[1113835259] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:5458; }","duration":"108.752591ms","start":"2026-10-02T09:19:01.540859Z","end":"2026-10-02T09:19:01.649611Z","steps":["trace[1113835259] 'count revisions from in-memory index tree' (duration: 108.511127ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:20:30.407364Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3013} {"level":"info","ts":"2026-10-02T09:20:30.50581Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3013,"took":"96.864564ms","hash":439286232,"current-db-size-bytes":57626624,"current-db-size":"58 MB","current-db-size-in-use-bytes":30654464,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-02T09:20:30.505888Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":439286232,"revision":3013,"compact-revision":-1} {"level":"info","ts":"2026-10-02T09:25:30.413517Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6141} {"level":"info","ts":"2026-10-02T09:25:30.517912Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6141,"took":"102.702691ms","hash":1347257983,"current-db-size-bytes":57626624,"current-db-size":"58 MB","current-db-size-in-use-bytes":18776064,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-02T09:25:30.517986Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1347257983,"revision":6141,"compact-revision":3013}