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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16588824 kB
MemAvailable 31521508 kB
Buffers 0 kB
Cached 15079412 kB
SwapCached 2276 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.10
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-04-06 19:38:12
DTS_END                         2022-04-06 19:38:12.4900
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/45522
TRN_ID                          240072
ATT_ID                          694
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: 9406.51 22309 1128781
customer order: creation 169.93 330 20392
customer order: refuse 14.90 327 1788
order to supplier: creation 45.58 1343 5469
order to supplier: removal 3.45 89754 414
invoice (draft): creation 45.99 3203 5519
invoice (draft): removal 54.43 729 6531
invoice accept: apply 42.35 17113 5082
invoice accept: cancel 49.72 744 5966
customer reserve: creation 3869.01 172 464281
customer reserve: removal 4816.77 50 578012
realization accept: apply 83.52 163 10022
realization accept: cancel 82.22 100 9866
payment from customer: creation 58.20 99 6984
payment from customer: removal 5.85 70 702
payment to supplier: creation 49.24 124 5909
payment to supplier: removal 3.63 74 435
service: total inventory turnovers 4.59 1084 551
service: total monetary turnovers 6.25 22 750
service: aggregate perf. data 0.87 19264 104
service: refresh index statistics 0.03 311406 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-04-2022 19:39:27 to 06-04-2022 19:39:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7616
TEST_TIME 2 7148
TEST_TIME 3 6389
TEST_TIME 4 5950
TEST_TIME 5 5570
TEST_TIME 6 5705
TEST_TIME 7 5644
TEST_TIME 8 5883
TEST_TIME 9 6095
TEST_TIME 10 6186
TEST_TIME 11 6246
TEST_TIME 12 6530
TEST_TIME 13 6813
TEST_TIME 14 6629
TEST_TIME 15 6842
TEST_TIME 16 6922
TEST_TIME 17 7257
TEST_TIME 18 7464
TEST_TIME 19 8115
TEST_TIME 20 8322
TEST_TIME 21 8657
TEST_TIME 22 8476
TEST_TIME 23 9023
TEST_TIME 24 9279
TEST_TIME 25 9161
TEST_TIME 26 9626
TEST_TIME 27 10172
TEST_TIME 28 10189
TEST_TIME 29 10573
TEST_TIME 30 10534
TEST_TIME 31 10407
TEST_TIME 32 10206
TEST_TIME 33 10169
TEST_TIME 34 10146
TEST_TIME 35 9996
TEST_TIME 36 9957
TEST_TIME 37 9828
TEST_TIME 38 9846
TEST_TIME 39 10020
TEST_TIME 40 9860
TEST_TIME 41 9815
TEST_TIME 42 9823
TEST_TIME 43 9769
TEST_TIME 44 9648
TEST_TIME 45 9584
TEST_TIME 46 9507
TEST_TIME 47 9540
TEST_TIME 48 9506
TEST_TIME 49 9426
TEST_TIME 50 9384
TEST_TIME 51 9399
TEST_TIME 52 9348
TEST_TIME 53 9387
TEST_TIME 54 9443
TEST_TIME 55 9510
TEST_TIME 56 9529
TEST_TIME 57 9561
TEST_TIME 58 9663
TEST_TIME 59 9633
TEST_TIME 60 9656
TEST_TIME 61 9663
TEST_TIME 62 9770
TEST_TIME 63 9870
TEST_TIME 64 9731
TEST_TIME 65 9612
TEST_TIME 66 9590
TEST_TIME 67 9503
TEST_TIME 68 9449
TEST_TIME 69 9384
TEST_TIME 70 9412
TEST_TIME 71 9356
TEST_TIME 72 9316
TEST_TIME 73 9385
TEST_TIME 74 9363
TEST_TIME 75 9375
TEST_TIME 76 9462
TEST_TIME 77 9431
TEST_TIME 78 9369
TEST_TIME 79 9428
TEST_TIME 80 9589
TEST_TIME 81 9552
TEST_TIME 82 9636
TEST_TIME 83 9675
TEST_TIME 84 9692
TEST_TIME 85 9679
TEST_TIME 86 9592
TEST_TIME 87 9554
TEST_TIME 88 9590
TEST_TIME 89 9562
TEST_TIME 90 9519
TEST_TIME 91 9504
TEST_TIME 92 9437
TEST_TIME 93 9389
TEST_TIME 94 9435
TEST_TIME 95 9446
TEST_TIME 96 9463
TEST_TIME 97 9433
TEST_TIME 98 9497
TEST_TIME 99 9497
TEST_TIME 100 9454
TEST_TIME 101 9633
TEST_TIME 102 9598
TEST_TIME 103 9602
TEST_TIME 104 9645
TEST_TIME 105 9671
TEST_TIME 106 9767
TEST_TIME 107 9750
TEST_TIME 108 9741
TEST_TIME 109 9661
TEST_TIME 110 9620
TEST_TIME 111 9581
TEST_TIME 112 9604
TEST_TIME 113 9575
TEST_TIME 114 9528
TEST_TIME 115 9512
TEST_TIME 116 9467
TEST_TIME 117 9492
TEST_TIME 118 9483
TEST_TIME 119 9480
TEST_TIME 120 9411

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-04-2022 19:39:31 to 06-04-2022 19:39:34.

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 1557585 1557585 0 0.00 0 38981 6 0 0
doc_list_biud 1557585 1557585 0 0.00 0 70294 24 0 0
sp_add_invoice_to_stock 5181 5082 99 1.91 26 227592 17113 0 99
sp_cancel_adding_invoice 5966 5966 0 0.00 4 94387 745 0 0
sp_cancel_client_order 1788 1788 0 0.00 4 10428 327 0 0
sp_cancel_customer_reserve 578012 578012 0 0.00 1 70295 50 0 0
sp_cancel_pay_from_customer 702 702 0 0.00 2 6111 70 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 2 5104 74 0 0
sp_cancel_supplier_invoice 6542 6531 11 0.16 2 17020 730 0 11
sp_cancel_supplier_order 414 414 0 0.00 7 957000 89755 0 0
sp_cancel_write_off 9866 9866 0 0.00 1 6845 101 0 0
sp_client_order 20392 20392 0 0.00 3 40044 331 0 0
sp_customer_reserve 464281 464281 0 0.00 0 61836 172 0 0
sp_fill_shopping_cart 31372 31372 0 0.00 1 13733 38 0 0
sp_fill_shopping_cart_clo_res 464274 464274 0 0.00 0 20835 13 0 0
sp_get_clo_for_invoice 5075 5075 0 0.00 0 18784 252 0 0
sp_kill_cost_storno 16969 16969 0 0.00 0 6923 50 0 0
sp_kill_qstorno_ret_qs2qd 1172835 1172835 0 0.00 0 70287 28 0 0
sp_kill_qty_storno 617674 617674 0 0.00 0 70294 60 0 0
sp_lock_selected_doc 20934 20934 0 0.00 0 7803 5 0 0
sp_make_cost_storno 27990 27990 0 0.00 0 12169 49 0 0
sp_multiply_rows_for_pdistr 27990 27990 0 0.00 0 7117 27 0 0
sp_multiply_rows_for_qdistr 20392 20392 0 0.00 0 11025 156 0 0
sp_pay_from_customer 6984 6984 0 0.00 4 14164 99 0 0
sp_pay_to_supplier 5909 5909 0 0.00 5 11560 124 0 0
sp_payment_common 12893 12893 0 0.00 2 7753 25 0 0
sp_qd_handle_on_cancel_clo 1788 1788 0 0.00 0 10416 287 0 0
sp_qd_handle_on_invoice_upd_sts 11041 11041 0 0.00 0 22541 299 0 0
sp_qd_handle_on_reserve_upd_sts 10022 10022 0 0.00 0 17037 52 0 0
sp_reserve_write_off 10022 10022 0 0.00 6 17082 164 0 0
sp_supplier_invoice 5519 5519 0 0.00 5 76726 3204 0 0
sp_supplier_order 5469 5469 0 0.00 5 37555 1343 0 0
srv_aggregate_perf_data 104 104 0 0.00 1750 464118 19264 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2728 125607 42773 0 0
srv_find_qd_qs_mism 1080618 1080618 0 0.00 0 38981 5 0 0
srv_make_invnt_saldo 551 551 0 0.00 16 50738 1085 0 0
srv_make_money_saldo 750 750 0 0.00 1 635 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 95126 542726 311407 0 0
t$perf-abend:sp_add_invoice_to_stock 99 99 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5083 5083 0 0.00 0 16105 187 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 0 5394 85 0 0
t$perf-norm:sp_cancel_client_order 1788 1788 0 0.00 0 6420 16 0 0
t$perf-norm:sp_cancel_customer_reserve 689 689 0 0.00 0 6730 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 702 702 0 0.00 0 2375 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 2608 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 3435 27 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 22371 1568 0 0
t$perf-norm:sp_cancel_write_off 1006 1006 0 0.00 0 15936 36 0 0
t$perf-norm:sp_client_order 20392 20392 0 0.00 0 31113 18 0 0
t$perf-norm:sp_customer_reserve 1008 1008 0 0.00 0 41034 52 0 0
t$perf-norm:sp_pay_from_customer 6984 6984 0 0.00 0 9739 14 0 0
t$perf-norm:sp_pay_to_supplier 5909 5909 0 0.00 0 8607 13 0 0
t$perf-norm:sp_reserve_write_off 10022 10022 0 0.00 0 11264 17 0 0
t$perf-norm:sp_supplier_invoice 5519 5519 0 0.00 0 11331 25 0 0
t$perf-norm:sp_supplier_order 5469 5469 0 0.00 0 2360 14 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 71 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 383 12 0 0
t$perf-norm:srv_make_invnt_saldo 551 551 0 0.00 0 2245 23 0 0
t$perf-norm:srv_make_money_saldo 750 750 0 0.00 0 2077 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10348 10150 198 1.91 0 4387 18 0 198
v_all_customers 36780 36780 0 0.00 0 41 0 0 0
v_all_suppliers 30372 30372 0 0.00 0 20 1 0 0
v_all_wares 40784 40784 0 0.00 0 32 1 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 1271 8 0 0
v_cancel_client_order 3576 3576 0 0.00 0 4559 9 0 0
v_cancel_customer_prepayment 1404 1404 0 0.00 0 2234 7 0 0
v_cancel_customer_reserve 1378 1378 0 0.00 0 1433 6 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 4023 9 0 0
v_cancel_supplier_invoice 784 762 22 2.80 0 3623 20 0 22
v_cancel_supplier_order 828 828 0 0.00 0 3067 16 0 0
v_cancel_write_off 2012 2012 0 0.00 0 4333 16 0 0
v_max_id_clo_ord 5469 5469 0 0.00 0 1947 2 0 0
v_max_id_clo_res 1008 1008 0 0.00 0 2865 15 0 0
v_max_id_ord_sup 5511 5511 0 0.00 0 2998 4 0 0
v_max_non_paid_invoice 1703 1703 0 0.00 0 1838 5 0 0
v_max_non_paid_realizn 3838 3838 0 0.00 0 3995 6 0 0
v_min_id_clo_ord 5469 5469 0 0.00 0 4391 7 0 0
v_min_id_clo_res 1008 1008 0 0.00 0 6513 11 0 0
v_min_id_ord_sup 5511 5511 0 0.00 0 13645 6 0 0
v_min_non_paid_invoice 5909 5909 0 0.00 0 5431 17 0 0
v_min_non_paid_realizn 6984 6984 0 0.00 0 7750 19 0 0
v_our_firm 10296 10296 0 0.00 0 202 1 0 0
v_reserve_write_off 20044 20044 0 0.00 0 4420 6 0 0
x_lock_depdocs_on_canc_invoice 12116 12116 0 0.00 0 22252 104 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 18605 404 0 0
x_make_qstorno_1000_1200 5469 5469 0 0.00 1 37545 1261 0 0
x_make_qstorno_1200_2000 5519 5519 0 0.00 2 76716 3075 0 0
x_make_qstorno_2100_3300 464281 464281 0 0.00 0 61833 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-04-2022 19:39:35 to 06-04-2022 19:39:39.

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
17:08:16 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:16 39741 8192000000 9925685200 10237816832 995458416 10.834 100 91 181 0 1025225872 6240912 23526544
17:11:01 20383 8192000000 9964185616 10289745920 1043374480 11.297 100 86 172 0 1073337056 8205360 21757216
17:12:25 19528 8192000000 10001746688 10336243712 1081255040 11.659 100 85 170 2 1112295360 10143648 20896672
17:13:53 11771 8192000000 10055411344 10402992128 1137351344 12.191 100 84 168 0 1168845312 11902368 19591600
17:15:09 11380 8192000000 10086240000 10447106048 1174174160 12.536 100 82 164 1 1205825504 12591280 19060064
17:16:23 5348 8192000000 10128130672 10498813952 1220698016 12.968 100 84 168 0 1251442528 12441952 18302560
17:17:30 2445 8192000000 10174223952 10547339264 1259015456 13.321 100 92 182 0 1293351936 14488672 19847808
17:18:35 1681 8192000000 10211030432 10595053568 1296647456 13.665 100 91 181 2 1329964112 12971120 20345536
17:19:38 1458 8192000000 10228691312 10619211776 1311069360 13.796 100 91 182 1 1345159424 13002256 21087808
17:20:41 12150 8192000000 10222876384 10623688704 1315152976 13.833 100 70 137 3 1342188752 11323536 15712240
17:22:04 6418 8192000000 10211182448 10626019328 1323401824 13.907 100 26 52 1 1335874656 6687664 5785168
17:23:11 11694 8192000000 10219998768 10638622720 1335572464 14.017 100 24 48 1 1347541632 6572288 5396880
17:24:24 6606 8192000000 10223556816 10642792448 1338689792 14.046 100 17 34 0 1348135664 5851936 3593936
17:25:31 48649 8192000000 10245032032 10658471936 1340297152 14.060 100 31 62 1 1352629088 5159680 7172256
17:27:21 7470 8192000000 10231109328 10656600064 1340921680 14.066 100 27 54 1 1353268288 6506816 5839792
17:28:31 19368 8192000000 10202613184 10616209408 1257813616 13.310 95 18 36 0 1267421904 5170944 4437344
17:29:50 11696 8192000000 10159255360 10571325440 1225494160 13.012 95 16 33 2 1235299568 5528416 4276992
17:31:10 39750 8192000000 10108913632 10504392704 1084070144 11.686 85 21 42 0 1093357184 4569424 4717616
17:32:50 7615 8192000000 10147896160 10548158464 1251350160 13.251 100 27 54 1 1264572976 7021728 6201088
17:34:00 6085 8192000000 10179397680 10581626880 1277665760 13.492 100 40 80 2 1293673488 5763536 10244192
17:35:07 14379 8192000000 10189641968 10591318016 1288127936 13.587 100 17 34 2 1297592768 5167808 4297024
17:36:21 10902 8192000000 10212740112 10607636480 1297947872 13.677 100 64 128 0 1318792112 5811264 15032976
17:37:33 7834 8192000000 10201358880 10616176640 1308462640 13.772 100 24 48 1 1320115904 5996736 5656528
17:38:48 2727 8192000000 10241055024 10645176320 1333348832 13.997 100 43 84 2 1350395952 6773120 10274000
17:39:53 20184 8192000000 10255276080 10673319936 1348925824 14.138 100 41 82 0 1366452208 7428528 10097856
17:41:22 69390 8192000000 10282595360 10705276928 1366305248 14.294 100 36 72 0 1383050272 8693056 8051968
17:43:44 39420 8192000000 10287328848 10715693056 1381137488 14.427 100 27 54 0 1401020288 13541280 6341520
17:45:26 5202 8192000000 10308265760 10731814912 1390069792 14.506 100 58 115 2 1418967088 15469408 13427888
17:46:38 20357 8192000000 10313535888 10738429952 1393461680 14.537 100 53 104 2 1424676464 19136752 12078032
17:48:04 3896 8192000000 10319443392 10742198272 1394170032 14.543 100 74 147 2 1431715008 19922832 17622144
17:49:19 16097 8192000000 10318144800 10735456256 1370332416 14.330 98 56 112 2 1407046752 23234880 13479456
17:50:40 5604 8192000000 10320655536 10734444544 1378114048 14.400 99 70 139 1 1420261568 25299312 16848208
17:51:52 4978 8192000000 10338573072 10759737344 1407855584 14.665 100 56 111 1 1442943936 21424240 13664112
17:53:11 5466 8192000000 10340026512 10757378048 1382212208 14.436 98 57 110 3 1423254704 27069904 13972592
17:54:22 6660 8192000000 10331388816 10746601472 1392244272 14.526 100 78 156 2 1440306144 28089456 19972416
17:55:41 5433 8192000000 10312866496 10718048256 1355685840 14.199 99 79 157 2 1409982464 34819504 19477120
17:56:49 6019 8192000000 10319293312 10732240896 1361871616 14.254 99 58 116 3 1413962688 37148592 14942480
17:57:59 4524 8192000000 10313622096 10729164800 1364551088 14.278 99 49 98 0 1410777168 33441232 12784848
17:59:08 9639 8192000000 10325824688 10738692096 1371908272 14.344 100 51 100 3 1422310704 37213168 13189264
18:00:25 18771 8192000000 10318161120 10742800384 1373196496 14.356 100 50 98 3 1420721024 34760624 12763904
18:01:50 14031 8192000000 10300691248 10722336768 1351938368 14.165 98 43 86 2 1393523136 30891984 10692784
18:03:07 19715 8192000000 10286857504 10719219712 1320951552 13.885 97 29 58 0 1366245600 37331248 7962800
18:04:35 37625 8192000000 10293626176 10732478464 1346162336 14.113 99 24 48 2 1388206816 35818944 6225536
18:06:45 34096 8192000000 10273234656 10718416896 1347204288 14.122 99 13 26 0 1374567616 24007952 3355376
18:08:20 53661 8192000000 10274051024 10713919488 1346043312 14.112 99 12 24 0 1362289824 13185648 3060864
18:10:14 66187 8192000000 10293221984 10731823104 1356349872 14.205 99 36 72 2 1376221488 11933008 7938608
18:12:21 8313 8192000000 10299959696 10720231424 1359527664 14.233 99 67 132 3 1387732272 12213104 15991504
18:13:37 31054 8192000000 10279522352 10726174720 1357373280 14.214 100 13 26 0 1381824000 21460240 2990480
18:15:09 34664 8192000000 10297782880 10738929664 1385401440 14.465 100 15 30 0 1402448944 13488352 3559152
18:16:50 22896 8192000000 10301496992 10741407744 1380229872 14.419 100 38 74 3 1404483424 15206960 9046592
18:18:14 32631 8192000000 10290879552 10742423552 1392260000 14.526 100 29 60 1 1409146256 10219456 6666800
18:19:47 29864 8192000000 10286459920 10744725504 1384111936 14.453 100 10 20 1 1399684880 12883328 2689616
18:21:18 50855 8192000000 10286366880 10747494400 1389974736 14.506 100 18 35 1 1400960992 6901888 4084368
18:23:13 29418 8192000000 10301923920 10754785280 1396696656 14.566 100 49 95 0 1415835152 7897920 11240576
18:24:43 22947 8192000000 10303968544 10768461824 1401700000 14.610 100 7 14 0 1418138928 14792944 1645984
18:26:06 24849 8192000000 10289393200 10752372736 1393559920 14.538 100 6 12 0 1401405280 6654288 1191072
18:27:38 109093 8192000000 10289206032 10749513728 1358342432 14.222 97 7 14 0 1368940112 8235040 2362640
18:30:27 38552 8192000000 10295699088 10763923456 1401900592 14.612 100 16 32 0 1414044704 8609376 3534736
18:32:12 35767 8192000000 10305786144 10765381632 1392896208 14.532 100 33 66 0 1410775648 10254368 7625072
18:33:49 37446 8192000000 10308790096 10755121152 1372752944 14.352 99 27 54 0 1395866960 16428176 6685840
18:35:30 57260 8192000000 10292725856 10745585664 1339210640 14.050 96 18 36 0 1359074864 15494128 4370096
18:37:33 30781 8192000000 10305186768 10750828544 1387137280 14.480 100 51 100 1 1411828176 12308016 12382880
18:39:12 50320 8192000000 10309579712 10766766080 1378745120 14.405 99 29 58 1 1402918976 17579328 6594528
18:41:04 35394 8192000000 10286590496 10749763584 1356862320 14.209 98 24 48 1 1377690512 15463648 5364544
18:42:42 83584 8192000000 10295620944 10741764096 1370794288 14.334 99 30 60 0 1385163888 7598512 6771088
18:45:51 34823 8192000000 10254575856 10704117760 1347898752 14.129 99 13 26 2 1360720880 9616640 3205488
18:47:31 88325 8192000000 10254079888 10724802560 1339777040 14.055 98 11 22 0 1357905952 15007712 3121200
18:50:01 44126 8192000000 10285979584 10735382528 1373971472 14.363 100 30 58 3 1391660560 11458896 6230192
18:51:47 92207 8192000000 10231389536 10676772864 1230548464 13.059 89 22 44 0 1252250624 16838672 4863488
18:54:21 53667 8192000000 10278722704 10721280000 1358140784 14.221 99 30 58 1 1373820544 8231600 7448160
18:56:21 62194 8192000000 10301676816 10744373248 1355235008 14.195 98 45 90 0 1382399280 16516960 10647312
18:58:38 75453 8192000000 10303446448 10750320640 1383415088 14.447 100 18 36 1 1400118960 13034112 3669760
19:01:00 89779 8192000000 10274124784 10734571520 1318855952 13.866 95 16 32 0 1340785952 18012880 3917120
19:03:30 104836 8192000000 10285153792 10744025088 1380547680 14.421 100 22 44 0 1399564288 13771408 5245200
19:06:20 29293 8192000000 10279656896 10748305408 1376106928 14.382 100 16 32 1 1392182496 12697776 3377792
19:07:51 43663 8192000000 10279011184 10741473280 1380080096 14.417 100 14 28 1 1396995520 13843200 3072224
19:09:45 46133 8192000000 10293962816 10751705088 1374998688 14.372 99 15 30 1 1396805008 18103952 3702368
19:11:31 54478 8192000000 10299549648 10767536128 1390285024 14.508 100 11 22 1 1417585824 24634400 2666400
19:13:26 76388 8192000000 10306054480 10764640256 1379866720 14.415 99 12 24 0 1394674160 12005840 2801600
19:15:42 69802 8192000000 10318808448 10774487040 1363081808 14.265 97 20 40 1 1381968896 14240192 4646896
19:17:55 49380 8192000000 10320403376 10778865664 1403079152 14.622 100 13 26 0 1424641648 17987424 3575072
19:19:45 81866 8192000000 10330059472 10764025856 1376882896 14.389 98 28 55 0 1407693328 24269504 6540928
19:22:07 86118 8192000000 10311489200 10774196224 1365198448 14.284 97 25 50 0 1398480112 27000096 6281568
19:24:36 86074 8192000000 10298208080 10752716800 1338800016 14.047 96 18 36 0 1363665248 21027504 3837728
19:27:13 125606 8192000000 10293784096 10742886400 1369548896 14.323 98 22 42 1 1386357648 11288416 5520336
19:31:24 50423 8192000000 10292608672 10761629696 1383010992 14.443 99 7 13 0 1393694672 9505504 1178176
19:33:14 75751 8192000000 10271197824 10727526400 1323075168 13.905 95 8 17 0 1336375232 11036432 2263632
19:35:33 40991 8192000000 10262063296 10734772224 1345013168 14.103 98 10 20 0 1357184800 10093904 2077728
19:37:14 26157 8192000000 10253490880 10712670208 1321284176 13.888 96 13 26 0 1331624464 7583696 2756592

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-04-2022 19:39:41 to 06-04-2022 19:39:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 99 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-04-2022 19:39:57 to 06-04-2022 19:39:59.

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          239058
MON$OLDEST_ACTIVE               240286
MON$OLDEST_SNAPSHOT             240286
MON$NEXT_TRANSACTION            240286
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                       2034592
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33576 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33576 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33576 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33576 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 ---
:31: 
:32: mscs-test-161.budzdorov.ru    Wed Apr  6 19:38:13 2022
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:34: 
:35: 
:36: mscs-test-161.budzdorov.ru    Wed Apr  6 19:38:19 2022
:37:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:38: 
--- end of diff output ---

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

06.04.22 19:40:00. 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-04-06 19:40:02 No severe PSQL-related problems occured
06.04.22 19:40:04. End of report.