Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.03.2023 04: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 14619380 kB
MemAvailable 30386916 kB
Buffers 20 kB
Cached 17033704 kB
SwapCached 3028 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-03-08 04:37:30
DTS_END                         2023-03-08 04:37:30.2150
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46532
TRN_ID                          205721
ATT_ID                          603
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: 8570.14 35558 1028417
customer order: creation 150.67 468 18080
customer order: refuse 13.46 453 1615
order to supplier: creation 41.01 1542 4921
order to supplier: removal 3.22 200625 386
invoice (draft): creation 41.15 3651 4938
invoice (draft): removal 47.73 1227 5728
invoice accept: apply 38.12 27356 4574
invoice accept: cancel 43.68 1458 5242
customer reserve: creation 3615.06 252 433807
customer reserve: removal 4308.43 116 517011
realization accept: apply 74.36 305 8923
realization accept: cancel 76.74 191 9209
payment from customer: creation 53.43 190 6412
payment from customer: removal 5.18 125 622
payment to supplier: creation 43.96 219 5275
payment to supplier: removal 3.15 120 378
service: total inventory turnovers 3.12 5891 374
service: total monetary turnovers 6.81 444 817
service: aggregate perf. data 0.84 19597 101
service: refresh index statistics 0.03 446929 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-03-2023 04:39:18 to 08-03-2023 04:39:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7646
TEST_TIME 2 6275
TEST_TIME 3 5705
TEST_TIME 4 5261
TEST_TIME 5 5355
TEST_TIME 6 5197
TEST_TIME 7 5361
TEST_TIME 8 5532
TEST_TIME 9 5441
TEST_TIME 10 5378
TEST_TIME 11 5417
TEST_TIME 12 5675
TEST_TIME 13 5815
TEST_TIME 14 6060
TEST_TIME 15 6284
TEST_TIME 16 6475
TEST_TIME 17 6655
TEST_TIME 18 6710
TEST_TIME 19 6637
TEST_TIME 20 6875
TEST_TIME 21 7068
TEST_TIME 22 6974
TEST_TIME 23 7204
TEST_TIME 24 7394
TEST_TIME 25 7322
TEST_TIME 26 7503
TEST_TIME 27 7777
TEST_TIME 28 8048
TEST_TIME 29 7989
TEST_TIME 30 8334
TEST_TIME 31 8808
TEST_TIME 32 9172
TEST_TIME 33 9208
TEST_TIME 34 9419
TEST_TIME 35 9565
TEST_TIME 36 9792
TEST_TIME 37 9801
TEST_TIME 38 10209
TEST_TIME 39 10145
TEST_TIME 40 10069
TEST_TIME 41 9827
TEST_TIME 42 9854
TEST_TIME 43 9935
TEST_TIME 44 9931
TEST_TIME 45 10042
TEST_TIME 46 9991
TEST_TIME 47 10034
TEST_TIME 48 9888
TEST_TIME 49 9770
TEST_TIME 50 9677
TEST_TIME 51 9667
TEST_TIME 52 9675
TEST_TIME 53 9585
TEST_TIME 54 9486
TEST_TIME 55 9444
TEST_TIME 56 9359
TEST_TIME 57 9213
TEST_TIME 58 9168
TEST_TIME 59 9116
TEST_TIME 60 9105
TEST_TIME 61 9152
TEST_TIME 62 9094
TEST_TIME 63 9047
TEST_TIME 64 8941
TEST_TIME 65 8839
TEST_TIME 66 8793
TEST_TIME 67 8745
TEST_TIME 68 8713
TEST_TIME 69 8724
TEST_TIME 70 8654
TEST_TIME 71 8620
TEST_TIME 72 8612
TEST_TIME 73 8557
TEST_TIME 74 8507
TEST_TIME 75 8600
TEST_TIME 76 8561
TEST_TIME 77 8550
TEST_TIME 78 8572
TEST_TIME 79 8581
TEST_TIME 80 8631
TEST_TIME 81 8657
TEST_TIME 82 8617
TEST_TIME 83 8725
TEST_TIME 84 8772
TEST_TIME 85 8793
TEST_TIME 86 8813
TEST_TIME 87 8764
TEST_TIME 88 8695
TEST_TIME 89 8668
TEST_TIME 90 8674
TEST_TIME 91 8646
TEST_TIME 92 8636
TEST_TIME 93 8591
TEST_TIME 94 8543
TEST_TIME 95 8570
TEST_TIME 96 8575
TEST_TIME 97 8540
TEST_TIME 98 8564
TEST_TIME 99 8531
TEST_TIME 100 8529
TEST_TIME 101 8544
TEST_TIME 102 8519
TEST_TIME 103 8564
TEST_TIME 104 8575
TEST_TIME 105 8608
TEST_TIME 106 8753
TEST_TIME 107 8806
TEST_TIME 108 8811
TEST_TIME 109 8813
TEST_TIME 110 8791
TEST_TIME 111 8731
TEST_TIME 112 8699
TEST_TIME 113 8683
TEST_TIME 114 8687
TEST_TIME 115 8699
TEST_TIME 116 8670
TEST_TIME 117 8640
TEST_TIME 118 8606
TEST_TIME 119 8579
TEST_TIME 120 8593

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-03-2023 04:39:22 to 08-03-2023 04:39:25.

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 1426251 1426251 0 0.00 0 20611 13 0 0
doc_list_biud 1426235 1426235 0 0.00 0 111351 49 0 0
sp_add_invoice_to_stock 4650 4574 76 1.63 34 302362 27357 0 76
sp_cancel_adding_invoice 5242 5242 0 0.00 7 97782 1459 0 0
sp_cancel_client_order 1615 1615 0 0.00 6 5911 454 0 0
sp_cancel_customer_reserve 517011 517011 0 0.00 1 37942 117 0 0
sp_cancel_pay_from_customer 622 622 0 0.00 4 2123 126 0 0
sp_cancel_pay_to_supplier 378 378 0 0.00 4 2383 121 0 0
sp_cancel_supplier_invoice 5738 5728 10 0.17 3 111494 1227 0 10
sp_cancel_supplier_order 386 386 0 0.00 13 1516874 200626 0 0
sp_cancel_write_off 9209 9209 0 0.00 2 6436 192 0 0
sp_client_order 18080 18080 0 0.00 6 39150 469 0 0
sp_customer_reserve 433807 433807 0 0.00 1 48502 253 0 0
sp_fill_shopping_cart 27922 27922 0 0.00 1 26739 90 0 0
sp_fill_shopping_cart_clo_res 433777 433777 0 0.00 0 18270 21 0 0
sp_get_clo_for_invoice 4530 4530 0 0.00 0 25303 355 0 0
sp_kill_cost_storno 15451 15451 0 0.00 0 17424 94 0 0
sp_kill_qstorno_ret_qs2qd 1049345 1049345 0 0.00 0 111336 52 0 0
sp_kill_qty_storno 552642 552642 0 0.00 0 111347 119 0 0
sp_lock_selected_doc 18687 18687 0 0.00 0 5013 6 0 0
sp_make_cost_storno 25139 25139 0 0.00 0 20606 90 0 0
sp_multiply_rows_for_pdistr 25136 25136 0 0.00 0 15288 40 0 0
sp_multiply_rows_for_qdistr 18080 18080 0 0.00 0 38943 175 0 0
sp_pay_from_customer 6412 6412 0 0.00 5 11290 190 0 0
sp_pay_to_supplier 5275 5275 0 0.00 6 20625 220 0 0
sp_payment_common 11685 11685 0 0.00 2 20057 50 0 0
sp_qd_handle_on_cancel_clo 1615 1615 0 0.00 1 5884 381 0 0
sp_qd_handle_on_invoice_upd_sts 9771 9771 0 0.00 0 42638 653 0 0
sp_qd_handle_on_reserve_upd_sts 8922 8922 0 0.00 0 20891 84 0 0
sp_reserve_write_off 8923 8923 0 0.00 8 21116 306 0 0
sp_supplier_invoice 4938 4938 0 0.00 12 110541 3651 0 0
sp_supplier_order 4921 4921 0 0.00 8 43908 1542 0 0
srv_aggregate_perf_data 101 101 0 0.00 2989 201262 19598 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 742 138522 36309 0 0
srv_find_qd_qs_mism 983116 983116 0 0.00 0 17521 9 0 0
srv_make_invnt_saldo 374 374 0 0.00 72 128813 5891 0 0
srv_make_money_saldo 817 817 0 0.00 1 8771 444 0 0
srv_recalc_idx_stat 4 4 0 0.00 281528 654394 446929 0 0
t$perf-abend:sp_add_invoice_to_stock 76 76 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4577 4577 0 0.00 1 18951 1013 0 0
t$perf-norm:sp_cancel_adding_invoice 358 358 0 0.00 1 6858 652 0 0
t$perf-norm:sp_cancel_client_order 1615 1615 0 0.00 0 8307 42 0 0
t$perf-norm:sp_cancel_customer_reserve 631 631 0 0.00 0 2599 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 622 622 0 0.00 0 3619 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 378 378 0 0.00 0 3273 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 349 349 0 0.00 0 901 41 0 0
t$perf-norm:sp_cancel_supplier_order 386 386 0 0.00 0 113582 8198 0 0
t$perf-norm:sp_cancel_write_off 905 905 0 0.00 0 2769 22 0 0
t$perf-norm:sp_client_order 18080 18080 0 0.00 0 17387 41 0 0
t$perf-norm:sp_customer_reserve 843 843 0 0.00 0 2069 15 0 0
t$perf-norm:sp_pay_from_customer 6413 6413 0 0.00 0 6516 20 0 0
t$perf-norm:sp_pay_to_supplier 5275 5275 0 0.00 0 8141 21 0 0
t$perf-norm:sp_reserve_write_off 8923 8923 0 0.00 0 5351 22 0 0
t$perf-norm:sp_supplier_invoice 4939 4939 0 0.00 0 8887 37 0 0
t$perf-norm:sp_supplier_order 4921 4921 0 0.00 0 3837 36 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 184 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 618 19 0 0
t$perf-norm:srv_make_invnt_saldo 374 374 0 0.00 0 1271 17 0 0
t$perf-norm:srv_make_money_saldo 817 817 0 0.00 0 372 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 95 55 0 0
v_add_invoice_to_stock 9194 9042 152 1.65 0 6629 28 0 152
v_all_customers 33660 33660 0 0.00 0 315 4 0 0
v_all_suppliers 27636 27636 0 0.00 0 103 4 0 0
v_all_wares 36154 36154 0 0.00 0 283 4 0 0
v_cancel_adding_invoice 716 716 0 0.00 0 2281 20 0 0
v_cancel_client_order 3230 3230 0 0.00 0 2105 11 0 0
v_cancel_customer_prepayment 1244 1244 0 0.00 0 612 12 0 0
v_cancel_customer_reserve 1262 1262 0 0.00 0 714 12 0 0
v_cancel_payment_to_supplier 756 756 0 0.00 0 948 13 0 0
v_cancel_supplier_invoice 718 698 20 2.78 0 3147 31 0 20
v_cancel_supplier_order 772 772 0 0.00 0 3528 21 0 0
v_cancel_write_off 1810 1810 0 0.00 0 3741 34 0 0
v_max_id_clo_ord 4916 4916 0 0.00 0 632 5 0 0
v_max_id_clo_res 842 842 0 0.00 0 1649 11 0 0
v_max_id_ord_sup 4929 4929 0 0.00 0 5276 8 0 0
v_max_non_paid_invoice 1299 1299 0 0.00 0 4656 12 0 0
v_max_non_paid_realizn 3076 3076 0 0.00 0 4656 10 0 0
v_min_id_clo_ord 4916 4916 0 0.00 0 3765 12 0 0
v_min_id_clo_res 842 842 0 0.00 0 5035 20 0 0
v_min_id_ord_sup 4929 4929 0 0.00 0 4423 11 0 0
v_min_non_paid_invoice 5274 5274 0 0.00 0 20041 20 0 0
v_min_non_paid_realizn 6411 6411 0 0.00 0 9345 20 0 0
v_our_firm 9164 9164 0 0.00 0 64 4 0 0
v_reserve_write_off 17844 17844 0 0.00 0 6831 14 0 0
x_lock_depdocs_on_canc_invoice 10621 10621 0 0.00 0 10460 146 0 0
x_lock_depdocs_on_canc_sup_ord 386 386 0 0.00 0 7767 458 0 0
x_make_qstorno_1000_1200 4921 4921 0 0.00 2 43750 1348 0 0
x_make_qstorno_1200_2000 4938 4938 0 0.00 6 109125 3404 0 0
x_make_qstorno_2100_3300 433807 433807 0 0.00 0 43606 219 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 08-03-2023 04:39:26 to 08-03-2023 04:39:31.

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
02:07:34 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:35 21588 8192000000 9717782992 10018004992 797296448 8.869 100 80 160 0 823917696 7094928 19526320
02:10:03 15979 8192000000 9758868560 10065735680 833783744 9.237 100 88 176 0 866685120 12191904 20709472
02:11:26 17138 8192000000 9798075824 10115227648 868532448 9.585 100 87 172 0 905550576 16939760 20078368
02:12:48 9160 8192000000 9851719104 10180829184 905270432 9.951 100 82 163 0 961666000 38340720 18054848
02:14:00 4343 8192000000 9888704048 10227617792 938476944 10.278 100 88 171 0 1002647440 45866544 18303952
02:15:07 3882 8192000000 9944506624 10294120448 977331904 10.658 100 93 180 2 1059358368 63321984 18704480
02:16:15 1687 8192000000 9995978480 10356011008 1019622736 11.068 100 92 179 0 1107301216 69610640 18067840
02:17:19 1446 8192000000 10010736816 10379587584 1041044512 11.275 100 87 168 1 1128812640 71586864 16181264
02:18:23 1305 8192000000 10030835472 10406141952 1056651248 11.424 100 94 181 1 1153563840 79087456 17825136
02:19:25 867 8192000000 10027179824 10407239680 1064653808 11.501 100 89 168 0 1147735264 66237712 16843744
02:20:28 1067 8192000000 10061134384 10442293248 1074463232 11.595 100 97 191 0 1180239344 85688464 20087648
02:21:30 1994 8192000000 10028107840 10417393664 1078177328 11.630 100 75 139 3 1148531664 55615776 14738560
02:22:33 1373 8192000000 10033945840 10425204736 1081284304 11.660 100 71 133 1 1151917248 57043184 13589760
02:23:36 2434 8192000000 10039030048 10426687488 1087568016 11.720 100 79 152 6 1160008160 57090320 15349824
02:24:39 1104 8192000000 10056678112 10443923456 1085826192 11.703 100 93 187 1 1178887984 73736736 19325056
02:25:41 4013 8192000000 10057422432 10453209088 1089864608 11.741 100 87 167 0 1179782176 72394880 17522688
02:26:46 6917 8192000000 10065334528 10457772032 1087848432 11.722 100 61 114 1 1180400000 81266096 11285472
02:27:54 1291 8192000000 10056916608 10454970368 1092358624 11.765 100 80 151 1 1180102736 72784336 14959776
02:28:56 1531 8192000000 10064151072 10454900736 1089334496 11.736 100 79 156 1 1186488336 80016480 17137360
02:29:59 1154 8192000000 10049915696 10443624448 1093742320 11.778 100 73 145 4 1170909952 62176256 14991376
02:31:01 938 8192000000 10031924080 10418851840 1082548528 11.672 100 92 171 2 1156654560 56677808 17428224
02:32:02 1262 8192000000 10031979904 10413830144 1067833232 11.531 100 95 175 5 1154556144 68398720 18324192
02:33:05 1092 8192000000 10017813712 10394791936 1054822832 11.407 100 92 176 1 1140312400 66667152 18822416
02:34:06 1104 8192000000 10004517984 10380328960 1037619072 11.242 100 85 158 0 1127424224 73869872 15935280
02:35:09 1742 8192000000 10003131776 10383761408 1035940240 11.226 100 79 149 1 1129692128 79815152 13936736
02:36:12 847 8192000000 9989320752 10369060864 1038713872 11.252 100 84 154 3 1117289744 60806960 17768912
02:37:14 1171 8192000000 9981098384 10360971264 1042013728 11.284 100 77 145 3 1105269104 48065680 15189696
02:38:19 8645 8192000000 9997786896 10376617984 1052197632 11.382 100 76 144 0 1119386592 53372928 13816032
02:39:45 19885 8192000000 10032148016 10418593792 1071486608 11.566 100 65 112 1 1147290640 64586960 11217072
02:41:14 6660 8192000000 10051057904 10440794112 1084358192 11.689 100 69 135 2 1175630544 77530992 13741360
02:42:25 3447 8192000000 10083985568 10470752256 1095707760 11.797 100 71 138 1 1210860144 100793856 14358528
02:43:32 3762 8192000000 10128697664 10521239552 1107453840 11.908 100 76 147 2 1248296448 125454048 15388560
02:44:46 6637 8192000000 10138865520 10538897408 1107994656 11.913 100 95 187 2 1248212144 120707280 19510208
02:45:58 11195 8192000000 10170722144 10567929856 1121738864 12.043 100 58 112 0 1277924944 143851120 12334960
02:47:14 5969 8192000000 10197962224 10604052480 1142752704 12.241 100 66 129 0 1316542048 159944608 13844736
02:48:27 1552 8192000000 10259663248 10657832960 1158381904 12.388 100 85 165 0 1375734368 198827808 18524656
02:49:34 953 8192000000 10292480416 10701025280 1169448272 12.492 100 96 186 0 1403104064 212663520 20992272
02:50:39 937 8192000000 10344542736 10751754240 1181799344 12.607 100 96 184 0 1453499344 250848928 20851072
02:51:43 1750 8192000000 10322543840 10727243776 1180761408 12.597 100 88 167 0 1435503408 236202768 18539232
02:52:49 732 8192000000 10376032096 10786058240 1187592416 12.661 100 96 186 0 1485418768 277379728 20446624
02:53:52 1182 8192000000 10383040528 10795446272 1186401104 12.650 100 95 182 0 1490754352 282702288 21650960
02:54:56 1143 8192000000 10442030848 10848137216 1199665952 12.773 100 100 195 2 1548085008 326441968 21977088
02:56:02 1173 8192000000 10456611808 10882973696 1202626480 12.801 100 99 188 0 1563925040 339666464 21632096
02:57:09 1066 8192000000 10512736272 10947031040 1202135600 12.796 100 97 187 1 1616848592 391934464 22778528
02:58:16 2316 8192000000 10518896848 10956353536 1207228928 12.843 100 79 156 1 1623415840 396736240 19450672
02:59:26 1567 8192000000 10519909968 10963025920 1213786768 12.904 100 88 173 0 1632014256 395687728 22539760
03:00:31 1127 8192000000 10558094480 10995109888 1209591600 12.865 100 87 174 1 1665989904 434841648 21556656
03:01:36 6279 8192000000 10546183760 10996449280 1201093584 12.786 100 66 129 0 1650588512 432905920 16589008
03:02:48 5872 8192000000 10527391072 11024224256 1185123488 12.638 99 62 118 0 1631835936 431394480 15317968
03:03:58 752 8192000000 10562398080 11000565760 1180870080 12.598 100 93 186 2 1665778592 463107824 21800688
03:05:04 5661 8192000000 10534683168 10972680192 1174335264 12.537 100 62 116 1 1642076224 453417712 14323248
03:06:28 7160 8192000000 10505933248 10974388224 1168479824 12.483 100 50 97 3 1621725216 440911664 12333728
03:07:42 4511 8192000000 10446146640 10922192896 1168352848 12.481 100 51 97 1 1554406896 374469792 11584256
03:08:56 22302 8192000000 10310535920 10783350784 1136786832 12.185 98 30 57 0 1414545312 270382656 7375824
03:10:21 32979 8192000000 10327661520 10786426880 1149222208 12.302 100 20 40 0 1436694800 282092128 5380464
03:11:56 23186 8192000000 10247935696 10702135296 1119317216 12.021 98 29 58 0 1346642544 220358880 6966448
03:13:24 2891 8192000000 10196265504 10672852992 1112124192 11.953 98 86 168 4 1293069872 160354896 20590784
03:14:28 4100 8192000000 10264552736 10720542720 1153603552 12.343 100 64 128 1 1362611328 194880032 14127744
03:15:38 11917 8192000000 10222857984 10699862016 1130264432 12.124 98 52 104 0 1313055392 170858080 11932880
03:16:51 120933 8192000000 10256511216 10712858624 1152852784 12.336 100 33 64 2 1354419328 193529552 8036992
03:19:58 15381 8192000000 10229312592 10693259264 1153247248 12.340 100 60 113 0 1339926704 174667200 12012256
03:21:20 69381 8192000000 10202136336 10722816000 1123720144 12.062 98 13 24 0 1287987648 161298656 2968848
03:24:26 94079 8192000000 10109706448 10551246848 1129091088 12.113 99 16 32 0 1189921552 57880480 2949984
03:27:00 112777 8192000000 10130114624 10620084224 1138373520 12.200 99 21 41 0 1251012368 107593040 5045808
03:30:04 90581 8192000000 10068648848 10515369984 1124492112 12.069 98 21 40 1 1162001584 33406704 4102768
03:32:39 76443 8192000000 10097963920 10547707904 1147894608 12.290 100 27 54 0 1198892064 45122688 5874768
03:34:59 6330 8192000000 10125080832 10557599744 1150669168 12.316 100 63 112 3 1222135296 58588976 12877152
03:36:10 73604 8192000000 10122556080 10611408896 1115513056 11.985 97 21 38 0 1203609760 83788512 4308192
03:38:35 20815 8192000000 10089322928 10578419712 1132517664 12.145 99 12 24 1 1180901088 45709984 2673440
03:39:57 34676 8192000000 10061043472 10553319424 1143062544 12.244 100 5 10 0 1177920880 34210992 647344
03:42:57 90393 8192000000 10070741056 10534715392 1129618704 12.118 99 13 26 0 1174565152 42103136 2843312
03:45:34 104951 8192000000 10055631696 10531745792 1102267088 11.859 96 18 36 1 1141912480 36034752 3610640
03:48:20 51079 8192000000 10084990528 10554224640 1145540944 12.268 100 17 32 0 1198354944 49666704 3147296
03:50:36 53123 8192000000 10126600608 10592485376 1150911456 12.318 100 19 38 0 1219321200 64516832 3892912
03:52:36 42812 8192000000 10086744208 10530324480 1126506096 12.088 99 16 31 1 1172664592 43033072 3125424
03:54:19 45827 8192000000 10151397440 10605277184 1136957072 12.187 98 24 48 0 1243992160 101519856 5515232
03:56:06 16864 8192000000 10188836720 10619928576 1147835040 12.289 99 54 108 1 1258016848 97765744 12416064
03:57:30 58599 8192000000 10082846432 10550509568 1106515888 11.899 97 17 34 1 1172623424 62294416 3813120
03:59:32 46458 8192000000 10144282704 10615443456 1130260112 12.124 99 34 68 0 1244139952 106837968 7041872
04:01:18 15903 8192000000 10102738064 10555523072 1108159232 11.915 98 36 63 1 1181402592 66746848 6496512
04:02:36 35146 8192000000 10110600240 10580279296 1131230976 12.133 100 42 84 1 1261714176 121617648 8865552
04:04:15 106948 8192000000 10104299104 10541527040 1113854896 11.969 98 44 88 0 1191290352 68776496 8658960
04:07:03 42600 8192000000 10069532992 10551992320 1102241056 11.859 98 24 48 0 1185270784 77856320 5173408
04:08:53 68996 8192000000 10025488336 10480214016 1071243248 11.564 95 19 37 0 1123376320 47979120 4153952
04:11:05 73820 8192000000 10099976416 10559582208 1119434256 12.022 99 23 45 1 1180247424 56317904 4495264
04:13:22 69642 8192000000 10075971952 10526375936 1111886656 11.950 98 17 35 0 1164946272 49473872 3585744
04:15:33 77615 8192000000 10120451472 10563919872 1106600016 11.900 97 25 48 1 1191533152 78608672 6324464
04:17:50 22879 8192000000 10147496528 10579410944 1102618944 11.862 96 34 68 0 1202195392 92161376 7415072
04:19:27 48916 8192000000 10173705408 10665021440 1142820576 12.242 100 24 48 0 1306369280 157771792 5776912
04:21:19 60297 8192000000 10188585488 10686361600 1124567408 12.070 98 30 60 1 1331571184 200327712 6676064
04:23:22 60172 8192000000 10114053664 10628321280 1118090240 12.009 99 22 44 0 1196281248 73792144 4398864
04:25:30 54874 8192000000 10119943840 10554175488 1130189648 12.123 99 43 79 1 1184462752 45804896 8468208
04:27:30 138522 8192000000 10082928240 10534662144 1116089648 11.990 98 34 66 1 1171069312 48136864 6842800
04:30:50 51171 8192000000 10086998560 10558521344 1113800528 11.968 98 34 66 0 1175506096 54407856 7297712
04:32:43 56728 8192000000 10062336288 10539614208 1130490016 12.126 100 16 30 0 1172319360 38609024 3220320
04:34:40 65324 8192000000 10098815952 10556153856 1113803840 11.968 97 12 24 0 1174136592 57832160 2500592
04:36:47 57367 8192000000 9377122288 9778528256 496859072 5.718 44 7 14 0 594272032 95441696 1971264

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 08-03-2023 04:39:33 to 08-03-2023 04:39:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 76 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-03-2023 04:39:50 to 08-03-2023 04:39:52.

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          207053
MON$OLDEST_ACTIVE               207054
MON$OLDEST_SNAPSHOT             207054
MON$NEXT_TRANSACTION            207054
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                       2106112
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             750
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2906 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2906 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2906 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2906 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 ---
:193: 
:194: mscs-test-161.budzdorov.ru    Wed Mar  8 04:37:50 2023
:195:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:196: 
--- end of diff output ---

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

08.03.23 04:39:53. 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-03-08 04:39:56 No severe PSQL-related problems occured
08.03.23 04:39:57. End of report.