Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.01.2022 16: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 16589676 kB
MemAvailable 31334056 kB
Buffers 0 kB
Cached 15118220 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 16:38:02
DTS_END                         2022-01-07 16:38:02.6730
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/55790
TRN_ID                          244391
ATT_ID                          654
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: 9227.58 21022 1107309
customer order: creation 178.23 340 21387
customer order: refuse 15.21 352 1825
order to supplier: creation 47.95 1243 5754
order to supplier: removal 3.58 93925 429
invoice (draft): creation 47.89 3228 5747
invoice (draft): removal 50.28 831 6034
invoice accept: apply 43.93 18152 5272
invoice accept: cancel 45.26 896 5431
customer reserve: creation 4071.29 180 488555
customer reserve: removal 4426.56 59 531187
realization accept: apply 85.43 161 10251
realization accept: cancel 78.49 106 9419
payment from customer: creation 60.92 88 7310
payment from customer: removal 5.90 67 708
payment to supplier: creation 50.61 109 6073
payment to supplier: removal 3.61 78 433
service: total inventory turnovers 5.03 1109 603
service: total monetary turnovers 6.61 24 793
service: aggregate perf. data 0.78 22369 94
service: refresh index statistics 0.03 277125 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-01-2022 16:39:56 to 07-01-2022 16:39:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5985
TEST_TIME 2 5378
TEST_TIME 3 4865
TEST_TIME 4 5298
TEST_TIME 5 4874
TEST_TIME 6 4868
TEST_TIME 7 5261
TEST_TIME 8 6075
TEST_TIME 9 5972
TEST_TIME 10 6068
TEST_TIME 11 6153
TEST_TIME 12 6406
TEST_TIME 13 6707
TEST_TIME 14 7027
TEST_TIME 15 7113
TEST_TIME 16 7123
TEST_TIME 17 7430
TEST_TIME 18 7751
TEST_TIME 19 8219
TEST_TIME 20 8469
TEST_TIME 21 8815
TEST_TIME 22 9116
TEST_TIME 23 9000
TEST_TIME 24 10069
TEST_TIME 25 9879
TEST_TIME 26 9611
TEST_TIME 27 9579
TEST_TIME 28 9504
TEST_TIME 29 10107
TEST_TIME 30 10159
TEST_TIME 31 10692
TEST_TIME 32 10590
TEST_TIME 33 10655
TEST_TIME 34 10634
TEST_TIME 35 10705
TEST_TIME 36 10556
TEST_TIME 37 10585
TEST_TIME 38 10573
TEST_TIME 39 10460
TEST_TIME 40 10230
TEST_TIME 41 10029
TEST_TIME 42 9927
TEST_TIME 43 9821
TEST_TIME 44 9885
TEST_TIME 45 9998
TEST_TIME 46 9865
TEST_TIME 47 9851
TEST_TIME 48 9767
TEST_TIME 49 9648
TEST_TIME 50 9592
TEST_TIME 51 9557
TEST_TIME 52 9517
TEST_TIME 53 9481
TEST_TIME 54 9447
TEST_TIME 55 9342
TEST_TIME 56 9291
TEST_TIME 57 9286
TEST_TIME 58 9271
TEST_TIME 59 9298
TEST_TIME 60 9328
TEST_TIME 61 9354
TEST_TIME 62 9383
TEST_TIME 63 9433
TEST_TIME 64 9485
TEST_TIME 65 9536
TEST_TIME 66 9522
TEST_TIME 67 9588
TEST_TIME 68 9598
TEST_TIME 69 9576
TEST_TIME 70 9587
TEST_TIME 71 9663
TEST_TIME 72 9662
TEST_TIME 73 9595
TEST_TIME 74 9576
TEST_TIME 75 9535
TEST_TIME 76 9547
TEST_TIME 77 9525
TEST_TIME 78 9462
TEST_TIME 79 9424
TEST_TIME 80 9352
TEST_TIME 81 9353
TEST_TIME 82 9380
TEST_TIME 83 9354
TEST_TIME 84 9315
TEST_TIME 85 9352
TEST_TIME 86 9460
TEST_TIME 87 9497
TEST_TIME 88 9493
TEST_TIME 89 9459
TEST_TIME 90 9472
TEST_TIME 91 9522
TEST_TIME 92 9550
TEST_TIME 93 9600
TEST_TIME 94 9578
TEST_TIME 95 9609
TEST_TIME 96 9583
TEST_TIME 97 9497
TEST_TIME 98 9434
TEST_TIME 99 9469
TEST_TIME 100 9448
TEST_TIME 101 9407
TEST_TIME 102 9385
TEST_TIME 103 9334
TEST_TIME 104 9344
TEST_TIME 105 9317
TEST_TIME 106 9319
TEST_TIME 107 9333
TEST_TIME 108 9322
TEST_TIME 109 9368
TEST_TIME 110 9378
TEST_TIME 111 9379
TEST_TIME 112 9371
TEST_TIME 113 9360
TEST_TIME 114 9391
TEST_TIME 115 9409
TEST_TIME 116 9394
TEST_TIME 117 9435
TEST_TIME 118 9362
TEST_TIME 119 9309
TEST_TIME 120 9233

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-01-2022 16:40:00 to 07-01-2022 16:40:03.

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 1557727 1557727 0 0.00 0 26715 6 0 0
doc_list_biud 1557719 1557719 0 0.00 0 38822 26 0 0
sp_add_invoice_to_stock 5367 5272 95 1.77 36 170335 18153 0 95
sp_cancel_adding_invoice 5431 5431 0 0.00 5 79175 897 0 0
sp_cancel_client_order 1825 1825 0 0.00 4 14960 352 0 0
sp_cancel_customer_reserve 531187 531187 0 0.00 1 38827 60 0 0
sp_cancel_pay_from_customer 708 708 0 0.00 2 4759 68 0 0
sp_cancel_pay_to_supplier 433 433 0 0.00 3 4386 78 0 0
sp_cancel_supplier_invoice 6043 6034 9 0.14 2 42021 832 0 9
sp_cancel_supplier_order 429 429 0 0.00 10 953218 93925 0 0
sp_cancel_write_off 9419 9419 0 0.00 1 31310 107 0 0
sp_client_order 21387 21387 0 0.00 3 39167 340 0 0
sp_customer_reserve 488555 488555 0 0.00 0 83687 181 0 0
sp_fill_shopping_cart 32883 32883 0 0.00 1 19252 42 0 0
sp_fill_shopping_cart_clo_res 488547 488547 0 0.00 0 15666 13 0 0
sp_get_clo_for_invoice 5263 5263 0 0.00 0 30427 283 0 0
sp_kill_cost_storno 15991 15991 0 0.00 0 7223 55 0 0
sp_kill_qstorno_ret_qs2qd 1078256 1078256 0 0.00 0 38666 33 0 0
sp_kill_qty_storno 569838 569838 0 0.00 0 38821 71 0 0
sp_lock_selected_doc 21536 21536 0 0.00 0 9139 4 0 0
sp_make_cost_storno 28896 28896 0 0.00 0 13474 46 0 0
sp_multiply_rows_for_pdistr 28896 28896 0 0.00 0 9071 27 0 0
sp_multiply_rows_for_qdistr 21387 21387 0 0.00 0 12052 157 0 0
sp_pay_from_customer 7310 7310 0 0.00 4 10030 89 0 0
sp_pay_to_supplier 6073 6073 0 0.00 4 13483 109 0 0
sp_payment_common 13383 13383 0 0.00 1 9099 21 0 0
sp_qd_handle_on_cancel_clo 1825 1825 0 0.00 0 14953 329 0 0
sp_qd_handle_on_invoice_upd_sts 10693 10693 0 0.00 0 38185 334 0 0
sp_qd_handle_on_reserve_upd_sts 10251 10251 0 0.00 0 30024 58 0 0
sp_reserve_write_off 10251 10251 0 0.00 6 30392 162 0 0
sp_supplier_invoice 5747 5747 0 0.00 6 97961 3229 0 0
sp_supplier_order 5754 5754 0 0.00 5 95795 1244 0 0
srv_aggregate_perf_data 94 94 0 0.00 3137 429717 22369 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2495 155195 49167 0 0
srv_find_qd_qs_mism 1056523 1056523 0 0.00 0 26712 6 0 0
srv_make_invnt_saldo 603 603 0 0.00 36 75394 1109 0 0
srv_make_money_saldo 793 793 0 0.00 0 893 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 153489 507863 277126 0 0
t$perf-abend:sp_add_invoice_to_stock 95 95 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5273 5273 0 0.00 0 10520 162 0 0
t$perf-norm:sp_cancel_adding_invoice 450 450 0 0.00 0 2128 82 0 0
t$perf-norm:sp_cancel_client_order 1825 1825 0 0.00 0 3778 11 0 0
t$perf-norm:sp_cancel_customer_reserve 697 697 0 0.00 0 5944 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 708 708 0 0.00 0 3993 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 433 433 0 0.00 0 4124 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 435 435 0 0.00 0 3897 24 0 0
t$perf-norm:sp_cancel_supplier_order 429 429 0 0.00 0 25249 1712 0 0
t$perf-norm:sp_cancel_write_off 1046 1046 0 0.00 0 2157 7 0 0
t$perf-norm:sp_client_order 21387 21387 0 0.00 0 14841 19 0 0
t$perf-norm:sp_customer_reserve 981 981 0 0.00 0 1547 5 0 0
t$perf-norm:sp_pay_from_customer 7310 7310 0 0.00 0 9104 11 0 0
t$perf-norm:sp_pay_to_supplier 6073 6073 0 0.00 0 6196 12 0 0
t$perf-norm:sp_reserve_write_off 10251 10251 0 0.00 0 9140 14 0 0
t$perf-norm:sp_supplier_invoice 5747 5747 0 0.00 0 7085 22 0 0
t$perf-norm:sp_supplier_order 5754 5754 0 0.00 0 4284 16 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 186 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 4 0 0 0
t$perf-norm:srv_make_invnt_saldo 603 603 0 0.00 0 3642 28 0 0
t$perf-norm:srv_make_money_saldo 793 793 0 0.00 0 1705 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10714 10524 190 1.77 0 5215 18 0 190
v_all_customers 38876 38876 0 0.00 0 20 1 0 0
v_all_suppliers 31896 31896 0 0.00 0 36 1 0 0
v_all_wares 42774 42774 0 0.00 0 31 1 0 0
v_cancel_adding_invoice 900 900 0 0.00 0 1181 6 0 0
v_cancel_client_order 3650 3650 0 0.00 0 2907 5 0 0
v_cancel_customer_prepayment 1416 1416 0 0.00 0 2467 9 0 0
v_cancel_customer_reserve 1394 1394 0 0.00 0 2315 10 0 0
v_cancel_payment_to_supplier 866 866 0 0.00 0 454 3 0 0
v_cancel_supplier_invoice 888 870 18 2.02 0 2028 17 0 18
v_cancel_supplier_order 858 858 0 0.00 0 2316 8 0 0
v_cancel_write_off 2092 2092 0 0.00 0 1994 15 0 0
v_max_id_clo_ord 5754 5754 0 0.00 0 393 1 0 0
v_max_id_clo_res 981 981 0 0.00 0 1677 12 0 0
v_max_id_ord_sup 5742 5742 0 0.00 0 7050 6 0 0
v_max_non_paid_invoice 1621 1621 0 0.00 0 109 2 0 0
v_max_non_paid_realizn 3911 3911 0 0.00 0 4665 5 0 0
v_min_id_clo_ord 5754 5754 0 0.00 0 8382 9 0 0
v_min_id_clo_res 981 981 0 0.00 0 6510 13 0 0
v_min_id_ord_sup 5742 5742 0 0.00 0 7937 7 0 0
v_min_non_paid_invoice 6073 6073 0 0.00 0 3943 12 0 0
v_min_non_paid_realizn 7310 7310 0 0.00 0 9096 15 0 0
v_our_firm 10696 10696 0 0.00 0 15 1 0 0
v_reserve_write_off 20502 20502 0 0.00 0 7554 7 0 0
x_lock_depdocs_on_canc_invoice 11030 11030 0 0.00 0 14491 118 0 0
x_lock_depdocs_on_canc_sup_ord 429 429 0 0.00 0 3631 299 0 0
x_make_qstorno_1000_1200 5754 5754 0 0.00 1 92956 1163 0 0
x_make_qstorno_1200_2000 5747 5747 0 0.00 3 97920 3071 0 0
x_make_qstorno_2100_3300 488555 488555 0 0.00 0 83685 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-01-2022 16:40:04 to 07-01-2022 16:40:08.

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
14:08:06 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:07 24256 8192000000 9907075904 10216636416 982823216 10.712 100 84 167 1 1010978304 5747808 22407280
14:10:36 22824 8192000000 9938417488 10262462464 1026003088 11.130 100 83 166 0 1054980288 7487920 21489280
14:12:03 22295 8192000000 9984534224 10319413248 1066312288 11.517 100 81 163 1 1095740432 9186544 20241600
14:13:33 11341 8192000000 10056865584 10408361984 1139818112 12.214 100 80 160 0 1168954848 10509616 18627120
14:14:51 7756 8192000000 10091777984 10454974464 1181324608 12.603 100 76 152 1 1210178160 11450016 17403536
14:16:05 6139 8192000000 10142674640 10520162304 1226476752 13.022 100 78 156 1 1256571200 12742032 17352416
14:17:14 2777 8192000000 10184010384 10561495040 1260352800 13.333 100 88 174 0 1293614144 13827552 19433792
14:18:18 2058 8192000000 10202401104 10586968064 1286306816 13.571 100 82 163 0 1317367152 12951200 18109136
14:19:23 1532 8192000000 10220724544 10606407680 1301080144 13.705 100 94 188 3 1334543056 13112352 20350560
14:20:25 2888 8192000000 10202183600 10614640640 1313414128 13.817 100 36 72 3 1329596048 8071888 8110032
14:21:29 4751 8192000000 10208888240 10623180800 1321245136 13.888 100 23 46 0 1332432752 6270560 4917056
14:22:35 46602 8192000000 10237947152 10645622784 1328723024 13.956 100 51 102 1 1346754800 5024288 13007488
14:24:22 6058 8192000000 10241274688 10654887936 1337349760 14.034 100 37 74 0 1353106624 7162048 8594816
14:25:29 11389 8192000000 10224968672 10652147712 1338102176 14.040 100 16 32 1 1347209264 5327696 3779392
14:26:44 13443 8192000000 10219921008 10650890240 1338932336 14.048 100 30 60 0 1351438816 5032000 7474480
14:27:59 5831 8192000000 10220797648 10638123008 1326865504 13.939 100 31 62 0 1340234512 6378160 6990848
14:29:08 4572 8192000000 10197848496 10600624128 1282835472 13.539 99 41 81 1 1303209344 11478384 8895488
14:30:15 40174 8192000000 10148881360 10558500864 1196578432 12.745 93 35 68 2 1210333968 5705328 8050208
14:32:09 7378 8192000000 10123464976 10523234304 1221732496 12.978 99 32 64 1 1235244960 6517648 6994816
14:33:17 7579 8192000000 10161994944 10562646016 1251307056 13.250 99 25 50 2 1263202672 5529776 6365840
14:34:26 11815 8192000000 10173498144 10580652032 1280890928 13.521 100 25 50 0 1292357344 5866304 5600112
14:35:40 49494 8192000000 10174477456 10590846976 1287924640 13.585 100 10 20 1 1294796080 4806496 2064944
14:37:30 14853 8192000000 10204268432 10616791040 1307933184 13.767 100 27 54 2 1320217472 5674768 6609520
14:38:50 11544 8192000000 10253874272 10653532160 1337718928 14.037 100 56 112 1 1359290080 9453856 12117296
14:40:06 13172 8192000000 10256331568 10669592576 1351340384 14.160 100 49 96 3 1371382528 9464000 10578144
14:41:22 16807 8192000000 10278140128 10695430144 1370021104 14.327 100 53 104 4 1395462672 13012368 12429200
14:42:46 22411 8192000000 10284926128 10711068672 1374602256 14.368 100 38 76 1 1396641728 13317088 8722384
14:44:13 6182 8192000000 10280597536 10700234752 1368557776 14.314 100 47 93 1 1398272016 18701264 11012976
14:45:22 19645 8192000000 10289748864 10713575424 1379723232 14.414 100 36 72 1 1404416176 16183728 8509216
14:46:47 4972 8192000000 10324276064 10745856000 1395931184 14.559 100 74 144 3 1433944400 20961040 17052176
14:47:56 5315 8192000000 10339503136 10756612096 1408909264 14.674 100 69 136 1 1447909584 22786656 16213664
14:49:04 5930 8192000000 10348971296 10765873152 1414086368 14.720 100 70 138 1 1458514752 27293552 17134832
14:50:17 7535 8192000000 10353479232 10776711168 1419853904 14.771 100 65 128 1 1465370272 29690240 15826128
14:51:41 7465 8192000000 10347409712 10768236544 1405391280 14.643 99 61 123 0 1449578864 28592416 15595168
14:52:53 2494 8192000000 10367321232 10786131968 1424414432 14.812 100 76 151 1 1472590800 29330688 18845680
14:54:00 2589 8192000000 10382948352 10797207552 1434276128 14.899 100 82 162 0 1483596032 29978496 19341408
14:55:07 5123 8192000000 10351397136 10766282752 1403331920 14.625 100 77 154 1 1459618960 36636896 19650144
14:56:18 5632 8192000000 10362254704 10776883200 1405833744 14.647 100 72 142 0 1462174624 38029728 18311152
14:57:29 6862 8192000000 10307158880 10726031360 1345447232 14.106 98 53 105 0 1394384192 35025872 13911088
14:58:40 4257 8192000000 10300254368 10715361280 1355297232 14.195 100 55 106 1 1404047792 34878352 13872208
14:59:51 12455 8192000000 10284541904 10703593472 1342209904 14.077 99 47 92 1 1387911744 32918480 12783360
15:01:14 9712 8192000000 10303487904 10737233920 1362465856 14.259 100 31 62 1 1413109936 41565808 9078272
15:02:42 18963 8192000000 10310643584 10747711488 1378501920 14.403 100 34 66 0 1414495920 26608320 9385680
15:04:03 27774 8192000000 10315440208 10749911040 1371139952 14.337 99 36 72 0 1411627344 30901152 9586240
15:05:59 17568 8192000000 10324237040 10762457088 1379577840 14.413 100 23 46 0 1429063904 42903056 6583008
15:08:37 47943 8192000000 10300101456 10771759104 1355144128 14.194 99 14 28 0 1405135888 46199488 3792272
15:10:31 79603 8192000000 10273186896 10726592512 1341121568 14.068 98 23 45 1 1360676944 14847200 4708176
15:12:52 155194 8192000000 10287178160 10739023872 1336623600 14.027 97 9 18 0 1356704128 18593856 1486672
15:16:28 135869 8192000000 10296651072 10734702592 1371951328 14.345 99 19 36 0 1388694208 13007168 3735712
15:20:41 57894 8192000000 10298076704 10750074880 1392844576 14.531 100 28 56 0 1410630096 12307024 5478496
15:22:41 109771 8192000000 10296320640 10771116032 1384624256 14.458 99 21 42 0 1403336320 13839552 4872512
15:25:30 63814 8192000000 10287226624 10747002880 1381747712 14.432 99 17 33 0 1394296368 8533120 4015536
15:27:35 47378 8192000000 10306405664 10763350016 1400635792 14.601 100 18 36 0 1418891152 14195952 4059408
15:29:23 93743 8192000000 10282483808 10743103488 1388195664 14.490 100 19 39 0 1408193232 15328976 4668592
15:32:10 6827 8192000000 10298266064 10752598016 1396232272 14.561 100 36 72 2 1412711152 8298416 8180464
15:33:19 86895 8192000000 10293535184 10762498048 1398123472 14.578 100 14 28 0 1410487632 8581200 3782960
15:35:51 50734 8192000000 10280843360 10749943808 1373009216 14.354 98 12 25 1 1383514560 7420288 3085056
15:37:43 17851 8192000000 10296793392 10742611968 1367208560 14.302 98 44 87 2 1383532640 6763472 9560608
15:39:03 68631 8192000000 10273513408 10736967680 1328886752 13.957 96 17 34 2 1342171152 9809968 3474432
15:41:12 62511 8192000000 10296674256 10757701632 1379287664 14.410 99 17 34 1 1399492128 16450624 3753840
15:43:51 49966 8192000000 10281055456 10723831808 1312093168 13.805 95 39 78 0 1331236400 10415136 8728096
15:45:46 105696 8192000000 10236914960 10686423040 1258470128 13.316 93 8 15 0 1269973888 9911792 1591968
15:48:32 107014 8192000000 10281094576 10715873280 1307867680 13.767 96 15 30 0 1322251696 11215312 3168704
15:51:21 138982 8192000000 10274487552 10718175232 1345979888 14.111 98 26 52 2 1361047696 9145440 5922368
15:54:43 32472 8192000000 10251800656 10712539136 1339000480 14.048 98 16 31 2 1355891520 13176608 3714432
15:56:22 57106 8192000000 10266798176 10718318592 1327617888 13.946 97 9 18 0 1335517888 6143296 1756704
15:58:27 84004 8192000000 10271582848 10739671040 1362576432 14.260 99 13 26 0 1379888480 13991648 3320400
16:01:02 9327 8192000000 10260530544 10702516224 1352481680 14.170 100 43 86 2 1368128064 5487648 10158736
16:02:14 65340 8192000000 10267259168 10721202176 1355816144 14.200 99 29 58 0 1375662608 12959488 6886976
16:04:49 65407 8192000000 10264292992 10721939456 1366372288 14.295 100 14 28 0 1381196048 11470896 3352864
16:06:54 30734 8192000000 10280533456 10725765120 1360671280 14.243 99 28 56 0 1375889840 9323488 5895072
16:08:28 78457 8192000000 10291625840 10752716800 1353283392 14.177 98 41 82 0 1385135072 23300416 8551264
16:10:52 127284 8192000000 10296241824 10745036800 1320020480 13.877 94 18 36 1 1336212704 11923968 4268256
16:14:00 102112 8192000000 10288357120 10740314112 1362827424 14.263 98 13 27 1 1374763600 9091680 2844496
16:16:42 27335 8192000000 10310097568 10755002368 1392947296 14.532 100 51 102 0 1416563376 12024224 11591856
16:19:06 77429 8192000000 10275856512 10737143808 1351245536 14.159 97 12 24 0 1360318752 6400688 2672528
16:21:30 74394 8192000000 10261524720 10727120896 1336135520 14.023 97 6 12 0 1344811296 7340640 1335136
16:23:49 48313 8192000000 10269546848 10716626944 1311777152 13.802 95 23 46 0 1324097440 7346000 4974288
16:25:40 65209 8192000000 10268394032 10718302208 1337740336 14.037 98 28 56 0 1355161952 11186032 6235584
16:28:00 54201 8192000000 10260683888 10711662592 1334500128 14.008 98 12 24 0 1346417248 9201760 2715360
16:30:03 36878 8192000000 10271002032 10711351296 1325256432 13.924 97 33 66 2 1342058496 9565584 7236480
16:31:44 89691 8192000000 10260923664 10710331392 1317837008 13.857 97 23 46 1 1335852976 13368464 4647504
16:34:14 93755 8192000000 10247574288 10702532608 1266949696 13.394 93 34 68 1 1286710704 11211184 8549824
16:36:48 58975 8192000000 10252101136 10697637888 1352781440 14.172 100 26 52 1 1366529216 7799616 5948160

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 07-01-2022 16:40:10 to 07-01-2022 16:40:24.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-01-2022 16:40:25 to 07-01-2022 16:40:27.

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          244451
MON$OLDEST_ACTIVE               244804
MON$OLDEST_SNAPSHOT             244804
MON$NEXT_TRANSACTION            244804
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                       2019968
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 ---
:586: 
:587: mscs-test-161.budzdorov.ru    Fri Jan  7 16:38:16 2022
:588:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:589: 
:590: 
:591: mscs-test-161.budzdorov.ru    Fri Jan  7 16:38:17 2022
:592:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:593: 
:594: 
:595: mscs-test-161.budzdorov.ru    Fri Jan  7 16:38:18 2022
:596:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:597: 
--- end of diff output ---

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

07.01.22 16:40:28. 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 16:40:31 No severe PSQL-related problems occured
07.01.22 16:40:32. End of report.