Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.06.2022 07:48
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 15968108 kB
MemAvailable 31379276 kB
Buffers 0 kB
Cached 15758556 kB
SwapCached 2444 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.2
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-06-02 07:47:14
DTS_END                         2022-06-02 07:47:14.2130
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46138
TRN_ID                          211745
ATT_ID                          583
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: 9178.12 28033 1101374
customer order: creation 161.43 479 19372
customer order: refuse 14.32 407 1718
order to supplier: creation 43.53 1493 5223
order to supplier: removal 3.39 173833 407
invoice (draft): creation 43.58 3290 5229
invoice (draft): removal 53.53 1019 6424
invoice accept: apply 40.13 26068 4816
invoice accept: cancel 47.98 1185 5757
customer reserve: creation 3785.47 242 454256
customer reserve: removal 4698.40 95 563808
realization accept: apply 78.26 290 9391
realization accept: cancel 85.43 172 10251
payment from customer: creation 55.42 182 6650
payment from customer: removal 5.28 133 634
payment to supplier: creation 46.11 202 5533
payment to supplier: removal 3.38 173 406
service: total inventory turnovers 3.69 4226 443
service: total monetary turnovers 7.76 310 931
service: aggregate perf. data 1.00 16139 120
service: refresh index statistics 0.04 330723 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-06-2022 07:48:50 to 02-06-2022 07:48:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8557
TEST_TIME 2 6757
TEST_TIME 3 5803
TEST_TIME 4 5114
TEST_TIME 5 5292
TEST_TIME 6 5242
TEST_TIME 7 5422
TEST_TIME 8 6428
TEST_TIME 9 6829
TEST_TIME 10 6934
TEST_TIME 11 7346
TEST_TIME 12 7399
TEST_TIME 13 7313
TEST_TIME 14 7159
TEST_TIME 15 7337
TEST_TIME 16 7315
TEST_TIME 17 7323
TEST_TIME 18 7519
TEST_TIME 19 7773
TEST_TIME 20 7793
TEST_TIME 21 7784
TEST_TIME 22 7879
TEST_TIME 23 7996
TEST_TIME 24 8348
TEST_TIME 25 8482
TEST_TIME 26 8625
TEST_TIME 27 8903
TEST_TIME 28 9092
TEST_TIME 29 9324
TEST_TIME 30 9444
TEST_TIME 31 10162
TEST_TIME 32 10233
TEST_TIME 33 10423
TEST_TIME 34 10574
TEST_TIME 35 10476
TEST_TIME 36 10785
TEST_TIME 37 10873
TEST_TIME 38 10745
TEST_TIME 39 10695
TEST_TIME 40 10628
TEST_TIME 41 10505
TEST_TIME 42 10360
TEST_TIME 43 10442
TEST_TIME 44 10373
TEST_TIME 45 10321
TEST_TIME 46 10291
TEST_TIME 47 10303
TEST_TIME 48 10222
TEST_TIME 49 10223
TEST_TIME 50 10236
TEST_TIME 51 10090
TEST_TIME 52 9966
TEST_TIME 53 9856
TEST_TIME 54 9896
TEST_TIME 55 9801
TEST_TIME 56 9847
TEST_TIME 57 9799
TEST_TIME 58 9787
TEST_TIME 59 9749
TEST_TIME 60 9739
TEST_TIME 61 9721
TEST_TIME 62 9684
TEST_TIME 63 9620
TEST_TIME 64 9618
TEST_TIME 65 9614
TEST_TIME 66 9598
TEST_TIME 67 9583
TEST_TIME 68 9654
TEST_TIME 69 9610
TEST_TIME 70 9626
TEST_TIME 71 9639
TEST_TIME 72 9683
TEST_TIME 73 9623
TEST_TIME 74 9719
TEST_TIME 75 9700
TEST_TIME 76 9799
TEST_TIME 77 9761
TEST_TIME 78 9808
TEST_TIME 79 9756
TEST_TIME 80 9774
TEST_TIME 81 9767
TEST_TIME 82 9661
TEST_TIME 83 9666
TEST_TIME 84 9627
TEST_TIME 85 9571
TEST_TIME 86 9548
TEST_TIME 87 9493
TEST_TIME 88 9489
TEST_TIME 89 9427
TEST_TIME 90 9429
TEST_TIME 91 9341
TEST_TIME 92 9317
TEST_TIME 93 9364
TEST_TIME 94 9328
TEST_TIME 95 9344
TEST_TIME 96 9314
TEST_TIME 97 9324
TEST_TIME 98 9336
TEST_TIME 99 9366
TEST_TIME 100 9331
TEST_TIME 101 9307
TEST_TIME 102 9356
TEST_TIME 103 9428
TEST_TIME 104 9526
TEST_TIME 105 9508
TEST_TIME 106 9544
TEST_TIME 107 9536
TEST_TIME 108 9460
TEST_TIME 109 9415
TEST_TIME 110 9376
TEST_TIME 111 9366
TEST_TIME 112 9335
TEST_TIME 113 9284
TEST_TIME 114 9289
TEST_TIME 115 9233
TEST_TIME 116 9198
TEST_TIME 117 9190
TEST_TIME 118 9253
TEST_TIME 119 9268
TEST_TIME 120 9201

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 02-06-2022 07:48:53 to 02-06-2022 07:48:57.

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 1519864 1519864 0 0.00 0 18560 13 0 0
doc_list_biud 1519843 1519843 0 0.00 0 92312 42 0 0
sp_add_invoice_to_stock 4924 4816 108 2.19 53 180558 26068 0 108
sp_cancel_adding_invoice 5757 5757 0 0.00 7 106944 1185 0 0
sp_cancel_client_order 1718 1718 0 0.00 6 9142 407 0 0
sp_cancel_customer_reserve 563808 563808 0 0.00 2 27574 96 0 0
sp_cancel_pay_from_customer 634 634 0 0.00 4 8066 134 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 5 11082 173 0 0
sp_cancel_supplier_invoice 6431 6424 7 0.10 3 92324 1019 0 7
sp_cancel_supplier_order 407 407 0 0.00 19 1268309 173834 0 0
sp_cancel_write_off 10251 10251 0 0.00 2 15758 172 0 0
sp_client_order 19372 19372 0 0.00 6 36662 480 0 0
sp_customer_reserve 454256 454256 0 0.00 1 86878 243 0 0
sp_fill_shopping_cart 29808 29808 0 0.00 2 10365 87 0 0
sp_fill_shopping_cart_clo_res 454218 454218 0 0.00 0 14653 19 0 0
sp_get_clo_for_invoice 4777 4777 0 0.00 0 29559 300 0 0
sp_kill_cost_storno 17048 17048 0 0.00 0 5749 79 0 0
sp_kill_qstorno_ret_qs2qd 1144698 1144698 0 0.00 0 92304 42 0 0
sp_kill_qty_storno 602532 602532 0 0.00 0 92309 97 0 0
sp_lock_selected_doc 19664 19664 0 0.00 0 10380 9 0 0
sp_make_cost_storno 26351 26351 0 0.00 0 18483 86 0 0
sp_multiply_rows_for_pdistr 26351 26351 0 0.00 0 6654 40 0 0
sp_multiply_rows_for_qdistr 19372 19372 0 0.00 0 16609 177 0 0
sp_pay_from_customer 6650 6650 0 0.00 6 13489 183 0 0
sp_pay_to_supplier 5533 5533 0 0.00 7 9579 203 0 0
sp_payment_common 12183 12183 0 0.00 2 9563 46 0 0
sp_qd_handle_on_cancel_clo 1718 1718 0 0.00 0 7227 333 0 0
sp_qd_handle_on_invoice_upd_sts 10534 10534 0 0.00 0 24737 560 0 0
sp_qd_handle_on_reserve_upd_sts 9390 9390 0 0.00 0 16881 61 0 0
sp_reserve_write_off 9391 9391 0 0.00 9 19941 290 0 0
sp_supplier_invoice 5229 5229 0 0.00 15 80424 3291 0 0
sp_supplier_order 5223 5223 0 0.00 8 90858 1494 0 0
srv_aggregate_perf_data 120 120 0 0.00 2059 238774 16140 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 789 140502 34522 0 0
srv_find_qd_qs_mism 1054612 1054612 0 0.00 0 14702 9 0 0
srv_make_invnt_saldo 443 443 0 0.00 69 139459 4227 0 0
srv_make_money_saldo 931 931 0 0.00 1 9813 310 0 0
srv_recalc_idx_stat 5 5 0 0.00 96439 537238 330723 0 0
t$perf-abend:sp_add_invoice_to_stock 108 108 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4817 4817 0 0.00 1 12539 1003 0 0
t$perf-norm:sp_cancel_adding_invoice 380 380 0 0.00 2 7527 479 0 0
t$perf-norm:sp_cancel_client_order 1718 1718 0 0.00 0 6398 38 0 0
t$perf-norm:sp_cancel_customer_reserve 621 621 0 0.00 0 4190 46 0 0
t$perf-norm:sp_cancel_pay_from_customer 634 634 0 0.00 0 362 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 733 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 4214 54 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 101188 7933 0 0
t$perf-norm:sp_cancel_write_off 925 925 0 0.00 0 2857 18 0 0
t$perf-norm:sp_client_order 19372 19372 0 0.00 0 5825 39 0 0
t$perf-norm:sp_customer_reserve 929 929 0 0.00 0 2276 21 0 0
t$perf-norm:sp_pay_from_customer 6650 6650 0 0.00 0 3941 20 0 0
t$perf-norm:sp_pay_to_supplier 5533 5533 0 0.00 0 9737 24 0 0
t$perf-norm:sp_reserve_write_off 9391 9391 0 0.00 0 5971 24 0 0
t$perf-norm:sp_supplier_invoice 5229 5229 0 0.00 0 6070 38 0 0
t$perf-norm:sp_supplier_order 5223 5223 0 0.00 0 3513 36 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 1079 35 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 30 3 0 0
t$perf-norm:srv_make_invnt_saldo 443 443 0 0.00 0 734 16 0 0
t$perf-norm:srv_make_money_saldo 931 931 0 0.00 0 4342 10 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 212 94 0 0
v_add_invoice_to_stock 9764 9548 216 2.21 0 4263 26 0 216
v_all_customers 35894 35894 0 0.00 0 208 4 0 0
v_all_suppliers 29092 29092 0 0.00 0 76 4 0 0
v_all_wares 38730 38730 0 0.00 0 322 4 0 0
v_cancel_adding_invoice 760 760 0 0.00 0 6959 25 0 0
v_cancel_client_order 3436 3436 0 0.00 0 613 8 0 0
v_cancel_customer_prepayment 1268 1268 0 0.00 0 8015 20 0 0
v_cancel_customer_reserve 1242 1242 0 0.00 1 1778 12 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 3553 16 0 0
v_cancel_supplier_invoice 832 818 14 1.68 0 2144 25 0 14
v_cancel_supplier_order 814 814 0 0.00 0 341 11 0 0
v_cancel_write_off 1850 1850 0 0.00 0 3252 25 0 0
v_max_id_clo_ord 5221 5221 0 0.00 0 1462 6 0 0
v_max_id_clo_res 929 929 0 0.00 0 2940 13 0 0
v_max_id_ord_sup 5222 5222 0 0.00 0 6311 7 0 0
v_max_non_paid_invoice 1430 1430 0 0.00 0 59 8 0 0
v_max_non_paid_realizn 3350 3350 0 0.00 0 2771 8 0 0
v_min_id_clo_ord 5221 5221 0 0.00 0 8356 11 0 0
v_min_id_clo_res 929 929 0 0.00 0 64 6 0 0
v_min_id_ord_sup 5222 5222 0 0.00 0 9242 10 0 0
v_min_non_paid_invoice 5533 5533 0 0.00 0 5362 16 0 0
v_min_non_paid_realizn 6650 6650 0 0.00 0 4867 15 0 0
v_our_firm 9436 9436 0 0.00 0 76 5 0 0
v_reserve_write_off 18780 18780 0 0.00 0 7063 12 0 0
x_lock_depdocs_on_canc_invoice 11772 11772 0 0.00 0 17521 133 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 3096 374 0 0
x_make_qstorno_1000_1200 5223 5223 0 0.00 2 90696 1306 0 0
x_make_qstorno_1200_2000 5229 5229 0 0.00 6 80411 3071 0 0
x_make_qstorno_2100_3300 454256 454256 0 0.00 0 86874 211 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-06-2022 07:48:58 to 02-06-2022 07:49:02.

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:17:18 924 8192000000 8926764448 8981397504 10794080 0.131 3 2 4 0 11200176 96016 310080
05:18:20 25148 8192000000 9724454864 10022326272 787210896 8.767 100 83 166 1 814726016 7231760 20283360
05:19:49 27252 8192000000 9755747728 10060881920 822229840 9.121 100 92 184 0 857470208 12985024 22255344
05:21:25 18067 8192000000 9792388960 10109759488 847901232 9.379 100 80 160 1 889118864 22881744 18335888
05:22:50 9644 8192000000 9871791056 10200965120 908359216 9.981 100 86 172 0 961402624 34938720 18104688
05:24:04 5660 8192000000 9940370896 10281312256 947362848 10.365 100 93 181 0 1043656400 77676816 18616736
05:25:12 2063 8192000000 9965806848 10318487552 983538096 10.719 100 89 175 1 1079453728 78245536 17670096
05:26:16 1111 8192000000 9997789472 10358050816 1006516080 10.942 100 94 183 1 1110424672 85802400 18106192
05:27:19 913 8192000000 10007755184 10375630848 1023700144 11.108 100 96 189 0 1116972608 74873040 18399424
05:28:21 1100 8192000000 10043993536 10418241536 1047233344 11.334 100 97 187 0 1157268752 91068448 18966960
05:29:23 1138 8192000000 10032772464 10411941888 1051822272 11.378 100 94 182 2 1147332128 76613280 18896576
05:30:25 1193 8192000000 10055310288 10438033408 1061521664 11.471 100 94 181 2 1169327408 89760816 18044928
05:31:27 2824 8192000000 10011097696 10399678464 1071207328 11.564 100 75 128 3 1124265216 40457520 12600368
05:32:30 1063 8192000000 10052139760 10436141056 1075000432 11.600 100 98 190 1 1160056320 67475040 17580848
05:33:33 1047 8192000000 10036599120 10422108160 1077970224 11.628 100 87 163 3 1146409552 52194832 16244496
05:34:34 1201 8192000000 10053835088 10442792960 1082634320 11.673 100 93 177 0 1163577888 61778784 19164784
05:35:36 1177 8192000000 10063272736 10448863232 1082587200 11.672 100 97 191 1 1168555024 66989968 18977856
05:36:39 1173 8192000000 10055094224 10444566528 1088459040 11.728 100 93 180 1 1165626224 58270912 18896272
05:37:41 8209 8192000000 10032518784 10433486848 1090440032 11.747 100 69 134 0 1158952656 54607904 13904720
05:38:50 955 8192000000 10026384944 10416459776 1065673136 11.511 98 81 156 0 1144813776 62803616 16337024
05:39:52 946 8192000000 10036296880 10426179584 1086340944 11.708 100 81 148 0 1160502336 58097936 16063456
05:40:57 1286 8192000000 10021662368 10402684928 1066629904 11.520 100 97 187 2 1145414752 59107968 19676880
05:41:59 903 8192000000 10016433648 10403934208 1068665984 11.539 100 74 137 2 1137845984 54841248 14338752
05:43:01 1024 8192000000 9994177536 10366795776 1034639536 11.213 99 88 168 1 1115131136 62683632 17807968
05:44:03 1245 8192000000 9987907664 10358267904 1030158848 11.170 100 91 176 3 1112923104 64087968 18676288
05:45:04 1827 8192000000 9984934624 10369646592 1047317664 11.335 100 71 135 0 1113578480 51366864 14893952
05:46:07 966 8192000000 9946536800 10317004800 1018918272 11.062 100 94 158 2 1070920912 31735024 20267616
05:47:09 2727 8192000000 10001884512 10382860288 1051381456 11.374 100 81 147 1 1121806416 55962512 14462448
05:48:17 2681 8192000000 10029667424 10417221632 1074875888 11.599 100 66 128 0 1145193072 57164544 13152640
05:49:27 13518 8192000000 10050806384 10446647296 1090950256 11.752 100 72 138 0 1169039680 63381312 14708112
05:50:44 6055 8192000000 10074838944 10465628160 1101932768 11.856 100 70 134 0 1200848672 84438000 14477904
05:51:54 2916 8192000000 10126090448 10516082688 1107983040 11.913 100 80 154 0 1245371072 122020576 15367456
05:53:16 9132 8192000000 10131193936 10539737088 1121756816 12.044 100 47 90 1 1255956176 123747824 10451536
05:54:29 890 8192000000 10211273696 10603356160 1129897904 12.120 100 85 163 3 1326898336 178056416 18944016
05:55:34 1143 8192000000 10211279344 10628976640 1144646720 12.259 100 90 165 2 1334054176 170927040 18480416
05:56:39 1395 8192000000 10244035344 10653687808 1160916016 12.412 100 83 163 0 1356501664 177483760 18101888
05:57:44 1455 8192000000 10256436992 10676060160 1163727344 12.438 100 90 176 1 1368209408 185790400 18691664
05:58:49 1073 8192000000 10281674672 10683985920 1182394992 12.613 100 95 184 0 1392581920 189211488 20975440
05:59:54 3034 8192000000 10318158976 10731569152 1180960576 12.599 100 88 174 0 1436253232 234601056 20691600
06:01:02 1169 8192000000 10369596960 10776264704 1196512512 12.744 100 96 184 2 1478002688 260520224 20969952
06:02:08 1019 8192000000 10402090528 10830602240 1204459920 12.818 100 97 194 1 1515907040 289180416 22266704
06:03:13 972 8192000000 10421880912 10834112512 1199325424 12.770 99 96 186 0 1530611744 308681664 22604656
06:04:19 1732 8192000000 10422439136 10858823680 1215732816 12.922 100 88 173 2 1532701056 296698480 20269760
06:05:25 1526 8192000000 10447771904 10878230528 1215589200 12.921 100 90 178 0 1554679504 317075440 22014864
06:06:32 1198 8192000000 10470675344 10909818880 1200978448 12.785 100 93 184 1 1574321664 351763680 21579536
06:07:38 780 8192000000 10507116480 10931675136 1205065328 12.823 100 92 181 3 1613414928 386077632 22271968
06:08:42 1748 8192000000 10551677120 10978250752 1195331856 12.733 100 89 172 0 1656834032 439143008 22359168
06:09:49 967 8192000000 10539853024 10989850624 1189356208 12.677 100 92 173 0 1640133200 430302608 20474384
06:10:53 927 8192000000 10541826032 11023110144 1203431136 12.808 100 93 180 0 1649828272 424914144 21482992
06:11:58 1041 8192000000 10526961760 10975027200 1200064816 12.777 100 88 171 1 1631836576 411857392 19914368
06:13:02 5141 8192000000 10522591504 10991570944 1205274448 12.825 100 66 126 0 1628373104 407413648 15685008
06:14:13 1322 8192000000 10548834384 11025874944 1209310688 12.863 100 67 131 1 1648358048 421566848 17480512
06:15:24 10320 8192000000 10515037792 10973151232 1203553648 12.809 100 46 88 1 1613057184 398159488 11344048
06:16:36 23405 8192000000 10455731008 10901225472 1167684432 12.475 98 39 78 1 1571912608 393799664 10428512
06:18:10 21096 8192000000 10346397616 10887049216 1159398048 12.398 99 36 73 1 1503308720 334742512 9168160
06:19:51 34502 8192000000 10248968912 10739122176 1169857728 12.495 100 26 52 1 1365481856 188853264 6770864
06:21:49 37081 8192000000 10246122976 10691072000 1158266944 12.387 99 28 56 1 1326337184 161570560 6499680
06:23:30 30930 8192000000 10147135248 10660474880 1155998960 12.366 100 17 35 0 1266017200 106198784 3819456
06:25:03 13739 8192000000 10086928816 10515836928 1127712688 12.100 98 64 108 3 1159881600 21140496 11028416
06:26:20 87825 8192000000 10102452608 10580365312 1150866528 12.318 100 18 34 1 1230232688 75529440 3836720
06:29:24 70970 8192000000 10085196672 10565492736 1075662640 11.606 94 19 38 0 1152754368 72526704 4565024
06:31:37 60900 8192000000 10073916560 10516291584 1100318832 11.841 98 27 54 0 1137740496 32035424 5386240
06:33:46 52451 8192000000 10068520752 10544242688 1110380192 11.936 98 14 28 0 1166919872 53684176 2855504
06:35:40 72761 8192000000 10127247584 10595688448 1137350880 12.191 100 27 50 1 1225355248 80831072 7173296
06:38:45 99324 8192000000 10130157232 10579263488 1124166128 12.066 98 41 82 0 1202301600 68780160 9355312
06:41:25 59205 8192000000 10088227536 10566184960 1126953584 12.093 99 12 24 0 1202032240 72215760 2862896
06:43:30 7058 8192000000 10127179120 10555359232 1127231376 12.095 100 71 142 2 1217636000 75652112 14752512
06:45:18 31772 8192000000 10102219456 10541096960 1137852096 12.195 100 26 52 1 1200533168 56491712 6189360
06:46:54 95234 8192000000 10120907488 10589483008 1119652272 12.024 98 26 52 0 1210686896 85450160 5584464
06:50:11 91692 8192000000 10152310448 10650542080 1150187344 12.311 100 17 34 1 1278233680 124218800 3827536
06:52:46 41690 8192000000 10098807104 10568278016 1137036656 12.188 99 32 61 1 1216999696 72839456 7123584
06:54:40 67768 8192000000 10069714608 10571382784 1101648864 11.853 96 15 26 1 1173633568 69066944 2917760
06:56:50 22210 8192000000 10149731632 10608029696 1153943296 12.346 100 38 76 2 1220001568 57822240 8236032
06:58:12 44340 8192000000 10117256032 10580934656 1114577312 11.976 97 25 50 0 1192863920 73303680 4982928
07:00:00 61541 8192000000 10139462064 10590859264 1102626976 11.863 96 32 64 1 1182389904 72965232 6797696
07:02:07 28994 8192000000 10125062560 10566541312 1104613856 11.881 98 40 80 0 1199710448 87041952 8054640
07:03:36 57260 8192000000 10107017520 10545012736 1090727584 11.750 97 20 40 2 1169704304 74885008 4091712
07:05:49 31274 8192000000 10082230976 10506637312 1120040032 12.027 99 34 66 1 1163353296 35636720 7676544
07:07:25 45760 8192000000 10104689456 10545369088 1117004800 11.999 99 37 74 0 1194483024 69961648 7516576
07:09:11 140501 8192000000 10075028960 10552164352 1126820288 12.091 99 21 42 0 1185895696 54737360 4338048
07:12:37 29228 8192000000 10087493504 10534756352 1137749424 12.194 100 44 87 0 1173189360 26586368 8853568
07:15:03 72653 8192000000 10126809632 10608066560 1144430144 12.257 99 17 34 0 1215300128 67129600 3740384
07:17:27 43830 8192000000 10068008752 10562289664 1108545424 11.919 97 11 22 4 1183448640 72085520 2817696
07:19:11 76225 8192000000 10062602896 10567073792 1065401952 11.508 94 20 40 1 1129125712 59260400 4463360
07:21:30 50493 8192000000 10057177872 10527199232 1084639712 11.692 97 24 46 1 1147001072 57419008 4942352
07:23:23 29455 8192000000 10125139488 10569908224 1125156352 12.076 100 51 95 1 1201037424 66235936 9645136
07:24:57 42146 8192000000 10135215248 10605027328 1136026384 12.178 100 23 46 1 1237716000 96529056 5160560
07:26:47 64339 8192000000 10140829392 10584125440 1105785424 11.892 97 19 38 1 1208661088 98924688 3950976
07:28:53 53306 8192000000 10184526768 10626449408 1117336640 12.002 98 20 40 0 1250007568 128354608 4316320
07:30:51 55455 8192000000 10068107248 10530754560 1090778112 11.750 96 22 44 0 1223844512 128563744 4502656
07:32:49 7726 8192000000 10101315952 10525753344 1136855248 12.186 100 54 108 1 1175705184 28208592 10641344
07:34:03 103796 8192000000 10071606912 10537926656 1123678704 12.062 99 20 38 2 1183406000 55763824 3963472
07:36:48 30216 8192000000 10057077840 10478452736 1107872000 11.912 98 57 104 2 1141387392 23298848 10216544
07:38:20 56357 8192000000 10096799920 10554220544 1128695168 12.109 99 14 28 0 1189149008 57264096 3189744
07:41:24 47663 8192000000 10121028704 10595352576 1109975200 11.932 97 10 20 1 1193182512 80933056 2274256
07:44:49 79941 8192000000 10107751952 10587877376 1139832128 12.214 100 18 32 0 1213416192 70048464 3535600

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 02-06-2022 07:49:04 to 02-06-2022 07:49:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 108 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-06-2022 07:49:20 to 02-06-2022 07:49:22.

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          211335
MON$OLDEST_ACTIVE               212944
MON$OLDEST_SNAPSHOT             212944
MON$NEXT_TRANSACTION            212944
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                       2084640
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             762
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2772 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2772 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2772 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2772 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Thu Jun  2 07:47:26 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Thu Jun  2 07:47:30 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
--- end of diff output ---

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

02.06.22 07:49:24. 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-06-02 07:49:26 No severe PSQL-related problems occured
02.06.22 07:49:27. End of report.