Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.01.2022 22: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 16624740 kB
MemAvailable 31265124 kB
Buffers 0 kB
Cached 15016148 kB
SwapCached 1220 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 3.0.9
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-01-07 22:38:01
DTS_END                         2022-01-07 22:38:01.1160
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/60028
TRN_ID                          235186
ATT_ID                          692
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: 9293.95 27145 1115274
customer order: creation 165.73 368 19887
customer order: refuse 14.16 336 1699
order to supplier: creation 44.74 1312 5369
order to supplier: removal 3.46 96729 415
invoice (draft): creation 44.85 3398 5382
invoice (draft): removal 53.98 739 6477
invoice accept: apply 40.63 18161 4876
invoice accept: cancel 48.93 750 5872
customer reserve: creation 3754.93 180 450592
customer reserve: removal 4837.64 54 580517
realization accept: apply 80.87 169 9704
realization accept: cancel 77.84 115 9341
payment from customer: creation 57.68 105 6922
payment from customer: removal 5.40 81 648
payment to supplier: creation 47.72 115 5726
payment to supplier: removal 3.31 49 397
service: total inventory turnovers 4.82 1049 578
service: total monetary turnovers 6.42 29 770
service: aggregate perf. data 0.82 17583 98
service: refresh index statistics 0.03 401572 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-01-2022 22:38:47 to 07-01-2022 22:38:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6575
TEST_TIME 2 6219
TEST_TIME 3 6592
TEST_TIME 4 6011
TEST_TIME 5 6020
TEST_TIME 6 5746
TEST_TIME 7 5569
TEST_TIME 8 5578
TEST_TIME 9 5496
TEST_TIME 10 5724
TEST_TIME 11 6248
TEST_TIME 12 6420
TEST_TIME 13 7047
TEST_TIME 14 7249
TEST_TIME 15 7555
TEST_TIME 16 7796
TEST_TIME 17 8555
TEST_TIME 18 9124
TEST_TIME 19 9530
TEST_TIME 20 9411
TEST_TIME 21 9978
TEST_TIME 22 10088
TEST_TIME 23 10037
TEST_TIME 24 10190
TEST_TIME 25 10308
TEST_TIME 26 10149
TEST_TIME 27 10141
TEST_TIME 28 10447
TEST_TIME 29 10440
TEST_TIME 30 10549
TEST_TIME 31 10375
TEST_TIME 32 10191
TEST_TIME 33 10219
TEST_TIME 34 10074
TEST_TIME 35 9865
TEST_TIME 36 9832
TEST_TIME 37 9860
TEST_TIME 38 9661
TEST_TIME 39 9637
TEST_TIME 40 9725
TEST_TIME 41 9683
TEST_TIME 42 9557
TEST_TIME 43 9556
TEST_TIME 44 9683
TEST_TIME 45 9648
TEST_TIME 46 9650
TEST_TIME 47 9701
TEST_TIME 48 9626
TEST_TIME 49 9553
TEST_TIME 50 9753
TEST_TIME 51 9705
TEST_TIME 52 9724
TEST_TIME 53 9656
TEST_TIME 54 9549
TEST_TIME 55 9457
TEST_TIME 56 9464
TEST_TIME 57 9366
TEST_TIME 58 9394
TEST_TIME 59 9320
TEST_TIME 60 9306
TEST_TIME 61 9269
TEST_TIME 62 9303
TEST_TIME 63 9167
TEST_TIME 64 9316
TEST_TIME 65 9397
TEST_TIME 66 9369
TEST_TIME 67 9334
TEST_TIME 68 9375
TEST_TIME 69 9528
TEST_TIME 70 9501
TEST_TIME 71 9529
TEST_TIME 72 9694
TEST_TIME 73 9648
TEST_TIME 74 9606
TEST_TIME 75 9695
TEST_TIME 76 9733
TEST_TIME 77 9696
TEST_TIME 78 9654
TEST_TIME 79 9584
TEST_TIME 80 9532
TEST_TIME 81 9474
TEST_TIME 82 9497
TEST_TIME 83 9487
TEST_TIME 84 9427
TEST_TIME 85 9414
TEST_TIME 86 9340
TEST_TIME 87 9263
TEST_TIME 88 9338
TEST_TIME 89 9329
TEST_TIME 90 9313
TEST_TIME 91 9306
TEST_TIME 92 9311
TEST_TIME 93 9355
TEST_TIME 94 9426
TEST_TIME 95 9445
TEST_TIME 96 9448
TEST_TIME 97 9397
TEST_TIME 98 9395
TEST_TIME 99 9490
TEST_TIME 100 9465
TEST_TIME 101 9549
TEST_TIME 102 9528
TEST_TIME 103 9501
TEST_TIME 104 9448
TEST_TIME 105 9423
TEST_TIME 106 9371
TEST_TIME 107 9395
TEST_TIME 108 9387
TEST_TIME 109 9352
TEST_TIME 110 9327
TEST_TIME 111 9292
TEST_TIME 112 9280
TEST_TIME 113 9329
TEST_TIME 114 9340
TEST_TIME 115 9309
TEST_TIME 116 9330
TEST_TIME 117 9338
TEST_TIME 118 9358
TEST_TIME 119 9375
TEST_TIME 120 9298

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-01-2022 22:38:51 to 07-01-2022 22:38:54.

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 1532786 1532786 0 0.00 0 24388 6 0 0
doc_list_biud 1532785 1532785 0 0.00 0 21264 26 0 0
sp_add_invoice_to_stock 4938 4876 62 1.25 36 184185 18162 0 62
sp_cancel_adding_invoice 5872 5872 0 0.00 3 52128 751 0 0
sp_cancel_client_order 1699 1699 0 0.00 4 16284 337 0 0
sp_cancel_customer_reserve 580517 580517 0 0.00 1 21268 54 0 0
sp_cancel_pay_from_customer 648 648 0 0.00 3 7546 82 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 3 2081 50 0 0
sp_cancel_supplier_invoice 6481 6477 4 0.06 1 17631 739 0 4
sp_cancel_supplier_order 415 415 0 0.00 9 831283 96730 0 0
sp_cancel_write_off 9341 9341 0 0.00 1 14736 115 0 0
sp_client_order 19887 19887 0 0.00 3 44939 369 0 0
sp_customer_reserve 450592 450592 0 0.00 0 64900 181 0 0
sp_fill_shopping_cart 30614 30614 0 0.00 1 11430 36 0 0
sp_fill_shopping_cart_clo_res 450587 450587 0 0.00 0 18612 14 0 0
sp_get_clo_for_invoice 4870 4870 0 0.00 0 22180 319 0 0
sp_kill_cost_storno 16258 16258 0 0.00 0 16060 50 0 0
sp_kill_qstorno_ret_qs2qd 1177267 1177267 0 0.00 0 21261 30 0 0
sp_kill_qty_storno 618895 618895 0 0.00 0 21264 64 0 0
sp_lock_selected_doc 20248 20248 0 0.00 0 9360 5 0 0
sp_make_cost_storno 27222 27222 0 0.00 0 24263 54 0 0
sp_multiply_rows_for_pdistr 27222 27222 0 0.00 0 6085 24 0 0
sp_multiply_rows_for_qdistr 19887 19887 0 0.00 0 44918 168 0 0
sp_pay_from_customer 6922 6922 0 0.00 4 20549 105 0 0
sp_pay_to_supplier 5726 5726 0 0.00 4 25175 116 0 0
sp_payment_common 12648 12648 0 0.00 1 25163 24 0 0
sp_qd_handle_on_cancel_clo 1699 1699 0 0.00 0 10673 298 0 0
sp_qd_handle_on_invoice_upd_sts 10742 10742 0 0.00 0 17551 309 0 0
sp_qd_handle_on_reserve_upd_sts 9704 9704 0 0.00 0 11337 56 0 0
sp_reserve_write_off 9704 9704 0 0.00 6 11935 169 0 0
sp_supplier_invoice 5382 5382 0 0.00 6 70172 3398 0 0
sp_supplier_order 5369 5369 0 0.00 5 50758 1313 0 0
srv_aggregate_perf_data 98 98 0 0.00 3036 87700 17584 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1367 168093 43296 0 0
srv_find_qd_qs_mism 1069005 1069005 0 0.00 0 14141 6 0 0
srv_make_invnt_saldo 578 578 0 0.00 20 39984 1049 0 0
srv_make_money_saldo 770 770 0 0.00 1 5104 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 194312 629727 401573 0 0
t$perf-abend:sp_add_invoice_to_stock 62 62 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4876 4876 0 0.00 0 10934 156 0 0
t$perf-norm:sp_cancel_adding_invoice 431 431 0 0.00 1 3840 102 0 0
t$perf-norm:sp_cancel_client_order 1699 1699 0 0.00 0 3489 12 0 0
t$perf-norm:sp_cancel_customer_reserve 673 673 0 0.00 0 4873 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 648 648 0 0.00 0 4380 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 3176 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 378 378 0 0.00 0 7055 61 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 24937 1709 0 0
t$perf-norm:sp_cancel_write_off 1033 1033 0 0.00 0 1346 9 0 0
t$perf-norm:sp_client_order 19887 19887 0 0.00 0 9959 19 0 0
t$perf-norm:sp_customer_reserve 1055 1055 0 0.00 0 745 3 0 0
t$perf-norm:sp_pay_from_customer 6922 6922 0 0.00 0 8391 8 0 0
t$perf-norm:sp_pay_to_supplier 5726 5726 0 0.00 0 8109 11 0 0
t$perf-norm:sp_reserve_write_off 9704 9704 0 0.00 0 5847 8 0 0
t$perf-norm:sp_supplier_invoice 5382 5382 0 0.00 0 11433 23 0 0
t$perf-norm:sp_supplier_order 5369 5369 0 0.00 0 7663 20 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 148 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 247 5 0 0
t$perf-norm:srv_make_invnt_saldo 578 578 0 0.00 0 5385 29 0 0
t$perf-norm:srv_make_money_saldo 770 770 0 0.00 0 1453 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 194 50 0 0
v_add_invoice_to_stock 9864 9740 124 1.25 0 5606 17 0 124
v_all_customers 36184 36184 0 0.00 0 15 0 0 0
v_all_suppliers 29990 29990 0 0.00 0 14 1 0 0
v_all_wares 39774 39774 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 862 862 0 0.00 0 795 8 0 0
v_cancel_client_order 3398 3398 0 0.00 0 3855 8 0 0
v_cancel_customer_prepayment 1296 1296 0 0.00 0 1641 8 0 0
v_cancel_customer_reserve 1346 1346 0 0.00 0 868 4 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 2046 8 0 0
v_cancel_supplier_invoice 764 756 8 1.04 0 4950 25 0 8
v_cancel_supplier_order 830 830 0 0.00 0 1285 7 0 0
v_cancel_write_off 2066 2066 0 0.00 0 3345 14 0 0
v_max_id_clo_ord 5369 5369 0 0.00 0 1420 2 0 0
v_max_id_clo_res 1055 1055 0 0.00 0 3052 17 0 0
v_max_id_ord_sup 5358 5358 0 0.00 0 4921 5 0 0
v_max_non_paid_invoice 1458 1458 0 0.00 0 902 3 0 0
v_max_non_paid_realizn 3832 3832 0 0.00 0 695 3 0 0
v_min_id_clo_ord 5369 5369 0 0.00 0 2379 7 0 0
v_min_id_clo_res 1055 1055 0 0.00 0 6806 11 0 0
v_min_id_ord_sup 5358 5358 0 0.00 0 9625 11 0 0
v_min_non_paid_invoice 5726 5726 0 0.00 0 25159 17 0 0
v_min_non_paid_realizn 6922 6922 0 0.00 0 20500 18 0 0
v_our_firm 9770 9770 0 0.00 0 10 1 0 0
v_reserve_write_off 19408 19408 0 0.00 0 3744 6 0 0
x_lock_depdocs_on_canc_invoice 11971 11971 0 0.00 0 27246 122 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 16019 368 0 0
x_make_qstorno_1000_1200 5369 5369 0 0.00 2 50721 1234 0 0
x_make_qstorno_1200_2000 5382 5382 0 0.00 3 70149 3269 0 0
x_make_qstorno_2100_3300 450592 450592 0 0.00 0 64899 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-01-2022 22:38:55 to 07-01-2022 22:38:59.

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
20:08:04 27 8192000000 8807199664 8821248000 10197008 0.124 4 1 2 0 10299328 59536 42784
20:09:05 21488 8192000000 9889240848 10201157632 975772320 10.643 100 86 172 0 1004516656 5976336 22768000
20:10:32 9306 8192000000 9943687216 10267324416 1030979776 11.178 100 89 178 0 1062290752 8230672 23080304
20:11:47 7541 8192000000 9985833216 10316820480 1065196208 11.506 100 87 174 0 1096626544 9906848 21523488
20:12:59 14771 8192000000 10027752816 10374791168 1105545408 11.890 100 76 152 3 1135186272 11592288 18048576
20:14:16 9793 8192000000 10062133712 10416984064 1149461088 12.304 100 79 159 1 1180210880 12749968 17999824
20:15:27 6244 8192000000 10094077344 10460098560 1182360816 12.612 100 76 152 1 1214070368 13806496 17903056
20:16:36 4428 8192000000 10145319152 10519826432 1234431616 13.095 100 75 147 2 1261841536 10682400 16727520
20:17:42 2318 8192000000 10186622528 10564993024 1275008976 13.467 100 90 180 1 1308745280 13618848 20117456
20:18:46 2686 8192000000 10206669616 10592366592 1293397760 13.635 100 78 157 2 1323837424 13427136 17012528
20:19:49 2603 8192000000 10207195888 10609057792 1308299408 13.771 100 57 114 1 1326959792 5603728 13056656
20:20:53 6850 8192000000 10214365840 10619445248 1316653488 13.846 100 43 86 1 1333840384 7800704 9386192
20:22:04 9918 8192000000 10204725440 10624180224 1322783264 13.902 100 19 38 0 1332816864 5550272 4483328
20:23:16 32786 8192000000 10245815040 10646777856 1329044432 13.959 100 60 120 1 1348824064 5427008 14352624
20:24:49 8150 8192000000 10229718304 10643062784 1332676336 13.991 100 25 49 2 1343920704 5680880 5563488
20:25:58 8427 8192000000 10227244352 10648735744 1335270256 14.015 100 22 40 2 1345906480 5059536 5576688
20:27:09 65076 8192000000 10214078480 10621775872 1282340512 13.534 96 49 98 1 1299970208 5341632 12288064
20:29:16 5586 8192000000 10187689712 10597408768 1263732384 13.364 97 22 44 3 1274404992 5996592 4676016
20:30:26 10995 8192000000 10135679616 10538270720 1186037392 12.646 94 18 36 3 1196061520 5702000 4322128
20:31:38 8544 8192000000 10138862784 10541084672 1236112992 13.110 99 20 40 1 1246083920 4908448 5062480
20:32:51 43916 8192000000 10155546016 10563756032 1244764448 13.190 98 24 48 0 1256444064 6327280 5352336
20:34:42 8026 8192000000 10190469296 10597109760 1290912992 13.613 100 54 108 3 1310502048 7102880 12486176
20:35:51 7953 8192000000 10195348368 10605875200 1300131472 13.696 100 20 40 1 1310466320 5249936 5084912
20:36:59 12668 8192000000 10201196320 10618519552 1311997712 13.804 100 18 36 0 1322173840 6343792 3832336
20:38:12 43367 8192000000 10232353584 10646458368 1334080896 14.004 100 36 72 1 1348009600 5247856 8680848
20:40:00 10419 8192000000 10261858592 10676613120 1355399856 14.196 100 45 91 0 1374831776 9491504 9940416
20:41:17 8006 8192000000 10285326592 10704527360 1373539952 14.359 100 44 88 0 1394258080 10696080 10022048
20:42:29 17468 8192000000 10308612416 10730250240 1391475936 14.519 100 60 118 2 1421748640 16404656 13868048
20:43:53 22985 8192000000 10318732864 10743013376 1397199776 14.570 100 42 84 0 1423585984 16483168 9903040
20:45:23 8398 8192000000 10341470256 10760085504 1408947120 14.675 100 50 100 0 1442919648 21513696 12458832
20:46:39 9531 8192000000 10341419424 10770309120 1418371712 14.758 100 54 105 2 1454538864 22153616 14013536
20:47:53 3376 8192000000 10356129536 10781376512 1423496624 14.804 100 64 128 3 1467802336 28359584 15946128
20:49:05 5108 8192000000 10355882288 10775281664 1406482336 14.653 99 67 134 0 1453660368 31041088 16136944
20:50:14 3719 8192000000 10367178912 10789113856 1421476736 14.786 100 73 144 1 1475486080 35409136 18600208
20:51:23 2656 8192000000 10361266288 10781483008 1408786624 14.673 99 64 125 1 1458673488 33406160 16480704
20:52:30 1365 8192000000 10377749904 10800873472 1431606592 14.875 100 83 164 2 1485104640 32893392 20604656
20:53:39 1886 8192000000 10365433136 10778365952 1408087984 14.667 100 80 159 0 1464404784 36440272 19876528
20:54:45 7817 8192000000 10333031136 10754699264 1383018000 14.444 100 53 104 4 1434938032 38433392 13486640
20:55:58 7803 8192000000 10304279808 10733182976 1362342304 14.258 99 34 68 1 1405378528 34140544 8895680
20:57:09 7571 8192000000 10313906624 10743840768 1382064672 14.435 100 46 93 2 1421465488 27388048 12012768
20:58:19 8931 8192000000 10287524576 10713731072 1326714960 13.937 97 29 58 0 1361450240 27136448 7598832
20:59:32 11067 8192000000 10261756368 10691522560 1315200352 13.833 97 29 57 0 1346247360 23622352 7424656
21:00:48 18297 8192000000 10280787600 10710089728 1362260800 14.258 100 23 46 0 1387897872 19491440 6145632
21:02:14 9526 8192000000 10307312336 10745077760 1376123568 14.382 100 28 56 2 1415555232 31904336 7527328
21:03:29 44516 8192000000 10292416432 10728361984 1309469520 13.781 95 19 38 1 1345107696 30440672 5197504
21:05:14 68911 8192000000 10289150224 10739470336 1363129872 14.265 100 25 50 2 1401306080 31693904 6482304
21:07:26 36203 8192000000 10263259392 10713141248 1347180880 14.122 99 15 30 1 1363380096 12935328 3263888
21:09:20 168092 8192000000 10276936496 10733457408 1339582688 14.054 97 20 42 2 1358678144 14833088 4262368
21:13:11 65392 8192000000 10263486128 10716467200 1334676384 14.009 97 22 45 0 1351802496 11193488 5932624
21:15:17 49507 8192000000 10266674816 10720083968 1355837376 14.200 99 22 43 1 1374044176 13290832 4915968
21:17:07 45012 8192000000 10278049616 10728357888 1365514960 14.287 99 23 46 1 1387127376 16325312 5287104
21:19:03 58966 8192000000 10269301536 10723495936 1353042080 14.175 98 7 14 1 1365159728 10268032 1849616
21:21:07 15602 8192000000 10309584816 10753765376 1397949216 14.577 100 52 104 1 1421860560 11776656 12134688
21:22:26 61121 8192000000 10312664672 10762043392 1380349712 14.420 99 12 24 0 1404936224 21840240 2746272
21:24:28 51615 8192000000 10305200912 10768564224 1384474336 14.457 99 23 46 0 1407914016 18294160 5145520
21:26:24 46346 8192000000 10320124384 10762567680 1393116544 14.534 99 35 70 1 1410681248 9760624 7804080
21:28:27 129560 8192000000 10299191808 10755444736 1346733456 14.118 96 16 32 0 1368375952 18264816 3377680
21:31:48 96634 8192000000 10286892992 10735689728 1349684240 14.145 97 26 53 1 1362657184 6722096 6250848
21:34:25 85712 8192000000 10304053472 10761887744 1382461280 14.439 99 21 42 2 1406046112 18600832 4984000
21:36:53 2402 8192000000 10335147840 10767224832 1405608048 14.645 100 88 173 5 1436169216 9998512 20562656
21:37:58 74010 8192000000 10321625632 10783109120 1411065232 14.693 100 23 46 0 1433759552 17193120 5501200
21:40:12 87589 8192000000 10303823504 10766491648 1398402912 14.581 99 18 36 0 1423646720 20683456 4560352
21:42:53 83332 8192000000 10285512224 10745778176 1368319664 14.312 98 8 16 0 1381810624 11894400 1596560
21:45:17 88986 8192000000 10299515872 10757509120 1341124288 14.068 95 19 37 0 1363953024 18243120 4585616
21:47:46 63345 8192000000 10294132592 10732933120 1326236960 13.933 95 18 36 2 1338499632 8477856 3784816
21:49:51 65256 8192000000 10273914640 10744745984 1334432128 14.007 97 8 17 0 1358068320 21764672 1871520
21:52:31 38469 8192000000 10280403056 10720288768 1323116208 13.905 96 39 78 1 1346852256 14741808 8994240
21:54:55 33449 8192000000 10259815232 10698457088 1302838304 13.721 95 25 50 1 1317173344 8411888 5923152
21:56:30 52834 8192000000 10273578752 10716123136 1349558656 14.144 99 52 101 4 1375572944 14586864 11427424
21:58:59 42854 8192000000 10258464976 10693271552 1314325200 13.825 97 40 80 1 1332359152 7623024 10410928
22:00:43 53652 8192000000 10250078848 10705002496 1349916624 14.147 100 19 38 0 1368621728 13985760 4719344
22:02:45 25662 8192000000 10255778128 10708242432 1348801504 14.137 100 13 26 0 1362085760 10451872 2832384
22:04:13 45956 8192000000 10251715104 10694860800 1341649712 14.072 99 18 36 2 1353620352 7661936 4308704
22:06:28 42833 8192000000 10241969344 10692329472 1338806464 14.047 99 10 20 0 1352018912 11124784 2087664
22:08:16 56705 8192000000 10257143472 10700677120 1322499808 13.899 97 19 38 0 1334099472 7615008 3984656
22:10:17 45355 8192000000 10277475648 10719469568 1348596944 14.135 98 21 42 0 1362440720 9374832 4468944
22:12:03 48108 8192000000 10276112992 10734792704 1373852016 14.362 100 8 16 0 1392987952 16534496 2601440
22:15:11 27531 8192000000 10293591984 10732191744 1367136816 14.301 99 22 44 1 1381868864 9674240 5057808
22:16:38 51925 8192000000 10297028992 10754813952 1382121696 14.436 100 23 46 3 1409857728 22076128 5659904
22:18:30 42136 8192000000 10311851296 10763444224 1395268736 14.553 100 13 26 0 1416218128 17982864 2966528
22:20:14 70841 8192000000 10299797936 10749550592 1365138608 14.283 98 12 24 0 1378888448 11176256 2573584
22:22:28 97600 8192000000 10294223184 10741100544 1376841712 14.388 99 26 52 0 1390743552 8384224 5517616
22:25:14 34218 8192000000 10287656192 10735554560 1387300384 14.482 100 35 68 2 1404081648 9529216 7252048
22:26:49 45197 8192000000 10281186032 10739326976 1386485760 14.474 100 14 28 0 1395894576 6423600 2985216
22:28:56 63590 8192000000 10298748192 10755923968 1378996848 14.408 98 17 34 1 1398370960 14779904 4594208
22:31:27 68574 8192000000 10273893600 10722410496 1331700448 13.983 96 14 28 1 1347879456 12888592 3290416
22:33:41 62231 8192000000 10287225184 10737946624 1350249056 14.150 97 17 34 0 1363216352 9077488 3889808
22:35:43 50355 8192000000 10290464048 10733998080 1355220560 14.194 98 23 46 0 1371799440 11796064 4782816
22:37:48 16625 8192000000 9042680400 9258381312 209301616 2.491 16 1 2 0 211645184 2301216 42352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-01-2022 22:39:01 to 07-01-2022 22:39:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 62 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-01-2022 22:39:17 to 07-01-2022 22:39:19.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          235488
MON$OLDEST_ACTIVE               235489
MON$OLDEST_SNAPSHOT             235489
MON$NEXT_TRANSACTION            235489
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-03 16:41:55.3470
MON$PAGES                       2012224
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

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

Database validation

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

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

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

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

07.01.22 22:39:20. There are 3 logs in /var/tmp/logs-oltp30 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-01-07 22:39:22 No severe PSQL-related problems occured
07.01.22 22:39:23. End of report.