{"level":"warn","ts":"2026-09-11T17:20:16.723203Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-11T17:20:16.723401Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.109: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://162.253.55.109:2380","--initial-cluster=instance=https://162.253.55.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.109: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-09-11T17:20:16.723533Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-11T17:20:16.723557Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.109:2380"]} {"level":"info","ts":"2026-09-11T17:20:16.72361Z","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-09-11T17:20:16.726648Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.109:2379"]} {"level":"info","ts":"2026-09-11T17:20:16.726912Z","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://162.253.55.109:2380"],"listen-peer-urls":["https://162.253.55.109:2380"],"advertise-client-urls":["https://162.253.55.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.109: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-09-11T17:20:16.734731Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.259486ms"} {"level":"info","ts":"2026-09-11T17:20:16.744526Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1ee91cef4f0023c4","cluster-id":"8dccb05df97999f"} {"level":"info","ts":"2026-09-11T17:20:16.744621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee91cef4f0023c4 switched to configuration voters=()"} {"level":"info","ts":"2026-09-11T17:20:16.74465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee91cef4f0023c4 became follower at term 0"} {"level":"info","ts":"2026-09-11T17:20:16.744667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1ee91cef4f0023c4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-11T17:20:16.744678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee91cef4f0023c4 became follower at term 1"} {"level":"info","ts":"2026-09-11T17:20:16.744734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee91cef4f0023c4 switched to configuration voters=(2227343304859591620)"} {"level":"warn","ts":"2026-09-11T17:20:16.750465Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-11T17:20:16.75195Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-11T17:20:16.75353Z","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-09-11T17:20:16.756475Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1ee91cef4f0023c4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-11T17:20:16.756712Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1ee91cef4f0023c4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-11T17:20:16.756825Z","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-09-11T17:20:16.756893Z","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-09-11T17:20:16.756905Z","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-09-11T17:20:16.757853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee91cef4f0023c4 switched to configuration voters=(2227343304859591620)"} {"level":"info","ts":"2026-09-11T17:20:16.758Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8dccb05df97999f","local-member-id":"1ee91cef4f0023c4","added-peer-id":"1ee91cef4f0023c4","added-peer-peer-urls":["https://162.253.55.109:2380"]} {"level":"info","ts":"2026-09-11T17:20:16.763155Z","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-09-11T17:20:16.763435Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1ee91cef4f0023c4","initial-advertise-peer-urls":["https://162.253.55.109:2380"],"listen-peer-urls":["https://162.253.55.109:2380"],"advertise-client-urls":["https://162.253.55.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-11T17:20:16.763468Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-11T17:20:16.763609Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.109:2380"} {"level":"info","ts":"2026-09-11T17:20:16.763623Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.109:2380"} {"level":"info","ts":"2026-09-11T17:20:17.146768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee91cef4f0023c4 is starting a new election at term 1"} {"level":"info","ts":"2026-09-11T17:20:17.146829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee91cef4f0023c4 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-11T17:20:17.146866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee91cef4f0023c4 received MsgPreVoteResp from 1ee91cef4f0023c4 at term 1"} {"level":"info","ts":"2026-09-11T17:20:17.146885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee91cef4f0023c4 became candidate at term 2"} {"level":"info","ts":"2026-09-11T17:20:17.146901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee91cef4f0023c4 received MsgVoteResp from 1ee91cef4f0023c4 at term 2"} {"level":"info","ts":"2026-09-11T17:20:17.146921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee91cef4f0023c4 became leader at term 2"} {"level":"info","ts":"2026-09-11T17:20:17.146939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1ee91cef4f0023c4 elected leader 1ee91cef4f0023c4 at term 2"} {"level":"info","ts":"2026-09-11T17:20:17.152084Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1ee91cef4f0023c4","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.109:2379]}","request-path":"/0/members/1ee91cef4f0023c4/attributes","cluster-id":"8dccb05df97999f","publish-timeout":"7s"} {"level":"info","ts":"2026-09-11T17:20:17.152101Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-11T17:20:17.152157Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-11T17:20:17.152808Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-11T17:20:17.154383Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-11T17:20:17.154866Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8dccb05df97999f","local-member-id":"1ee91cef4f0023c4","cluster-version":"3.5"} {"level":"info","ts":"2026-09-11T17:20:17.159036Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-11T17:20:17.159134Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-11T17:20:17.155964Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.109:2379"} {"level":"info","ts":"2026-09-11T17:20:17.158771Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-11T17:20:17.15919Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-09-11T17:20:20.63302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.225798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-11T17:20:20.633164Z","caller":"traceutil/trace.go:171","msg":"trace[1334163539] transaction","detail":"{read_only:false; response_revision:139; number_of_response:1; }","duration":"357.097929ms","start":"2026-09-11T17:20:20.276043Z","end":"2026-09-11T17:20:20.633141Z","steps":["trace[1334163539] 'process raft request' (duration: 41.077831ms)","trace[1334163539] 'compare' (duration: 314.972036ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T17:20:20.633257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:20:20.276029Z","time spent":"357.199654ms","remote":"127.0.0.1:55368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":768,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-11T17:20:21.018664Z","caller":"traceutil/trace.go:171","msg":"trace[554844644] linearizableReadLoop","detail":"{readStateIndex:144; appliedIndex:143; }","duration":"158.575247ms","start":"2026-09-11T17:20:20.860063Z","end":"2026-09-11T17:20:21.018639Z","steps":["trace[554844644] 'read index received' (duration: 158.306604ms)","trace[554844644] 'applied index is now lower than readState.Index' (duration: 267.823µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:20:21.01882Z","caller":"traceutil/trace.go:171","msg":"trace[2110578361] transaction","detail":"{read_only:false; response_revision:140; number_of_response:1; }","duration":"378.439729ms","start":"2026-09-11T17:20:20.64034Z","end":"2026-09-11T17:20:21.01878Z","steps":["trace[2110578361] 'process raft request' (duration: 378.145765ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:20:21.018876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.791348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T17:20:21.018923Z","caller":"traceutil/trace.go:171","msg":"trace[1393456715] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:140; }","duration":"158.895263ms","start":"2026-09-11T17:20:20.860008Z","end":"2026-09-11T17:20:21.018903Z","steps":["trace[1393456715] 'agreement among raft nodes before linearized reading' (duration: 158.767047ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:20:21.018979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:20:20.640311Z","time spent":"378.594606ms","remote":"127.0.0.1:55368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":684,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-11T17:20:53.364259Z","caller":"traceutil/trace.go:171","msg":"trace[2054719911] linearizableReadLoop","detail":"{readStateIndex:461; appliedIndex:460; }","duration":"152.977368ms","start":"2026-09-11T17:20:53.211242Z","end":"2026-09-11T17:20:53.364219Z","steps":["trace[2054719911] 'read index received' (duration: 152.714161ms)","trace[2054719911] 'applied index is now lower than readState.Index' (duration: 262.237µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:20:53.36436Z","caller":"traceutil/trace.go:171","msg":"trace[1988561818] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"164.10546ms","start":"2026-09-11T17:20:53.200224Z","end":"2026-09-11T17:20:53.36433Z","steps":["trace[1988561818] 'process raft request' (duration: 163.736991ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:20:53.364491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.211254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-09-11T17:20:53.364563Z","caller":"traceutil/trace.go:171","msg":"trace[320619687] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:442; }","duration":"153.315386ms","start":"2026-09-11T17:20:53.211224Z","end":"2026-09-11T17:20:53.364539Z","steps":["trace[320619687] 'agreement among raft nodes before linearized reading' (duration: 153.168613ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:21:03.012101Z","caller":"traceutil/trace.go:171","msg":"trace[1887844803] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"329.198205ms","start":"2026-09-11T17:21:02.682822Z","end":"2026-09-11T17:21:03.01202Z","steps":["trace[1887844803] 'process raft request' (duration: 328.916038ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:21:03.012416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:21:02.682807Z","time spent":"329.533745ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-11T17:21:04.102485Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2577361433473543493,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-11T17:21:04.109073Z","caller":"traceutil/trace.go:171","msg":"trace[865891948] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"507.062444ms","start":"2026-09-11T17:21:03.601975Z","end":"2026-09-11T17:21:04.109038Z","steps":["trace[865891948] 'read index received' (duration: 506.19621ms)","trace[865891948] 'applied index is now lower than readState.Index' (duration: 864.764µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:21:04.109236Z","caller":"traceutil/trace.go:171","msg":"trace[754011943] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"599.571157ms","start":"2026-09-11T17:21:03.509624Z","end":"2026-09-11T17:21:04.109195Z","steps":["trace[754011943] 'process raft request' (duration: 598.829156ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:21:04.109378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.392742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-11T17:21:04.109428Z","caller":"traceutil/trace.go:171","msg":"trace[326783921] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:586; }","duration":"507.497005ms","start":"2026-09-11T17:21:03.601914Z","end":"2026-09-11T17:21:04.109411Z","steps":["trace[326783921] 'agreement among raft nodes before linearized reading' (duration: 507.338541ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:21:04.109427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.011877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T17:21:04.109554Z","caller":"traceutil/trace.go:171","msg":"trace[624658150] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"152.193362ms","start":"2026-09-11T17:21:03.957331Z","end":"2026-09-11T17:21:04.109524Z","steps":["trace[624658150] 'agreement among raft nodes before linearized reading' (duration: 152.002487ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:21:04.109621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:21:03.509589Z","time spent":"599.728281ms","remote":"127.0.0.1:55110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-11T17:21:04.109467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:21:03.601882Z","time spent":"507.576558ms","remote":"127.0.0.1:55278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-11T17:21:05.865282Z","caller":"traceutil/trace.go:171","msg":"trace[730082883] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"117.263635ms","start":"2026-09-11T17:21:05.747982Z","end":"2026-09-11T17:21:05.865245Z","steps":["trace[730082883] 'process raft request' (duration: 117.066489ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:21:05.867231Z","caller":"traceutil/trace.go:171","msg":"trace[94810525] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"118.032026ms","start":"2026-09-11T17:21:05.749169Z","end":"2026-09-11T17:21:05.867201Z","steps":["trace[94810525] 'process raft request' (duration: 117.858701ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:21:20.593389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.627509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T17:21:20.59349Z","caller":"traceutil/trace.go:171","msg":"trace[1751788946] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:755; }","duration":"190.741963ms","start":"2026-09-11T17:21:20.402726Z","end":"2026-09-11T17:21:20.593468Z","steps":["trace[1751788946] 'count revisions from in-memory index tree' (duration: 190.497836ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:21:20.593866Z","caller":"traceutil/trace.go:171","msg":"trace[1392925083] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"184.510511ms","start":"2026-09-11T17:21:20.409328Z","end":"2026-09-11T17:21:20.593838Z","steps":["trace[1392925083] 'process raft request' (duration: 138.907083ms)","trace[1392925083] 'compare' (duration: 45.096402ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:21:51.822632Z","caller":"traceutil/trace.go:171","msg":"trace[374382327] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"526.45716ms","start":"2026-09-11T17:21:51.296144Z","end":"2026-09-11T17:21:51.822601Z","steps":["trace[374382327] 'process raft request' (duration: 526.284453ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:21:51.82287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:21:51.29612Z","time spent":"526.642507ms","remote":"127.0.0.1:55278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T17:21:51.840283Z","caller":"traceutil/trace.go:171","msg":"trace[2060179024] linearizableReadLoop","detail":"{readStateIndex:1204; appliedIndex:1202; }","duration":"276.350662ms","start":"2026-09-11T17:21:51.563889Z","end":"2026-09-11T17:21:51.84024Z","steps":["trace[2060179024] 'read index received' (duration: 258.489893ms)","trace[2060179024] 'applied index is now lower than readState.Index' (duration: 17.859219ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:21:51.840457Z","caller":"traceutil/trace.go:171","msg":"trace[930260970] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"383.173889ms","start":"2026-09-11T17:21:51.45724Z","end":"2026-09-11T17:21:51.840414Z","steps":["trace[930260970] 'process raft request' (duration: 382.334996ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:21:51.84062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.703035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-11T17:21:51.840674Z","caller":"traceutil/trace.go:171","msg":"trace[1304173708] range","detail":"{range_begin:/registry/masterleases/162.253.55.109; range_end:; response_count:1; response_revision:1165; }","duration":"276.819519ms","start":"2026-09-11T17:21:51.563838Z","end":"2026-09-11T17:21:51.840658Z","steps":["trace[1304173708] 'agreement among raft nodes before linearized reading' (duration: 276.606161ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:21:51.840667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:21:51.457213Z","time spent":"383.344435ms","remote":"127.0.0.1:55278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-11T17:21:51.84082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.917542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-09-11T17:21:51.840937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.952913ms","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-09-11T17:21:51.84107Z","caller":"traceutil/trace.go:171","msg":"trace[1500143192] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1165; }","duration":"134.12661ms","start":"2026-09-11T17:21:51.7069Z","end":"2026-09-11T17:21:51.841027Z","steps":["trace[1500143192] 'agreement among raft nodes before linearized reading' (duration: 133.807128ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:21:51.840951Z","caller":"traceutil/trace.go:171","msg":"trace[2031359665] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1165; }","duration":"134.097758ms","start":"2026-09-11T17:21:51.706832Z","end":"2026-09-11T17:21:51.84093Z","steps":["trace[2031359665] 'agreement among raft nodes before linearized reading' (duration: 133.756545ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:22:08.65334Z","caller":"traceutil/trace.go:171","msg":"trace[179099734] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"140.251397ms","start":"2026-09-11T17:22:08.513045Z","end":"2026-09-11T17:22:08.653297Z","steps":["trace[179099734] 'process raft request' (duration: 140.065939ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:22:14.152742Z","caller":"traceutil/trace.go:171","msg":"trace[1141091143] transaction","detail":"{read_only:false; response_revision:1427; number_of_response:1; }","duration":"117.304578ms","start":"2026-09-11T17:22:14.035411Z","end":"2026-09-11T17:22:14.152715Z","steps":["trace[1141091143] 'process raft request' (duration: 99.719587ms)","trace[1141091143] 'compare' (duration: 17.434855ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:22:41.443517Z","caller":"traceutil/trace.go:171","msg":"trace[1901692344] linearizableReadLoop","detail":"{readStateIndex:1925; appliedIndex:1924; }","duration":"251.311464ms","start":"2026-09-11T17:22:41.192175Z","end":"2026-09-11T17:22:41.443487Z","steps":["trace[1901692344] 'read index received' (duration: 250.952489ms)","trace[1901692344] 'applied index is now lower than readState.Index' (duration: 358.035µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:22:41.443574Z","caller":"traceutil/trace.go:171","msg":"trace[1592838684] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"319.14836ms","start":"2026-09-11T17:22:41.124404Z","end":"2026-09-11T17:22:41.443553Z","steps":["trace[1592838684] 'process raft request' (duration: 318.883448ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:22:41.443736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.563795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-09-11T17:22:41.443727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:22:41.124378Z","time spent":"319.262297ms","remote":"127.0.0.1:55200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T17:22:41.443771Z","caller":"traceutil/trace.go:171","msg":"trace[1121833615] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1849; }","duration":"251.643599ms","start":"2026-09-11T17:22:41.192118Z","end":"2026-09-11T17:22:41.443762Z","steps":["trace[1121833615] 'agreement among raft nodes before linearized reading' (duration: 251.501702ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:22:41.670781Z","caller":"traceutil/trace.go:171","msg":"trace[1285591571] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"209.814908ms","start":"2026-09-11T17:22:41.460938Z","end":"2026-09-11T17:22:41.670753Z","steps":["trace[1285591571] 'process raft request' (duration: 209.565027ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:22:41.671089Z","caller":"traceutil/trace.go:171","msg":"trace[76036701] linearizableReadLoop","detail":"{readStateIndex:1926; appliedIndex:1925; }","duration":"188.624565ms","start":"2026-09-11T17:22:41.48245Z","end":"2026-09-11T17:22:41.671074Z","steps":["trace[76036701] 'read index received' (duration: 187.961905ms)","trace[76036701] 'applied index is now lower than readState.Index' (duration: 660.479µs)"],"step_count":2} {"level":"warn","ts":"2026-09-11T17:22:41.671278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.787872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T17:22:41.671376Z","caller":"traceutil/trace.go:171","msg":"trace[1249051282] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1850; }","duration":"188.942729ms","start":"2026-09-11T17:22:41.482411Z","end":"2026-09-11T17:22:41.671354Z","steps":["trace[1249051282] 'agreement among raft nodes before linearized reading' (duration: 188.75026ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:22:41.671872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.560551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-11T17:22:41.671925Z","caller":"traceutil/trace.go:171","msg":"trace[1874365708] range","detail":"{range_begin:/registry/masterleases/162.253.55.109; range_end:; response_count:1; response_revision:1850; }","duration":"106.658897ms","start":"2026-09-11T17:22:41.565253Z","end":"2026-09-11T17:22:41.671912Z","steps":["trace[1874365708] 'agreement among raft nodes before linearized reading' (duration: 106.015578ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:22:41.840483Z","caller":"traceutil/trace.go:171","msg":"trace[1132026995] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"158.138462ms","start":"2026-09-11T17:22:41.68232Z","end":"2026-09-11T17:22:41.840458Z","steps":["trace[1132026995] 'process raft request' (duration: 158.070309ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:22:41.840986Z","caller":"traceutil/trace.go:171","msg":"trace[1894578657] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"163.196035ms","start":"2026-09-11T17:22:41.677746Z","end":"2026-09-11T17:22:41.840942Z","steps":["trace[1894578657] 'process raft request' (duration: 162.485464ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:22:41.841181Z","caller":"traceutil/trace.go:171","msg":"trace[1354418347] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"162.510415ms","start":"2026-09-11T17:22:41.678626Z","end":"2026-09-11T17:22:41.841136Z","steps":["trace[1354418347] 'process raft request' (duration: 161.675799ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:22:41.841393Z","caller":"traceutil/trace.go:171","msg":"trace[1728074922] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"162.550928ms","start":"2026-09-11T17:22:41.678831Z","end":"2026-09-11T17:22:41.841382Z","steps":["trace[1728074922] 'process raft request' (duration: 161.508322ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:22:41.841618Z","caller":"traceutil/trace.go:171","msg":"trace[1626803713] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"164.095956ms","start":"2026-09-11T17:22:41.677309Z","end":"2026-09-11T17:22:41.841405Z","steps":["trace[1626803713] 'process raft request' (duration: 93.154472ms)","trace[1626803713] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/endpointslices/openstack/percona-xtradb-haproxy-replicas-r9mjb; req_size:1469; } (duration: 69.534432ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:22:54.585952Z","caller":"traceutil/trace.go:171","msg":"trace[599726508] linearizableReadLoop","detail":"{readStateIndex:2039; appliedIndex:2038; }","duration":"145.902316ms","start":"2026-09-11T17:22:54.440031Z","end":"2026-09-11T17:22:54.585933Z","steps":["trace[599726508] 'read index received' (duration: 145.768631ms)","trace[599726508] 'applied index is now lower than readState.Index' (duration: 132.625µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:22:54.586076Z","caller":"traceutil/trace.go:171","msg":"trace[1062287675] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"191.615639ms","start":"2026-09-11T17:22:54.394367Z","end":"2026-09-11T17:22:54.585982Z","steps":["trace[1062287675] 'process raft request' (duration: 191.436171ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:22:54.586393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.182019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11986"} {"level":"info","ts":"2026-09-11T17:22:54.586481Z","caller":"traceutil/trace.go:171","msg":"trace[1243429104] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1960; }","duration":"146.466443ms","start":"2026-09-11T17:22:54.439997Z","end":"2026-09-11T17:22:54.586464Z","steps":["trace[1243429104] 'agreement among raft nodes before linearized reading' (duration: 146.039224ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:22:54.941841Z","caller":"traceutil/trace.go:171","msg":"trace[1348120353] linearizableReadLoop","detail":"{readStateIndex:2040; appliedIndex:2039; }","duration":"355.815087ms","start":"2026-09-11T17:22:54.586009Z","end":"2026-09-11T17:22:54.941824Z","steps":["trace[1348120353] 'read index received' (duration: 311.78021ms)","trace[1348120353] 'applied index is now lower than readState.Index' (duration: 44.034147ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:22:54.941871Z","caller":"traceutil/trace.go:171","msg":"trace[1326422977] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"485.209533ms","start":"2026-09-11T17:22:54.456631Z","end":"2026-09-11T17:22:54.94184Z","steps":["trace[1326422977] 'process raft request' (duration: 441.082823ms)","trace[1326422977] 'compare' (duration: 43.990264ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T17:22:54.942017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:22:54.45661Z","time spent":"485.319138ms","remote":"127.0.0.1:55278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T17:22:54.942086Z","caller":"traceutil/trace.go:171","msg":"trace[573022801] transaction","detail":"{read_only:false; response_revision:1962; number_of_response:1; }","duration":"242.436619ms","start":"2026-09-11T17:22:54.699625Z","end":"2026-09-11T17:22:54.942062Z","steps":["trace[573022801] 'process raft request' (duration: 242.167777ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:22:54.942179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.397351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-09-11T17:22:54.942254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.22211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T17:22:54.942345Z","caller":"traceutil/trace.go:171","msg":"trace[435468050] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:1963; }","duration":"349.346806ms","start":"2026-09-11T17:22:54.592981Z","end":"2026-09-11T17:22:54.942328Z","steps":["trace[435468050] 'agreement among raft nodes before linearized reading' (duration: 349.196469ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:22:54.942248Z","caller":"traceutil/trace.go:171","msg":"trace[1604768128] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1962; }","duration":"405.506085ms","start":"2026-09-11T17:22:54.536722Z","end":"2026-09-11T17:22:54.942228Z","steps":["trace[1604768128] 'agreement among raft nodes before linearized reading' (duration: 405.201052ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:22:54.942389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:22:54.592958Z","time spent":"349.418369ms","remote":"127.0.0.1:55454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-09-11T17:22:54.94241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:22:54.536708Z","time spent":"405.687184ms","remote":"127.0.0.1:33164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-09-11T17:22:54.942571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.022521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T17:22:54.94261Z","caller":"traceutil/trace.go:171","msg":"trace[2091212519] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1963; }","duration":"115.097985ms","start":"2026-09-11T17:22:54.827498Z","end":"2026-09-11T17:22:54.942596Z","steps":["trace[2091212519] 'agreement among raft nodes before linearized reading' (duration: 115.040892ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:23:10.793521Z","caller":"traceutil/trace.go:171","msg":"trace[661411698] transaction","detail":"{read_only:false; response_revision:2066; number_of_response:1; }","duration":"131.595175ms","start":"2026-09-11T17:23:10.661903Z","end":"2026-09-11T17:23:10.793499Z","steps":["trace[661411698] 'process raft request' (duration: 131.445298ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:23:16.672095Z","caller":"traceutil/trace.go:171","msg":"trace[797630868] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"270.415931ms","start":"2026-09-11T17:23:16.401658Z","end":"2026-09-11T17:23:16.672074Z","steps":["trace[797630868] 'process raft request' (duration: 270.272204ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:23:16.786984Z","caller":"traceutil/trace.go:171","msg":"trace[1670506729] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"385.251398ms","start":"2026-09-11T17:23:16.401685Z","end":"2026-09-11T17:23:16.786936Z","steps":["trace[1670506729] 'process raft request' (duration: 303.27001ms)","trace[1670506729] 'compare' (duration: 81.877774ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T17:23:16.78722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:23:16.401659Z","time spent":"385.442938ms","remote":"127.0.0.1:55278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T17:23:17.360453Z","caller":"traceutil/trace.go:171","msg":"trace[1055819584] linearizableReadLoop","detail":"{readStateIndex:2186; appliedIndex:2185; }","duration":"244.465206ms","start":"2026-09-11T17:23:17.115958Z","end":"2026-09-11T17:23:17.360423Z","steps":["trace[1055819584] 'read index received' (duration: 244.281068ms)","trace[1055819584] 'applied index is now lower than readState.Index' (duration: 182.868µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:23:17.360524Z","caller":"traceutil/trace.go:171","msg":"trace[1134332805] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"280.203431ms","start":"2026-09-11T17:23:17.080271Z","end":"2026-09-11T17:23:17.360474Z","steps":["trace[1134332805] 'process raft request' (duration: 279.96137ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:23:17.360808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.825853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-11T17:23:17.360988Z","caller":"traceutil/trace.go:171","msg":"trace[1700756226] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2102; }","duration":"244.946869ms","start":"2026-09-11T17:23:17.115913Z","end":"2026-09-11T17:23:17.36086Z","steps":["trace[1700756226] 'agreement among raft nodes before linearized reading' (duration: 244.640694ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:23:17.372888Z","caller":"traceutil/trace.go:171","msg":"trace[1596296940] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"125.274213ms","start":"2026-09-11T17:23:17.247589Z","end":"2026-09-11T17:23:17.372864Z","steps":["trace[1596296940] 'process raft request' (duration: 125.052023ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:23:17.373107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.767647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-11T17:23:17.373221Z","caller":"traceutil/trace.go:171","msg":"trace[1002344583] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2103; }","duration":"240.917184ms","start":"2026-09-11T17:23:17.132282Z","end":"2026-09-11T17:23:17.3732Z","steps":["trace[1002344583] 'agreement among raft nodes before linearized reading' (duration: 240.516996ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:23:18.395077Z","caller":"traceutil/trace.go:171","msg":"trace[2070157891] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"136.739017ms","start":"2026-09-11T17:23:18.2583Z","end":"2026-09-11T17:23:18.395039Z","steps":["trace[2070157891] 'process raft request' (duration: 136.532857ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:24:57.934555Z","caller":"traceutil/trace.go:171","msg":"trace[363371808] linearizableReadLoop","detail":"{readStateIndex:2804; appliedIndex:2803; }","duration":"125.024754ms","start":"2026-09-11T17:24:57.809504Z","end":"2026-09-11T17:24:57.934529Z","steps":["trace[363371808] 'read index received' (duration: 124.870035ms)","trace[363371808] 'applied index is now lower than readState.Index' (duration: 154.239µs)"],"step_count":2} {"level":"warn","ts":"2026-09-11T17:24:57.934807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.292714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-11T17:24:57.934832Z","caller":"traceutil/trace.go:171","msg":"trace[2070517066] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2697; }","duration":"125.375654ms","start":"2026-09-11T17:24:57.809447Z","end":"2026-09-11T17:24:57.934823Z","steps":["trace[2070517066] 'agreement among raft nodes before linearized reading' (duration: 125.181974ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:24:57.934747Z","caller":"traceutil/trace.go:171","msg":"trace[1351423459] transaction","detail":"{read_only:false; response_revision:2697; number_of_response:1; }","duration":"125.513963ms","start":"2026-09-11T17:24:57.809115Z","end":"2026-09-11T17:24:57.934629Z","steps":["trace[1351423459] 'process raft request' (duration: 125.306634ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:25:34.504176Z","caller":"traceutil/trace.go:171","msg":"trace[1588270936] linearizableReadLoop","detail":"{readStateIndex:3047; appliedIndex:3046; }","duration":"297.019768ms","start":"2026-09-11T17:25:34.207124Z","end":"2026-09-11T17:25:34.504144Z","steps":["trace[1588270936] 'read index received' (duration: 296.811456ms)","trace[1588270936] 'applied index is now lower than readState.Index' (duration: 207.222µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:25:34.504246Z","caller":"traceutil/trace.go:171","msg":"trace[1637363579] transaction","detail":"{read_only:false; response_revision:2933; number_of_response:1; }","duration":"362.528951ms","start":"2026-09-11T17:25:34.141696Z","end":"2026-09-11T17:25:34.504225Z","steps":["trace[1637363579] 'process raft request' (duration: 362.297189ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:25:34.504347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:25:34.141671Z","time spent":"362.617041ms","remote":"127.0.0.1:55278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-11T17:25:34.504482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.158915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-11T17:25:34.504487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.29736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-11T17:25:34.504511Z","caller":"traceutil/trace.go:171","msg":"trace[1482607056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2933; }","duration":"228.250826ms","start":"2026-09-11T17:25:34.276252Z","end":"2026-09-11T17:25:34.504503Z","steps":["trace[1482607056] 'agreement among raft nodes before linearized reading' (duration: 228.173595ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:25:34.504585Z","caller":"traceutil/trace.go:171","msg":"trace[1931232796] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2933; }","duration":"297.457702ms","start":"2026-09-11T17:25:34.207056Z","end":"2026-09-11T17:25:34.504514Z","steps":["trace[1931232796] 'agreement among raft nodes before linearized reading' (duration: 297.253ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:25:40.526247Z","caller":"traceutil/trace.go:171","msg":"trace[1944678085] linearizableReadLoop","detail":"{readStateIndex:3080; appliedIndex:3079; }","duration":"165.126466ms","start":"2026-09-11T17:25:40.361097Z","end":"2026-09-11T17:25:40.526223Z","steps":["trace[1944678085] 'read index received' (duration: 164.960305ms)","trace[1944678085] 'applied index is now lower than readState.Index' (duration: 165.191µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:25:40.526288Z","caller":"traceutil/trace.go:171","msg":"trace[1562833211] transaction","detail":"{read_only:false; response_revision:2964; number_of_response:1; }","duration":"323.500638ms","start":"2026-09-11T17:25:40.202759Z","end":"2026-09-11T17:25:40.526259Z","steps":["trace[1562833211] 'process raft request' (duration: 323.297996ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:25:40.52641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.296158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T17:25:40.52645Z","caller":"traceutil/trace.go:171","msg":"trace[1184188865] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:2964; }","duration":"165.392149ms","start":"2026-09-11T17:25:40.361048Z","end":"2026-09-11T17:25:40.52644Z","steps":["trace[1184188865] 'agreement among raft nodes before linearized reading' (duration: 165.285768ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:25:40.526476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:25:40.202724Z","time spent":"323.619399ms","remote":"127.0.0.1:55090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":764,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-11T17:25:40.561372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.588392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T17:25:40.561523Z","caller":"traceutil/trace.go:171","msg":"trace[1662155613] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2965; }","duration":"145.790753ms","start":"2026-09-11T17:25:40.415719Z","end":"2026-09-11T17:25:40.56151Z","steps":["trace[1662155613] 'agreement among raft nodes before linearized reading' (duration: 145.619412ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:25:58.84997Z","caller":"traceutil/trace.go:171","msg":"trace[1566313731] transaction","detail":"{read_only:false; response_revision:3062; number_of_response:1; }","duration":"110.889747ms","start":"2026-09-11T17:25:58.739034Z","end":"2026-09-11T17:25:58.849924Z","steps":["trace[1566313731] 'process raft request' (duration: 24.768455ms)","trace[1566313731] 'compare' (duration: 86.01488ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T17:30:05.36559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.924038ms","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-09-11T17:30:05.365702Z","caller":"traceutil/trace.go:171","msg":"trace[1794725513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5019; }","duration":"163.071484ms","start":"2026-09-11T17:30:05.202607Z","end":"2026-09-11T17:30:05.365678Z","steps":["trace[1794725513] 'range keys from in-memory index tree' (duration: 162.769482ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:30:10.403036Z","caller":"traceutil/trace.go:171","msg":"trace[1102387638] transaction","detail":"{read_only:false; response_revision:5133; number_of_response:1; }","duration":"242.463442ms","start":"2026-09-11T17:30:10.160548Z","end":"2026-09-11T17:30:10.403011Z","steps":["trace[1102387638] 'process raft request' (duration: 242.249413ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:30:10.419452Z","caller":"traceutil/trace.go:171","msg":"trace[880854215] transaction","detail":"{read_only:false; response_revision:5134; number_of_response:1; }","duration":"208.559163ms","start":"2026-09-11T17:30:10.210864Z","end":"2026-09-11T17:30:10.419424Z","steps":["trace[880854215] 'process raft request' (duration: 208.389196ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:30:10.985936Z","caller":"traceutil/trace.go:171","msg":"trace[998529325] transaction","detail":"{read_only:false; response_revision:5143; number_of_response:1; }","duration":"101.398961ms","start":"2026-09-11T17:30:10.884504Z","end":"2026-09-11T17:30:10.985903Z","steps":["trace[998529325] 'process raft request' (duration: 95.831386ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:30:17.448607Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2842} {"level":"info","ts":"2026-09-11T17:30:17.548826Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2842,"took":"98.826851ms","hash":2367336029,"current-db-size-bytes":51838976,"current-db-size":"52 MB","current-db-size-in-use-bytes":27222016,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-11T17:30:17.548887Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2367336029,"revision":2842,"compact-revision":-1} {"level":"info","ts":"2026-09-11T17:30:24.835856Z","caller":"traceutil/trace.go:171","msg":"trace[1658227773] transaction","detail":"{read_only:false; response_revision:5335; number_of_response:1; }","duration":"295.853606ms","start":"2026-09-11T17:30:24.53997Z","end":"2026-09-11T17:30:24.835823Z","steps":["trace[1658227773] 'process raft request' (duration: 290.012687ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:30:24.838922Z","caller":"traceutil/trace.go:171","msg":"trace[1829046047] transaction","detail":"{read_only:false; response_revision:5336; number_of_response:1; }","duration":"298.476107ms","start":"2026-09-11T17:30:24.540417Z","end":"2026-09-11T17:30:24.838893Z","steps":["trace[1829046047] 'process raft request' (duration: 298.194817ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:30:24.839111Z","caller":"traceutil/trace.go:171","msg":"trace[1062022418] transaction","detail":"{read_only:false; response_revision:5337; number_of_response:1; }","duration":"288.563502ms","start":"2026-09-11T17:30:24.550528Z","end":"2026-09-11T17:30:24.839092Z","steps":["trace[1062022418] 'process raft request' (duration: 288.278951ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:30:24.839502Z","caller":"traceutil/trace.go:171","msg":"trace[1940463530] linearizableReadLoop","detail":"{readStateIndex:5546; appliedIndex:5543; }","duration":"267.19865ms","start":"2026-09-11T17:30:24.572286Z","end":"2026-09-11T17:30:24.839484Z","steps":["trace[1940463530] 'read index received' (duration: 257.7126ms)","trace[1940463530] 'applied index is now lower than readState.Index' (duration: 9.48477ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:30:24.839597Z","caller":"traceutil/trace.go:171","msg":"trace[1069281998] transaction","detail":"{read_only:false; response_revision:5338; number_of_response:1; }","duration":"125.948377ms","start":"2026-09-11T17:30:24.713636Z","end":"2026-09-11T17:30:24.839584Z","steps":["trace[1069281998] 'process raft request' (duration: 125.395226ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:30:24.839921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.620147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"warn","ts":"2026-09-11T17:30:24.839921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.177271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-11T17:30:24.839976Z","caller":"traceutil/trace.go:171","msg":"trace[927770768] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5338; }","duration":"246.265124ms","start":"2026-09-11T17:30:24.593697Z","end":"2026-09-11T17:30:24.839963Z","steps":["trace[927770768] 'agreement among raft nodes before linearized reading' (duration: 246.091527ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:30:24.839977Z","caller":"traceutil/trace.go:171","msg":"trace[503528980] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5338; }","duration":"267.720701ms","start":"2026-09-11T17:30:24.572235Z","end":"2026-09-11T17:30:24.839955Z","steps":["trace[503528980] 'agreement among raft nodes before linearized reading' (duration: 267.527203ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:30:25.117682Z","caller":"traceutil/trace.go:171","msg":"trace[599143378] transaction","detail":"{read_only:false; response_revision:5339; number_of_response:1; }","duration":"246.587719ms","start":"2026-09-11T17:30:24.871042Z","end":"2026-09-11T17:30:25.11763Z","steps":["trace[599143378] 'process raft request' (duration: 235.897742ms)","trace[599143378] 'compare' (duration: 10.210359ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:30:25.124405Z","caller":"traceutil/trace.go:171","msg":"trace[129849002] linearizableReadLoop","detail":"{readStateIndex:5549; appliedIndex:5547; }","duration":"189.146782ms","start":"2026-09-11T17:30:24.935231Z","end":"2026-09-11T17:30:25.124377Z","steps":["trace[129849002] 'read index received' (duration: 171.822467ms)","trace[129849002] 'applied index is now lower than readState.Index' (duration: 17.323125ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:30:25.124827Z","caller":"traceutil/trace.go:171","msg":"trace[597119043] transaction","detail":"{read_only:false; response_revision:5340; number_of_response:1; }","duration":"210.531735ms","start":"2026-09-11T17:30:24.91426Z","end":"2026-09-11T17:30:25.124791Z","steps":["trace[597119043] 'process raft request' (duration: 208.775867ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:30:25.124877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.167596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T17:30:25.125024Z","caller":"traceutil/trace.go:171","msg":"trace[1204940011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5341; }","duration":"167.386134ms","start":"2026-09-11T17:30:24.957609Z","end":"2026-09-11T17:30:25.124995Z","steps":["trace[1204940011] 'agreement among raft nodes before linearized reading' (duration: 167.119164ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:30:25.126132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.873289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T17:30:25.126211Z","caller":"traceutil/trace.go:171","msg":"trace[1337775812] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5341; }","duration":"191.021695ms","start":"2026-09-11T17:30:24.935169Z","end":"2026-09-11T17:30:25.126191Z","steps":["trace[1337775812] 'agreement among raft nodes before linearized reading' (duration: 189.382101ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:30:25.440426Z","caller":"traceutil/trace.go:171","msg":"trace[1814334294] linearizableReadLoop","detail":"{readStateIndex:5551; appliedIndex:5550; }","duration":"291.806455ms","start":"2026-09-11T17:30:25.148594Z","end":"2026-09-11T17:30:25.4404Z","steps":["trace[1814334294] 'read index received' (duration: 291.629999ms)","trace[1814334294] 'applied index is now lower than readState.Index' (duration: 175.466µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T17:30:25.440543Z","caller":"traceutil/trace.go:171","msg":"trace[1722321611] transaction","detail":"{read_only:false; response_revision:5342; number_of_response:1; }","duration":"292.243192ms","start":"2026-09-11T17:30:25.148286Z","end":"2026-09-11T17:30:25.44053Z","steps":["trace[1722321611] 'process raft request' (duration: 291.92966ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:30:25.440785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.18096ms","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-09-11T17:30:25.440826Z","caller":"traceutil/trace.go:171","msg":"trace[188198607] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5342; }","duration":"292.68939ms","start":"2026-09-11T17:30:25.148127Z","end":"2026-09-11T17:30:25.440816Z","steps":["trace[188198607] 'agreement among raft nodes before linearized reading' (duration: 292.532294ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T17:30:25.509891Z","caller":"traceutil/trace.go:171","msg":"trace[835763198] transaction","detail":"{read_only:false; response_revision:5343; number_of_response:1; }","duration":"194.45305ms","start":"2026-09-11T17:30:25.315401Z","end":"2026-09-11T17:30:25.509854Z","steps":["trace[835763198] 'process raft request' (duration: 151.045688ms)","trace[835763198] 'compare' (duration: 43.215215ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T17:30:25.51005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.009095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-09-11T17:30:25.51013Z","caller":"traceutil/trace.go:171","msg":"trace[1363915896] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5343; }","duration":"360.15399ms","start":"2026-09-11T17:30:25.149955Z","end":"2026-09-11T17:30:25.510109Z","steps":["trace[1363915896] 'agreement among raft nodes before linearized reading' (duration: 359.924231ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T17:30:25.510169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T17:30:25.149928Z","time spent":"360.232103ms","remote":"127.0.0.1:33164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5673,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-09-11T17:35:17.455387Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5271} {"level":"info","ts":"2026-09-11T17:35:17.555995Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5271,"took":"98.926364ms","hash":2066592705,"current-db-size-bytes":51838976,"current-db-size":"52 MB","current-db-size-in-use-bytes":18980864,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-11T17:35:17.556066Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2066592705,"revision":5271,"compact-revision":2842}