Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.12.2022 07:38
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 14770056 kB
MemAvailable 30126848 kB
Buffers 0 kB
Cached 16951440 kB
SwapCached 3596 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 2022-12-28 07:37:21
DTS_END                         2022-12-28 07:37:21.1990
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/46318
TRN_ID                          225781
ATT_ID                          646
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: 8999.01 26022 1079881
customer order: creation 172.15 429 20658
customer order: refuse 15.18 368 1821
order to supplier: creation 47.53 1230 5704
order to supplier: removal 3.38 135066 406
invoice (draft): creation 47.38 2820 5686
invoice (draft): removal 48.81 903 5857
invoice accept: apply 43.10 25681 5172
invoice accept: cancel 44.28 1128 5313
customer reserve: creation 3957.23 250 474868
customer reserve: removal 4321.67 84 518600
realization accept: apply 82.90 262 9948
realization accept: cancel 86.10 151 10332
payment from customer: creation 59.44 168 7133
payment from customer: removal 5.36 108 643
payment to supplier: creation 48.70 184 5844
payment to supplier: removal 3.29 117 395
service: total inventory turnovers 4.01 3947 481
service: total monetary turnovers 7.63 352 916
service: aggregate perf. data 0.83 18343 99
service: refresh index statistics 0.04 328854 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 28-12-2022 07:38:53 to 28-12-2022 07:38:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6207
TEST_TIME 2 5541
TEST_TIME 3 5220
TEST_TIME 4 5276
TEST_TIME 5 5304
TEST_TIME 6 5949
TEST_TIME 7 6231
TEST_TIME 8 6306
TEST_TIME 9 6519
TEST_TIME 10 6550
TEST_TIME 11 6626
TEST_TIME 12 7023
TEST_TIME 13 7081
TEST_TIME 14 7208
TEST_TIME 15 7292
TEST_TIME 16 7681
TEST_TIME 17 7834
TEST_TIME 18 7907
TEST_TIME 19 7952
TEST_TIME 20 7907
TEST_TIME 21 8007
TEST_TIME 22 8796
TEST_TIME 23 8619
TEST_TIME 24 9050
TEST_TIME 25 9271
TEST_TIME 26 9780
TEST_TIME 27 10153
TEST_TIME 28 10141
TEST_TIME 29 10338
TEST_TIME 30 10636
TEST_TIME 31 10513
TEST_TIME 32 10806
TEST_TIME 33 10743
TEST_TIME 34 10695
TEST_TIME 35 10579
TEST_TIME 36 10529
TEST_TIME 37 10437
TEST_TIME 38 10384
TEST_TIME 39 10246
TEST_TIME 40 10453
TEST_TIME 41 10478
TEST_TIME 42 10446
TEST_TIME 43 10299
TEST_TIME 44 10177
TEST_TIME 45 10062
TEST_TIME 46 9965
TEST_TIME 47 10043
TEST_TIME 48 9935
TEST_TIME 49 9835
TEST_TIME 50 9852
TEST_TIME 51 9783
TEST_TIME 52 9784
TEST_TIME 53 9777
TEST_TIME 54 9760
TEST_TIME 55 9786
TEST_TIME 56 9760
TEST_TIME 57 9782
TEST_TIME 58 9683
TEST_TIME 59 9658
TEST_TIME 60 9702
TEST_TIME 61 9715
TEST_TIME 62 9737
TEST_TIME 63 9728
TEST_TIME 64 9788
TEST_TIME 65 9778
TEST_TIME 66 9811
TEST_TIME 67 9749
TEST_TIME 68 9742
TEST_TIME 69 9791
TEST_TIME 70 9852
TEST_TIME 71 9732
TEST_TIME 72 9635
TEST_TIME 73 9601
TEST_TIME 74 9521
TEST_TIME 75 9439
TEST_TIME 76 9403
TEST_TIME 77 9351
TEST_TIME 78 9358
TEST_TIME 79 9306
TEST_TIME 80 9279
TEST_TIME 81 9277
TEST_TIME 82 9289
TEST_TIME 83 9278
TEST_TIME 84 9287
TEST_TIME 85 9271
TEST_TIME 86 9268
TEST_TIME 87 9263
TEST_TIME 88 9236
TEST_TIME 89 9246
TEST_TIME 90 9410
TEST_TIME 91 9440
TEST_TIME 92 9507
TEST_TIME 93 9534
TEST_TIME 94 9518
TEST_TIME 95 9452
TEST_TIME 96 9402
TEST_TIME 97 9367
TEST_TIME 98 9324
TEST_TIME 99 9265
TEST_TIME 100 9223
TEST_TIME 101 9216
TEST_TIME 102 9189
TEST_TIME 103 9182
TEST_TIME 104 9150
TEST_TIME 105 9123
TEST_TIME 106 9118
TEST_TIME 107 9153
TEST_TIME 108 9140
TEST_TIME 109 9139
TEST_TIME 110 9120
TEST_TIME 111 9159
TEST_TIME 112 9159
TEST_TIME 113 9146
TEST_TIME 114 9139
TEST_TIME 115 9156
TEST_TIME 116 9164
TEST_TIME 117 9181
TEST_TIME 118 9128
TEST_TIME 119 9074
TEST_TIME 120 9017

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-12-2022 07:38:57 to 28-12-2022 07:39:00.

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 1517781 1517781 0 0.00 0 38605 13 0 0
doc_list_biud 1517773 1517773 0 0.00 0 62439 35 0 0
sp_add_invoice_to_stock 5261 5172 89 1.69 69 206695 25682 0 89
sp_cancel_adding_invoice 5313 5313 0 0.00 7 100892 1129 0 0
sp_cancel_client_order 1821 1821 0 0.00 7 9507 368 0 0
sp_cancel_customer_reserve 518600 518600 0 0.00 1 62441 84 0 0
sp_cancel_pay_from_customer 643 643 0 0.00 3 1703 109 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 4 5174 118 0 0
sp_cancel_supplier_invoice 5866 5857 9 0.15 3 20766 904 0 9
sp_cancel_supplier_order 406 406 0 0.00 8 1352168 135066 0 0
sp_cancel_write_off 10332 10332 0 0.00 2 19983 151 0 0
sp_client_order 20658 20658 0 0.00 5 46519 430 0 0
sp_customer_reserve 474868 474868 0 0.00 0 129681 250 0 0
sp_fill_shopping_cart 32035 32035 0 0.00 1 12282 77 0 0
sp_fill_shopping_cart_clo_res 474833 474833 0 0.00 0 19462 17 0 0
sp_get_clo_for_invoice 5132 5132 0 0.00 0 37053 253 0 0
sp_kill_cost_storno 16683 16683 0 0.00 0 19978 61 0 0
sp_kill_qstorno_ret_qs2qd 1053795 1053795 0 0.00 0 62436 38 0 0
sp_kill_qty_storno 557408 557408 0 0.00 0 62439 87 0 0
sp_lock_selected_doc 20939 20939 0 0.00 0 10266 7 0 0
sp_make_cost_storno 28057 28057 0 0.00 0 29815 76 0 0
sp_multiply_rows_for_pdistr 28056 28056 0 0.00 0 32798 36 0 0
sp_multiply_rows_for_qdistr 20658 20658 0 0.00 0 46474 172 0 0
sp_pay_from_customer 7133 7133 0 0.00 5 32883 169 0 0
sp_pay_to_supplier 5844 5844 0 0.00 6 29836 184 0 0
sp_payment_common 12976 12976 0 0.00 2 8648 43 0 0
sp_qd_handle_on_cancel_clo 1821 1821 0 0.00 0 9498 297 0 0
sp_qd_handle_on_invoice_upd_sts 10445 10445 0 0.00 0 42513 456 0 0
sp_qd_handle_on_reserve_upd_sts 9947 9947 0 0.00 0 17118 63 0 0
sp_reserve_write_off 9948 9948 0 0.00 8 17203 263 0 0
sp_supplier_invoice 5686 5686 0 0.00 8 109952 2821 0 0
sp_supplier_order 5704 5704 0 0.00 8 16434 1231 0 0
srv_aggregate_perf_data 99 99 0 0.00 2361 202619 18344 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 770 117775 36251 0 0
srv_find_qd_qs_mism 1030599 1030599 0 0.00 0 38604 11 0 0
srv_make_invnt_saldo 481 481 0 0.00 93 86487 3948 0 0
srv_make_money_saldo 916 916 0 0.00 1 8858 352 0 0
srv_recalc_idx_stat 5 5 0 0.00 246448 412126 328854 0 0
t$perf-abend:sp_add_invoice_to_stock 89 89 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5176 5176 0 0.00 1 10744 804 0 0
t$perf-norm:sp_cancel_adding_invoice 435 435 0 0.00 1 5899 385 0 0
t$perf-norm:sp_cancel_client_order 1821 1821 0 0.00 0 9361 33 0 0
t$perf-norm:sp_cancel_customer_reserve 696 696 0 0.00 0 9632 40 0 0
t$perf-norm:sp_cancel_pay_from_customer 643 643 0 0.00 0 2971 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 1400 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 433 433 0 0.00 0 767 30 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 107064 5410 0 0
t$perf-norm:sp_cancel_write_off 1039 1039 0 0.00 0 2325 18 0 0
t$perf-norm:sp_client_order 20659 20659 0 0.00 0 10173 33 0 0
t$perf-norm:sp_customer_reserve 1011 1011 0 0.00 0 799 14 0 0
t$perf-norm:sp_pay_from_customer 7133 7133 0 0.00 0 4792 20 0 0
t$perf-norm:sp_pay_to_supplier 5844 5844 0 0.00 0 9454 21 0 0
t$perf-norm:sp_reserve_write_off 9948 9948 0 0.00 0 11442 24 0 0
t$perf-norm:sp_supplier_invoice 5686 5686 0 0.00 0 10407 28 0 0
t$perf-norm:sp_supplier_order 5704 5704 0 0.00 0 9305 30 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 464 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 757 22 0 0
t$perf-norm:srv_make_invnt_saldo 481 481 0 0.00 0 1864 20 0 0
t$perf-norm:srv_make_money_saldo 916 916 0 0.00 0 6664 15 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 25 162 100 0 0
v_add_invoice_to_stock 10428 10250 178 1.70 0 5097 23 0 178
v_all_customers 38260 38260 0 0.00 0 81 3 0 0
v_all_suppliers 31064 31064 0 0.00 0 84 4 0 0
v_all_wares 41306 41306 0 0.00 0 172 4 0 0
v_cancel_adding_invoice 870 870 0 0.00 0 2453 19 0 0
v_cancel_client_order 3642 3642 0 0.00 0 6142 14 0 0
v_cancel_customer_prepayment 1286 1286 0 0.00 0 639 10 0 0
v_cancel_customer_reserve 1392 1392 0 0.00 1 669 9 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 5109 21 0 0
v_cancel_supplier_invoice 884 866 18 2.03 0 2975 25 0 18
v_cancel_supplier_order 812 812 0 0.00 0 5117 20 0 0
v_cancel_write_off 2078 2078 0 0.00 0 3379 25 0 0
v_max_id_clo_ord 5699 5699 0 0.00 0 293 5 0 0
v_max_id_clo_res 1011 1011 0 0.00 0 3142 12 0 0
v_max_id_ord_sup 5677 5677 0 0.00 0 5463 8 0 0
v_max_non_paid_invoice 1687 1687 0 0.00 0 85 7 0 0
v_max_non_paid_realizn 3473 3473 0 0.00 0 3556 9 0 0
v_min_id_clo_ord 5699 5699 0 0.00 0 2747 9 0 0
v_min_id_clo_res 1011 1011 0 0.00 0 3988 13 0 0
v_min_id_ord_sup 5677 5677 0 0.00 0 5523 11 0 0
v_min_non_paid_invoice 5843 5843 0 0.00 0 7764 17 0 0
v_min_non_paid_realizn 7132 7132 0 0.00 0 8632 18 0 0
v_our_firm 10362 10362 0 0.00 0 99 4 0 0
v_reserve_write_off 19890 19890 0 0.00 0 5549 11 0 0
x_lock_depdocs_on_canc_invoice 10737 10737 0 0.00 0 15634 121 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 15000 325 0 0
x_make_qstorno_1000_1200 5704 5704 0 0.00 2 15702 1077 0 0
x_make_qstorno_1200_2000 5686 5686 0 0.00 4 107128 2607 0 0
x_make_qstorno_2100_3300 474867 474867 0 0.00 0 129680 222 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-12-2022 07:39:01 to 28-12-2022 07:39:05.

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
05:07:25 1 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:08:25 20480 8192000000 9718025776 10016350208 797554880 8.872 100 90 180 0 831605360 12188208 21862272
05:09:55 16675 8192000000 9756853984 10063998976 830593600 9.205 100 93 186 0 864683472 11766032 22323840
05:11:15 13185 8192000000 9790180976 10105384960 857830640 9.478 100 90 179 0 901663760 22810720 21022400
05:12:30 9190 8192000000 9840265600 10170200064 893510416 9.834 100 80 158 1 953489568 42055616 17923536
05:13:43 3084 8192000000 9897338592 10236432384 938274496 10.276 100 81 159 1 1003537232 47883040 17379696
05:14:49 1842 8192000000 9946851520 10295930880 974981792 10.635 100 90 178 0 1063006256 69671424 18353040
05:15:54 1831 8192000000 9988273712 10348244992 1007880320 10.955 100 91 180 0 1107607744 82093728 17633696
05:16:57 825 8192000000 10003877120 10371936256 1029358320 11.162 100 94 184 1 1125796768 78122208 18316240
05:18:00 1170 8192000000 10006663856 10377232384 1043307040 11.296 100 94 181 5 1125487232 63408464 18771728
05:19:03 1835 8192000000 10026851408 10403454976 1059302992 11.450 100 88 170 0 1142215776 65541840 17370944
05:20:07 1102 8192000000 10036552032 10413756416 1063858384 11.493 100 94 177 0 1153588928 72000416 17730128
05:21:09 1196 8192000000 10049408960 10426691584 1069127248 11.544 100 100 194 0 1170225456 81209104 19889104
05:22:12 1216 8192000000 10066870720 10450972672 1081562416 11.662 100 97 196 0 1188934592 87956176 19416000
05:23:14 1358 8192000000 10025240976 10407706624 1081685408 11.664 100 97 178 3 1147619712 47171328 18762976
05:24:16 2097 8192000000 10047251296 10437144576 1087087936 11.715 100 64 122 0 1173619200 74217840 12313424
05:25:20 1220 8192000000 10063296064 10451038208 1093968448 11.780 100 95 182 2 1184596688 71605632 19022608
05:26:22 1107 8192000000 10063715440 10455715840 1093722976 11.778 100 95 186 1 1188788976 74926624 20139376
05:27:25 2307 8192000000 10048304112 10432098304 1083220192 11.678 99 78 148 2 1162974784 65174640 14579952
05:28:29 1108 8192000000 10053127328 10441494528 1085948464 11.704 100 84 160 1 1178161600 75882816 16330320
05:29:31 952 8192000000 10008304128 10388324352 1062795872 11.483 98 93 172 6 1125714784 42660416 20258496
05:30:33 1330 8192000000 10027915200 10418651136 1085501616 11.700 100 75 147 3 1151940544 51665536 14773392
05:31:36 4268 8192000000 9996458640 10374496256 1064260592 11.497 100 81 138 4 1113554448 37605376 11688480
05:32:41 1210 8192000000 10005582160 10374713344 1030431776 11.173 100 95 185 1 1131014160 82153040 18429344
05:33:43 1493 8192000000 9992821712 10364387328 1033246784 11.200 99 95 181 4 1114908032 63722736 17938512
05:34:46 961 8192000000 10018291120 10398408704 1051137856 11.372 100 95 179 2 1140606496 70495360 18973280
05:35:48 1215 8192000000 9970498416 10345885696 1041598368 11.280 100 79 146 2 1091997328 33633216 16765744
05:36:49 11934 8192000000 10008215392 10406068224 1047734320 11.339 100 68 135 1 1143033312 81902720 13396272
05:38:10 4291 8192000000 9992920672 10373328896 1064118304 11.496 100 92 163 3 1115046512 34499632 16428576
05:39:19 6121 8192000000 10053313072 10431406080 1074949264 11.599 100 77 149 2 1163147984 73997440 14201280
05:40:30 6180 8192000000 10061735744 10446843904 1089694960 11.740 100 78 153 1 1167907392 64594016 13618416
05:41:38 2157 8192000000 10070238592 10459492352 1099285104 11.831 100 69 124 2 1187621696 76097216 12239376
05:42:44 2699 8192000000 10143974256 10529513472 1106352624 11.898 100 94 177 0 1260934528 135734192 18847712
05:43:49 10416 8192000000 10162105744 10559602688 1123673328 12.062 100 72 138 0 1281569440 143323264 14572848
05:45:04 1007 8192000000 10168941200 10561802240 1124907968 12.073 100 95 185 0 1288938912 144265168 19765776
05:46:09 1127 8192000000 10201833552 10595659776 1133526272 12.155 100 94 181 2 1314821040 161096256 20198512
05:47:15 5914 8192000000 10209090512 10614497280 1146078272 12.273 100 70 139 0 1320582624 159270992 15233360
05:48:24 1809 8192000000 10243649984 10648887296 1158227808 12.387 100 83 149 2 1356940912 182923760 15789344
05:49:27 864 8192000000 10271415760 10676064256 1163517600 12.436 100 93 181 1 1376134704 193094560 19522544
05:50:31 2448 8192000000 10320731936 10725584896 1178727568 12.578 100 91 180 1 1433376880 234892080 19757232
05:51:38 760 8192000000 10335155424 10742857728 1181957248 12.608 100 96 184 1 1443827184 241204720 20665216
05:52:41 3533 8192000000 10379377936 10784366592 1185322816 12.640 100 89 173 2 1483567888 279472976 18772096
05:53:47 1331 8192000000 10335919712 10752905216 1172793840 12.523 100 92 176 0 1443063504 249633232 20636432
05:54:52 945 8192000000 10401277408 10806468608 1186326000 12.649 100 97 195 0 1505439136 297664688 21448448
05:55:55 849 8192000000 10427018864 10832314368 1174610624 12.540 100 91 173 0 1529072544 335037792 19424128
05:56:59 3888 8192000000 10446741744 10868113408 1179056896 12.581 100 63 122 2 1555740400 361908800 14774704
05:58:12 1016 8192000000 10450791248 10913619968 1181453504 12.604 100 93 181 1 1558751376 354756304 22541568
05:59:15 762 8192000000 10446229712 10886660096 1171774768 12.513 100 84 156 2 1549626208 358450528 19400912
06:00:18 8864 8192000000 10405105216 10847227904 1162596560 12.428 100 37 74 0 1505040032 332608896 9834576
06:01:29 48598 8192000000 10376724416 10837798912 1154526176 12.352 99 39 72 1 1477392448 313283776 9582496
06:03:24 34383 8192000000 10239158976 10783387648 1150780736 12.317 100 23 46 0 1407930304 250851712 6297856
06:05:07 47940 8192000000 10196544064 10632757248 1112308336 11.954 97 22 42 0 1283261696 166010304 4943056
06:06:57 19063 8192000000 10125387632 10586828800 1091553456 11.757 96 28 54 1 1199844528 101766688 6524384
06:08:16 19270 8192000000 10134763440 10576314368 1120606544 12.033 100 36 72 0 1220108000 91759168 7742288
06:09:38 55878 8192000000 10067670224 10556989440 1116433072 11.993 99 6 12 0 1169767664 52289168 1045424
06:11:44 58519 8192000000 10088325344 10576375808 1140924320 12.224 100 29 53 1 1196664208 49826576 5913312
06:13:46 112354 8192000000 10128381888 10583318528 1121418000 12.040 98 24 48 0 1166440784 40606016 4416768
06:17:54 37969 8192000000 10071165152 10520256512 1117177872 12.000 99 21 40 0 1168329664 45327856 5823936
06:19:32 57721 8192000000 10041109152 10469818368 1087468064 11.719 96 33 55 0 1111632416 18405584 5758768
06:21:34 109543 8192000000 10126511344 10577186816 1108764624 11.921 99 41 82 0 1214726512 96870384 9091504
06:24:28 14620 8192000000 10123266176 10554798080 1135612784 12.174 100 60 108 0 1216483520 69001184 11869552
06:25:52 57897 8192000000 10120643680 10555777024 1127893680 12.102 98 43 88 3 1199945824 62469904 9582240
06:27:59 13115 8192000000 10149480992 10576646144 1154889712 12.355 100 54 108 0 1240257376 73590512 11777152
06:29:14 57965 8192000000 10189144848 10649366528 1166939552 12.468 100 25 50 0 1292147888 119465360 5742976
06:31:24 78400 8192000000 10121032048 10615738368 1126278624 12.086 98 11 22 0 1214092208 85283056 2530528
06:33:56 35031 8192000000 10084612208 10572398592 1131693856 12.137 100 10 20 0 1188326896 53997344 2635696
06:35:37 55368 8192000000 10120472256 10575945728 1086611152 11.710 95 7 14 0 1178715312 90536496 1567664
06:37:37 63896 8192000000 10139710688 10596937728 1122822336 12.054 98 21 42 0 1215768832 88405200 4541296
06:39:45 32738 8192000000 10096121808 10558763008 1103930096 11.875 97 26 49 0 1206034784 96665264 5439424
06:41:24 33956 8192000000 10123664592 10580262912 1131166704 12.132 99 44 88 0 1217200560 76565232 9468624
06:43:01 29492 8192000000 10094046112 10528964608 1125439824 12.078 99 26 50 0 1175847040 45248096 5159120
06:44:32 26359 8192000000 10139066784 10564653056 1116121552 11.990 98 55 108 1 1201209808 73976864 11111392
06:46:04 45116 8192000000 10139383312 10603536384 1135542960 12.174 100 18 36 0 1242511664 103119328 3849376
06:47:55 117775 8192000000 10065821840 10524680192 1121212592 12.038 98 19 38 0 1152163984 27306368 3645024
06:50:58 58440 8192000000 10063579088 10516189184 1108981040 11.923 97 9 18 0 1131007888 20335408 1691440
06:52:57 76098 8192000000 10116952944 10578595840 1129884864 12.120 99 15 30 0 1196940832 64105200 2950768
06:55:13 45899 8192000000 10084090608 10597613568 1137251008 12.190 100 15 31 0 1194748512 54385856 3111648
06:57:00 44922 8192000000 10079926640 10529300480 1120229344 12.029 98 21 42 0 1164521376 39953184 4338848
06:58:45 21907 8192000000 10110072464 10549178368 1147400384 12.285 100 29 54 0 1201947920 48339680 6207856
07:00:07 58598 8192000000 10132897344 10570584064 1133349472 12.153 98 35 70 0 1215631456 74662832 7619152
07:02:09 42712 8192000000 10144631248 10585997312 1121675072 12.043 97 42 82 0 1216577184 86216896 8685216
07:03:54 29948 8192000000 10150784144 10612178944 1111618864 11.948 98 35 70 0 1237330096 117929040 7782192
07:05:38 66768 8192000000 10195984736 10635399168 1114704096 11.977 98 29 58 1 1264603328 143581824 6317408
07:07:48 97594 8192000000 10105179056 10622656512 1093615744 11.777 97 12 24 0 1168655632 72441040 2598848
07:10:26 92745 8192000000 10083775344 10560782336 1113198048 11.963 98 17 34 0 1171496592 54803936 3494608
07:13:07 44827 8192000000 10067019920 10536120320 1138517472 12.202 100 24 46 1 1175609440 32550208 4541760
07:16:08 102401 8192000000 10114749792 10584780800 1119401088 12.021 97 12 24 0 1174086784 52025408 2660288
07:19:03 61830 8192000000 10100017056 10580144128 1140706992 12.222 100 17 34 0 1193009904 47907088 4395824
07:21:08 63326 8192000000 10059996128 10505285632 1082443632 11.671 95 16 28 3 1126397712 41165824 2788256
07:23:15 39687 8192000000 10083476272 10511527936 1117167744 12.000 99 35 70 1 1170827200 46370336 7289120
07:24:58 77464 8192000000 10068316480 10514173952 1069961184 11.552 95 30 56 0 1118481968 43442048 5078736
07:27:42 25194 8192000000 10085948688 10553462784 1104754016 11.883 99 52 97 2 1183593136 68881632 9957488
07:29:12 14661 8192000000 10087790640 10526625792 1100402992 11.841 99 51 102 0 1163293360 52918224 9972144
07:30:28 3644 8192000000 10081624384 10497835008 1097829408 11.817 99 66 123 0 1160859024 50485344 12544272
07:31:33 25850 8192000000 10104534944 10538106880 1121406544 12.040 100 58 114 1 1209083808 76184224 11493040
07:33:04 8102 8192000000 10105968784 10519470080 1119578304 12.023 100 67 128 0 1192207168 59783824 12845040
07:34:13 73635 8192000000 10058604016 10513506304 1091305488 11.755 97 29 58 0 1158674800 61895216 5474096
07:36:37 31067 8192000000 10057791472 10493186048 1123398176 12.059 100 45 88 1 1159826240 26696880 9731184

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 28-12-2022 07:39:07 to 28-12-2022 07:39:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 89 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-12-2022 07:39:24 to 28-12-2022 07:39:26.

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          224417
MON$OLDEST_ACTIVE               225915
MON$OLDEST_SNAPSHOT             225915
MON$NEXT_TRANSACTION            225915
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                       2067040
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             784
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 ---
:610: 
:611: mscs-test-161.budzdorov.ru    Wed Dec 28 07:37:28 2022
:612:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:613: 
:614: 
:615: mscs-test-161.budzdorov.ru    Wed Dec 28 07:37:29 2022
:616:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:617: 
--- end of diff output ---

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

28.12.22 07:39:28. 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
2022-12-28 07:39:30 No severe PSQL-related problems occured
28.12.22 07:39:32. End of report.