Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.01.2023 10:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14934708 kB
MemAvailable 30418828 kB
Buffers 0 kB
Cached 16794876 kB
SwapCached 5580 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.3
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-01-04 10:37:22
DTS_END                         2023-01-04 10:37:22.9670
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/43292
TRN_ID                          221146
ATT_ID                          662
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 8872.34 26116 1064681
customer order: creation 173.85 469 20862
customer order: refuse 14.82 466 1778
order to supplier: creation 45.79 1337 5495
order to supplier: removal 3.20 186962 384
invoice (draft): creation 45.53 3138 5464
invoice (draft): removal 48.38 1166 5805
invoice accept: apply 41.20 29900 4944
invoice accept: cancel 43.67 1461 5240
customer reserve: creation 3878.45 280 465414
customer reserve: removal 4282.88 108 513946
realization accept: apply 84.06 300 10087
realization accept: cancel 81.00 181 9720
payment from customer: creation 57.80 179 6936
payment from customer: removal 6.04 142 725
payment to supplier: creation 49.10 187 5892
payment to supplier: removal 3.63 119 435
service: total inventory turnovers 4.16 4054 499
service: total monetary turnovers 7.93 406 951
service: aggregate perf. data 0.83 15818 100
service: refresh index statistics 0.03 275647 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 04-01-2023 10:39:32 to 04-01-2023 10:39:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8545
TEST_TIME 2 6152
TEST_TIME 3 6334
TEST_TIME 4 6214
TEST_TIME 5 6557
TEST_TIME 6 6754
TEST_TIME 7 6340
TEST_TIME 8 6997
TEST_TIME 9 6620
TEST_TIME 10 6552
TEST_TIME 11 6457
TEST_TIME 12 6490
TEST_TIME 13 6551
TEST_TIME 14 6696
TEST_TIME 15 6673
TEST_TIME 16 6704
TEST_TIME 17 6761
TEST_TIME 18 6712
TEST_TIME 19 6817
TEST_TIME 20 6852
TEST_TIME 21 7133
TEST_TIME 22 7400
TEST_TIME 23 7468
TEST_TIME 24 7522
TEST_TIME 25 7737
TEST_TIME 26 7815
TEST_TIME 27 8032
TEST_TIME 28 8080
TEST_TIME 29 8499
TEST_TIME 30 8723
TEST_TIME 31 8846
TEST_TIME 32 9208
TEST_TIME 33 9514
TEST_TIME 34 9646
TEST_TIME 35 10104
TEST_TIME 36 10257
TEST_TIME 37 10140
TEST_TIME 38 10417
TEST_TIME 39 10648
TEST_TIME 40 10422
TEST_TIME 41 10347
TEST_TIME 42 10211
TEST_TIME 43 9988
TEST_TIME 44 9898
TEST_TIME 45 9933
TEST_TIME 46 9753
TEST_TIME 47 9807
TEST_TIME 48 9745
TEST_TIME 49 9669
TEST_TIME 50 9611
TEST_TIME 51 9682
TEST_TIME 52 9639
TEST_TIME 53 9647
TEST_TIME 54 9632
TEST_TIME 55 9614
TEST_TIME 56 9533
TEST_TIME 57 9455
TEST_TIME 58 9425
TEST_TIME 59 9305
TEST_TIME 60 9224
TEST_TIME 61 9190
TEST_TIME 62 9095
TEST_TIME 63 9148
TEST_TIME 64 9128
TEST_TIME 65 9143
TEST_TIME 66 9074
TEST_TIME 67 9053
TEST_TIME 68 9164
TEST_TIME 69 9099
TEST_TIME 70 9069
TEST_TIME 71 9091
TEST_TIME 72 9161
TEST_TIME 73 9345
TEST_TIME 74 9401
TEST_TIME 75 9357
TEST_TIME 76 9308
TEST_TIME 77 9262
TEST_TIME 78 9188
TEST_TIME 79 9202
TEST_TIME 80 9165
TEST_TIME 81 9130
TEST_TIME 82 9112
TEST_TIME 83 9059
TEST_TIME 84 8995
TEST_TIME 85 8960
TEST_TIME 86 9041
TEST_TIME 87 9064
TEST_TIME 88 9043
TEST_TIME 89 9017
TEST_TIME 90 9000
TEST_TIME 91 9021
TEST_TIME 92 9036
TEST_TIME 93 9017
TEST_TIME 94 9006
TEST_TIME 95 9007
TEST_TIME 96 9034
TEST_TIME 97 9105
TEST_TIME 98 9136
TEST_TIME 99 9123
TEST_TIME 100 9051
TEST_TIME 101 9041
TEST_TIME 102 8998
TEST_TIME 103 9073
TEST_TIME 104 9013
TEST_TIME 105 8985
TEST_TIME 106 8956
TEST_TIME 107 8977
TEST_TIME 108 8942
TEST_TIME 109 8955
TEST_TIME 110 8934
TEST_TIME 111 8947
TEST_TIME 112 8962
TEST_TIME 113 8939
TEST_TIME 114 8953
TEST_TIME 115 8971
TEST_TIME 116 8997
TEST_TIME 117 9006
TEST_TIME 118 8958
TEST_TIME 119 8940
TEST_TIME 120 8899

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-01-2023 10:39:36 to 04-01-2023 10:39:39.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1492134 1492134 0 0.00 0 25082 15 0 0
doc_list_biud 1492125 1492125 0 0.00 0 79255 44 0 0
sp_add_invoice_to_stock 5089 4944 145 2.84 34 272632 29901 0 145
sp_cancel_adding_invoice 5240 5240 0 0.00 6 105392 1461 0 0
sp_cancel_client_order 1778 1778 0 0.00 6 16594 466 0 0
sp_cancel_customer_reserve 513946 513946 0 0.00 1 30707 109 0 0
sp_cancel_pay_from_customer 725 725 0 0.00 4 4574 143 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 4 2606 119 0 0
sp_cancel_supplier_invoice 5822 5805 17 0.29 3 79280 1166 0 17
sp_cancel_supplier_order 384 384 0 0.00 10 1611137 186962 0 0
sp_cancel_write_off 9720 9720 0 0.00 2 9238 181 0 0
sp_client_order 20862 20862 0 0.00 6 29056 469 0 0
sp_customer_reserve 465414 465414 0 0.00 1 154824 281 0 0
sp_fill_shopping_cart 31806 31806 0 0.00 1 10730 80 0 0
sp_fill_shopping_cart_clo_res 465383 465383 0 0.00 0 20836 21 0 0
sp_get_clo_for_invoice 4900 4900 0 0.00 0 37029 302 0 0
sp_kill_cost_storno 16120 16120 0 0.00 0 25072 83 0 0
sp_kill_qstorno_ret_qs2qd 1043801 1043801 0 0.00 0 79250 47 0 0
sp_kill_qty_storno 551858 551858 0 0.00 0 79253 109 0 0
sp_lock_selected_doc 20754 20754 0 0.00 0 9079 9 0 0
sp_make_cost_storno 27814 27814 0 0.00 0 13584 85 0 0
sp_multiply_rows_for_pdistr 27812 27812 0 0.00 0 6269 41 0 0
sp_multiply_rows_for_qdistr 20862 20862 0 0.00 0 16415 177 0 0
sp_pay_from_customer 6936 6936 0 0.00 5 9438 179 0 0
sp_pay_to_supplier 5892 5892 0 0.00 6 10611 188 0 0
sp_payment_common 12826 12826 0 0.00 2 4032 44 0 0
sp_qd_handle_on_cancel_clo 1778 1778 0 0.00 0 16543 385 0 0
sp_qd_handle_on_invoice_upd_sts 10138 10138 0 0.00 0 29982 571 0 0
sp_qd_handle_on_reserve_upd_sts 10087 10087 0 0.00 0 22060 63 0 0
sp_reserve_write_off 10087 10087 0 0.00 8 22088 301 0 0
sp_supplier_invoice 5464 5464 0 0.00 9 101046 3139 0 0
sp_supplier_order 5495 5495 0 0.00 8 29079 1338 0 0
srv_aggregate_perf_data 100 100 0 0.00 2026 130178 15818 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 607 159295 32040 0 0
srv_find_qd_qs_mism 1015013 1015013 0 0.00 0 15612 12 0 0
srv_make_invnt_saldo 499 499 0 0.00 64 66487 4054 0 0
srv_make_money_saldo 951 951 0 0.00 1 17149 406 0 0
srv_recalc_idx_stat 4 4 0 0.00 58876 563447 275647 0 0
t$perf-abend:sp_add_invoice_to_stock 145 145 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4951 4951 0 0.00 0 13348 1055 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 1 6252 541 0 0
t$perf-norm:sp_cancel_client_order 1778 1778 0 0.00 0 1243 29 0 0
t$perf-norm:sp_cancel_customer_reserve 641 641 0 0.00 0 1347 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 725 725 0 0.00 0 695 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 1445 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 3580 53 0 0
t$perf-norm:sp_cancel_supplier_order 384 384 0 0.00 1 98060 8550 0 0
t$perf-norm:sp_cancel_write_off 999 999 0 0.00 0 1431 22 0 0
t$perf-norm:sp_client_order 20863 20863 0 0.00 0 6952 38 0 0
t$perf-norm:sp_customer_reserve 1010 1010 0 0.00 0 1620 17 0 0
t$perf-norm:sp_pay_from_customer 6936 6936 0 0.00 0 3696 19 0 0
t$perf-norm:sp_pay_to_supplier 5892 5892 0 0.00 0 17178 21 0 0
t$perf-norm:sp_reserve_write_off 10087 10087 0 0.00 0 5040 24 0 0
t$perf-norm:sp_supplier_invoice 5464 5464 0 0.00 0 6597 32 0 0
t$perf-norm:sp_supplier_order 5495 5495 0 0.00 0 16167 41 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 638 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 590 15 0 0
t$perf-norm:srv_make_invnt_saldo 499 499 0 0.00 0 691 15 0 0
t$perf-norm:srv_make_money_saldo 951 951 0 0.00 0 1966 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 18 201 71 0 0
v_add_invoice_to_stock 10074 9784 290 2.87 0 6489 31 0 290
v_all_customers 37742 37742 0 0.00 0 187 4 0 0
v_all_suppliers 30758 30758 0 0.00 0 102 4 0 0
v_all_wares 41720 41720 0 0.00 0 59 4 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 5360 22 0 0
v_cancel_client_order 3556 3556 0 0.00 0 3352 13 0 0
v_cancel_customer_prepayment 1450 1450 0 0.00 0 798 13 0 0
v_cancel_customer_reserve 1282 1282 0 0.00 0 1261 12 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 2443 17 0 0
v_cancel_supplier_invoice 816 782 34 4.16 0 6375 36 0 34
v_cancel_supplier_order 768 768 0 0.00 0 768 13 0 0
v_cancel_write_off 1998 1998 0 0.00 0 3259 31 0 0
v_max_id_clo_ord 5494 5494 0 0.00 0 1121 6 0 0
v_max_id_clo_res 1010 1010 0 0.00 0 5002 20 0 0
v_max_id_ord_sup 5450 5450 0 0.00 0 6496 7 0 0
v_max_non_paid_invoice 1455 1455 0 0.00 0 224 8 0 0
v_max_non_paid_realizn 3612 3612 0 0.00 0 2271 9 0 0
v_min_id_clo_ord 5494 5494 0 0.00 0 1691 9 0 0
v_min_id_clo_res 1010 1010 0 0.00 0 1900 11 0 0
v_min_id_ord_sup 5450 5450 0 0.00 0 5929 9 0 0
v_min_non_paid_invoice 5890 5890 0 0.00 0 3620 13 0 0
v_min_non_paid_realizn 6936 6936 0 0.00 0 3065 16 0 0
v_our_firm 10626 10626 0 0.00 0 77 5 0 0
v_reserve_write_off 20174 20174 0 0.00 0 3803 13 0 0
x_lock_depdocs_on_canc_invoice 10654 10654 0 0.00 0 20611 130 0 0
x_lock_depdocs_on_canc_sup_ord 384 384 0 0.00 0 17570 440 0 0
x_make_qstorno_1000_1200 5495 5495 0 0.00 2 29069 1166 0 0
x_make_qstorno_1200_2000 5464 5464 0 0.00 4 99915 2924 0 0
x_make_qstorno_2100_3300 465414 465414 0 0.00 0 154820 246 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-01-2023 10:39:40 to 04-01-2023 10:39:44.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:26 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:28 20988 8192000000 9731397744 10034884608 814970656 9.048 100 84 168 0 842743472 7739168 20033648
08:09:53 22207 8192000000 9773779472 10084511744 848728816 9.387 100 90 180 0 882638624 12414256 21495552
08:11:21 10957 8192000000 9802174000 10121015296 874719840 9.647 100 92 184 0 914950960 19296096 20935024
08:12:37 13150 8192000000 9855591936 10189139968 904778000 9.946 100 79 154 1 962257680 40385600 17094080
08:14:00 7520 8192000000 9905607392 10249732096 948956416 10.381 100 80 153 1 1024966096 59941440 16068240
08:15:10 3467 8192000000 9951700656 10299568128 985442560 10.737 100 92 180 0 1070025392 66204064 18378768
08:16:16 1367 8192000000 10002998416 10358906880 1007105600 10.947 100 92 181 0 1118932576 93884128 17942848
08:17:21 1528 8192000000 10004960896 10371665920 1030976576 11.178 100 93 177 0 1121546240 72506784 18062880
08:18:24 1806 8192000000 10029694048 10404835328 1052650880 11.386 100 91 174 1 1148648544 79139152 16858512
08:19:28 1273 8192000000 10035087520 10416771072 1063667712 11.492 100 93 170 2 1162634880 81283184 17683984
08:20:32 1367 8192000000 10034899616 10418094080 1072223680 11.573 100 85 165 0 1162499456 73982960 16292816
08:21:37 3209 8192000000 10050465728 10433368064 1078252224 11.631 100 83 153 1 1184440752 90363168 15825360
08:22:42 1137 8192000000 10058243664 10438885376 1088944112 11.733 100 95 171 2 1181452224 75362800 17145312
08:23:44 1155 8192000000 10076774144 10464817152 1091129232 11.753 100 96 191 0 1196914064 86373632 19411200
08:24:47 1098 8192000000 10056807488 10444795904 1096514896 11.805 100 95 181 1 1178122448 63265328 18342224
08:25:49 1163 8192000000 10070670704 10462953472 1098044128 11.819 100 84 160 1 1190240448 76687744 15508576
08:26:51 1129 8192000000 10053118560 10443833344 1106464560 11.899 100 91 178 2 1180628672 55702752 18461360
08:27:54 1106 8192000000 10053753904 10438885376 1092793264 11.769 99 90 168 3 1176080224 66445616 16841344
08:28:56 1063 8192000000 10078489216 10467180544 1101224848 11.849 100 98 191 0 1198192064 77728528 19238688
08:29:59 1167 8192000000 10051406992 10442289152 1092170112 11.763 100 85 158 1 1177301760 71029392 14102256
08:31:01 1873 8192000000 10052051776 10435878912 1090223424 11.745 100 96 184 1 1174719056 66305344 18190288
08:32:04 913 8192000000 10041104592 10424086528 1075726544 11.607 100 96 184 0 1164548320 68814128 20007648
08:33:06 1163 8192000000 9985853632 10359050240 1044044976 11.304 100 94 174 2 1109730512 47209120 18476416
08:34:08 977 8192000000 9976383072 10347683840 1035037888 11.217 100 90 176 0 1099482816 46744864 17700064
08:35:10 1388 8192000000 9956246432 10325581824 1005575568 10.933 100 93 174 4 1084832608 60797280 18459760
08:36:13 1522 8192000000 9994366608 10366955520 1035354368 11.220 100 92 173 4 1115148272 64058576 15735328
08:37:15 1088 8192000000 10010183616 10390958080 1050973328 11.370 100 94 171 3 1138776000 71232720 16569952
08:38:23 19207 8192000000 10020584304 10402512896 1056899648 11.427 99 75 135 2 1135664880 65373776 13391456
08:39:50 1154 8192000000 10047060480 10428076032 1078639440 11.635 100 97 180 2 1165728160 67420848 19667872
08:40:56 4499 8192000000 10066923920 10456162304 1093271728 11.774 100 67 128 1 1183874832 77261632 13341472
08:42:04 3773 8192000000 10101190720 10491207680 1104248496 11.878 100 73 141 2 1225465280 107017920 14198864
08:43:13 15751 8192000000 10142751744 10541891584 1116007248 11.989 100 71 138 0 1256085360 125589152 14488960
08:44:33 2619 8192000000 10166689456 10562326528 1128241568 12.105 100 75 142 0 1284134912 140521280 15372064
08:45:41 3256 8192000000 10168092624 10587688960 1140094576 12.216 100 64 125 1 1292457328 137974496 14388256
08:46:49 3724 8192000000 10196989984 10599718912 1147372064 12.285 100 71 141 0 1313209648 148542288 17295296
08:48:16 2407 8192000000 10215642464 10619887616 1156646080 12.372 100 84 158 0 1330121696 155781312 17694304
08:49:23 1179 8192000000 10256582848 10667995136 1169129248 12.489 100 87 164 0 1377156608 189022720 19004640
08:50:28 2018 8192000000 10297491888 10702430208 1168057232 12.479 100 89 176 1 1412584688 223962368 20565088
08:51:35 1225 8192000000 10311853968 10717405184 1166825856 12.467 100 95 180 1 1423301776 235629184 20846736
08:52:41 1268 8192000000 10349168064 10764824576 1179954608 12.590 100 98 193 2 1459365360 258152496 21258256
08:53:45 1076 8192000000 10398529424 10804166656 1184789840 12.635 100 97 193 0 1508516512 301341600 22385072
08:54:51 2151 8192000000 10399144544 10803859456 1184824256 12.635 100 92 180 1 1505860976 300500992 20535728
08:55:59 812 8192000000 10427962384 10854297600 1188569440 12.670 100 94 183 2 1536679104 326790176 21319488
08:57:04 1597 8192000000 10477061120 10895560704 1189436848 12.678 100 95 183 0 1582430512 371040416 21953248
08:58:09 1182 8192000000 10475273040 10926153728 1191115856 12.694 100 95 188 1 1582860688 369239088 22505744
08:59:16 835 8192000000 10476903296 10897264640 1182382784 12.612 100 93 182 0 1584244848 379678416 22183648
09:00:19 1879 8192000000 10492467184 10919727104 1177008080 12.562 100 73 146 1 1599944768 405272384 17664304
09:01:26 1271 8192000000 10467815152 10915241984 1176504880 12.558 100 96 187 2 1577522512 379193280 21824352
09:02:31 1632 8192000000 10517378544 10945294336 1182698032 12.615 100 96 187 1 1621948576 416556496 22694048
09:03:36 3653 8192000000 10517283280 10966614016 1171983216 12.515 100 75 148 1 1620023040 429453280 18586544
09:04:47 757 8192000000 10542734512 10984005632 1172277520 12.518 100 94 188 1 1643641632 447902848 23461264
09:05:52 1651 8192000000 10469310544 10958581760 1168918768 12.487 100 81 148 1 1573638608 386272912 18446928
09:06:55 2758 8192000000 10452659776 10972045312 1172862384 12.524 100 58 115 0 1557578944 369947680 14768880
09:08:09 16211 8192000000 10427760384 10928345088 1172945168 12.524 100 38 77 0 1564307984 381238416 10124400
09:09:28 12838 8192000000 10373903168 10842693632 1158519408 12.389 100 36 72 1 1488277664 320523632 9234624
09:10:58 33237 8192000000 10284804816 10770558976 1126323600 12.087 99 19 37 0 1426049248 294717344 5008304
09:12:41 25893 8192000000 10133647776 10573111296 1128258736 12.105 99 22 44 1 1250238064 117237520 4741808
09:14:11 77066 8192000000 10140860704 10630635520 1095650384 11.796 96 13 26 0 1215319376 116458432 3210560
09:17:00 41071 8192000000 10056275456 10482647040 1108280832 11.916 98 34 65 2 1133513120 17857984 7374304
09:18:42 132355 8192000000 10116527296 10556215296 1108545120 11.919 97 43 84 0 1168213120 50216448 9451552
09:22:10 51202 8192000000 10140667552 10616586240 1134219584 12.161 100 33 65 2 1252876496 111892752 6764160
09:24:05 33221 8192000000 10124783696 10583703552 1138461712 12.201 99 29 55 0 1203387776 59361952 5564112
09:25:39 68409 8192000000 10144181152 10584608768 1118609280 12.014 97 31 59 0 1188301536 63293552 6398704
09:27:50 13494 8192000000 10164439152 10586509312 1146132000 12.273 100 77 144 3 1257958064 95912256 15913808
09:29:05 159294 8192000000 10089744880 10571137024 1133714000 12.156 99 11 22 0 1224266928 87580768 2972160
09:32:45 34192 8192000000 10070138816 10588508160 1132457536 12.145 99 11 23 0 1195568128 60747280 2363312
09:34:21 605 8192000000 10065540688 10494337024 1131953584 12.140 100 79 156 2 1164374912 14834992 17586336
09:35:25 122793 8192000000 10070114816 10538373120 1112891008 11.960 98 20 38 1 1145341728 28670464 3780256
09:38:31 78228 8192000000 10088129600 10569162752 1124014752 12.065 98 12 23 0 1197999616 71777984 2206880
09:41:00 37391 8192000000 10093559376 10559791104 1132802576 12.148 99 23 43 1 1177904304 41491232 3610496
09:42:38 38055 8192000000 10124780784 10574462976 1136089008 12.179 99 15 26 1 1189872656 51110000 2673648
09:44:19 16635 8192000000 10197746944 10633453568 1143273792 12.246 99 39 77 0 1272625248 120752864 8598592
09:45:45 39345 8192000000 10179278768 10625540096 1150048080 12.310 99 27 54 0 1262147600 105937440 6162080
09:47:32 21821 8192000000 10260847680 10716069888 1148175920 12.292 100 53 104 1 1354842240 195105744 11560576
09:49:11 39395 8192000000 10203803888 10650357760 1132393104 12.144 99 18 36 0 1278969248 142513760 4062384
09:51:02 52218 8192000000 10079453072 10510155776 1113810640 11.968 98 29 58 0 1153373696 33225760 6337296
09:52:55 38859 8192000000 10151746576 10602659840 1123981696 12.065 98 53 104 0 1206164816 71454688 10728432
09:55:47 11219 8192000000 10098373824 10547785728 1127390640 12.097 99 60 110 0 1201699072 63359232 10949200
09:57:00 39207 8192000000 10086357616 10541821952 1126955568 12.093 99 27 54 2 1180922256 48156032 5810656
09:58:39 49956 8192000000 10077770240 10568839168 1142140656 12.236 100 13 26 0 1197504544 52772688 2591200
10:00:29 20575 8192000000 10086493488 10585706496 1137902576 12.196 100 4 8 0 1193509072 54733536 872960
10:01:50 106244 8192000000 10149765840 10620755968 1116368608 11.993 97 25 50 0 1221187200 99476400 5342192
10:04:38 86540 8192000000 10055359696 10514120704 1070986336 11.561 94 14 28 0 1109443712 35752416 2704960
10:07:05 7056 8192000000 10082929840 10510626816 1125352528 12.078 99 68 128 1 1175541712 36452336 13736848
10:08:12 20059 8192000000 10082631024 10521882624 1092087456 11.763 97 46 91 0 1162378000 61361568 8928976
10:09:33 28783 8192000000 10095832288 10550661120 1107149616 11.905 99 43 86 0 1170375696 54342816 8883264
10:11:02 8338 8192000000 10094003296 10518568960 1116878720 11.997 99 58 114 1 1174397360 46335072 11183568
10:12:13 54268 8192000000 10130515536 10574176256 1123483712 12.060 99 41 80 1 1214166416 82850704 7832000
10:14:31 70427 8192000000 10092628656 10531520512 1091115792 11.753 97 29 58 0 1176063248 78540112 6407344
10:16:46 6381 8192000000 10105614032 10536103936 1131552432 12.136 100 50 95 1 1196555808 55329744 9673632
10:17:55 106898 8192000000 10171338928 10653880320 1129845008 12.120 100 31 62 0 1278700592 141923248 6932336
10:22:00 57735 8192000000 10043130272 10514624512 1090747440 11.750 97 15 28 0 1138514656 44792640 2974576
10:23:58 59896 8192000000 10063534576 10536497152 1105694416 11.892 98 27 53 0 1173431040 62001904 5734720
10:26:07 34763 8192000000 10110481264 10579947520 1110533264 11.937 99 37 71 0 1198060480 80426176 7101040
10:27:52 44080 8192000000 10073360400 10510528512 1091568368 11.758 97 21 40 0 1130576192 34872032 4135792
10:29:56 35451 8192000000 10067307872 10509025280 1089851072 11.741 98 37 73 0 1143908944 46642608 7415264
10:31:35 11399 8192000000 10123490720 10552696832 1120687856 12.033 100 58 110 2 1216023440 84437280 10898304
10:32:48 54785 8192000000 10072239504 10531540992 1108553792 11.919 99 35 70 0 1189076192 73426080 7096320
10:34:51 125294 8192000000 10079426880 10554576896 1105808448 11.893 99 41 78 0 1188875072 74832896 8233728

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 04-01-2023 10:39:46 to 04-01-2023 10:40:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 145 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-01-2023 10:40:04 to 04-01-2023 10:40:06.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          220521
MON$OLDEST_ACTIVE               221401
MON$OLDEST_SNAPSHOT             221401
MON$NEXT_TRANSACTION            221401
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2080416
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             775
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp40 according to config parameter 'remove_isql_logs'

04.01.23 10:40:07. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2023-01-04 10:40:09 No severe PSQL-related problems occured
04.01.23 10:40:11. End of report.