Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.12.2021 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 16778616 kB
MemAvailable 31513788 kB
Buffers 0 kB
Cached 14928916 kB
SwapCached 0 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 2021-12-07 19:37:55
DTS_END                         2021-12-07 19:37:55.0790
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/54316
TRN_ID                          247142
ATT_ID                          678
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: 9144.58 20582 1097350
customer order: creation 182.34 340 21881
customer order: refuse 15.03 349 1803
order to supplier: creation 48.58 1232 5829
order to supplier: removal 3.38 97252 406
invoice (draft): creation 47.25 3262 5670
invoice (draft): removal 49.68 775 5962
invoice accept: apply 43.86 17293 5263
invoice accept: cancel 44.61 841 5353
customer reserve: creation 4011.33 173 481359
customer reserve: removal 4401.58 59 528189
realization accept: apply 87.04 173 10445
realization accept: cancel 74.42 112 8930
payment from customer: creation 61.00 107 7320
payment from customer: removal 6.43 86 772
payment to supplier: creation 51.21 128 6145
payment to supplier: removal 3.54 69 425
service: total inventory turnovers 5.51 846 661
service: total monetary turnovers 6.81 44 817
service: aggregate perf. data 0.97 17731 116
service: refresh index statistics 0.03 270760 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-12-2021 19:40:14 to 07-12-2021 19:40:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1770
TEST_TIME 2 2253
TEST_TIME 3 2770
TEST_TIME 4 3281
TEST_TIME 5 3829
TEST_TIME 6 3776
TEST_TIME 7 3883
TEST_TIME 8 4070
TEST_TIME 9 4019
TEST_TIME 10 4029
TEST_TIME 11 4247
TEST_TIME 12 4615
TEST_TIME 13 5052
TEST_TIME 14 5512
TEST_TIME 15 5960
TEST_TIME 16 6107
TEST_TIME 17 6498
TEST_TIME 18 7308
TEST_TIME 19 7974
TEST_TIME 20 8137
TEST_TIME 21 8143
TEST_TIME 22 8553
TEST_TIME 23 8731
TEST_TIME 24 8874
TEST_TIME 25 9477
TEST_TIME 26 9585
TEST_TIME 27 10302
TEST_TIME 28 10094
TEST_TIME 29 10359
TEST_TIME 30 10122
TEST_TIME 31 10227
TEST_TIME 32 10064
TEST_TIME 33 9886
TEST_TIME 34 9993
TEST_TIME 35 10027
TEST_TIME 36 9911
TEST_TIME 37 9851
TEST_TIME 38 9728
TEST_TIME 39 9971
TEST_TIME 40 9955
TEST_TIME 41 9863
TEST_TIME 42 9728
TEST_TIME 43 9664
TEST_TIME 44 9514
TEST_TIME 45 9547
TEST_TIME 46 9460
TEST_TIME 47 9510
TEST_TIME 48 9525
TEST_TIME 49 9394
TEST_TIME 50 9305
TEST_TIME 51 9234
TEST_TIME 52 9125
TEST_TIME 53 9227
TEST_TIME 54 9185
TEST_TIME 55 9142
TEST_TIME 56 9294
TEST_TIME 57 9304
TEST_TIME 58 9327
TEST_TIME 59 9311
TEST_TIME 60 9396
TEST_TIME 61 9364
TEST_TIME 62 9405
TEST_TIME 63 9533
TEST_TIME 64 9497
TEST_TIME 65 9517
TEST_TIME 66 9601
TEST_TIME 67 9646
TEST_TIME 68 9525
TEST_TIME 69 9434
TEST_TIME 70 9445
TEST_TIME 71 9399
TEST_TIME 72 9427
TEST_TIME 73 9379
TEST_TIME 74 9347
TEST_TIME 75 9297
TEST_TIME 76 9231
TEST_TIME 77 9193
TEST_TIME 78 9291
TEST_TIME 79 9259
TEST_TIME 80 9309
TEST_TIME 81 9300
TEST_TIME 82 9300
TEST_TIME 83 9302
TEST_TIME 84 9403
TEST_TIME 85 9411
TEST_TIME 86 9367
TEST_TIME 87 9345
TEST_TIME 88 9495
TEST_TIME 89 9528
TEST_TIME 90 9512
TEST_TIME 91 9531
TEST_TIME 92 9442
TEST_TIME 93 9411
TEST_TIME 94 9366
TEST_TIME 95 9346
TEST_TIME 96 9352
TEST_TIME 97 9324
TEST_TIME 98 9276
TEST_TIME 99 9297
TEST_TIME 100 9225
TEST_TIME 101 9253
TEST_TIME 102 9257
TEST_TIME 103 9235
TEST_TIME 104 9223
TEST_TIME 105 9206
TEST_TIME 106 9272
TEST_TIME 107 9271
TEST_TIME 108 9317
TEST_TIME 109 9316
TEST_TIME 110 9300
TEST_TIME 111 9349
TEST_TIME 112 9362
TEST_TIME 113 9358
TEST_TIME 114 9401
TEST_TIME 115 9337
TEST_TIME 116 9307
TEST_TIME 117 9271
TEST_TIME 118 9252
TEST_TIME 119 9207
TEST_TIME 120 9150

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-12-2021 19:40:18 to 07-12-2021 19:40:21.

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 1543896 1543896 0 0.00 0 18879 6 0 0
doc_list_biud 1543882 1543882 0 0.00 0 27056 26 0 0
sp_add_invoice_to_stock 5389 5263 126 2.33 29 183000 17293 0 126
sp_cancel_adding_invoice 5353 5353 0 0.00 4 97426 841 0 0
sp_cancel_client_order 1803 1803 0 0.00 4 9012 350 0 0
sp_cancel_customer_reserve 528189 528189 0 0.00 1 23690 60 0 0
sp_cancel_pay_from_customer 772 772 0 0.00 3 3903 86 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 2 3505 69 0 0
sp_cancel_supplier_invoice 5975 5962 13 0.21 1 13603 775 0 13
sp_cancel_supplier_order 406 406 0 0.00 6 1067809 97252 0 0
sp_cancel_write_off 8930 8930 0 0.00 1 16135 113 0 0
sp_client_order 21881 21881 0 0.00 3 30168 341 0 0
sp_customer_reserve 481359 481359 0 0.00 0 61592 173 0 0
sp_fill_shopping_cart 33373 33373 0 0.00 1 11263 34 0 0
sp_fill_shopping_cart_clo_res 481356 481356 0 0.00 0 15135 15 0 0
sp_get_clo_for_invoice 5257 5257 0 0.00 0 16131 309 0 0
sp_kill_cost_storno 15480 15480 0 0.00 0 8496 53 0 0
sp_kill_qstorno_ret_qs2qd 1071676 1071676 0 0.00 0 23669 32 0 0
sp_kill_qty_storno 566337 566337 0 0.00 0 27052 70 0 0
sp_lock_selected_doc 21816 21816 0 0.00 0 7597 7 0 0
sp_make_cost_storno 29166 29166 0 0.00 0 18833 48 0 0
sp_multiply_rows_for_pdistr 29152 29152 0 0.00 0 6877 26 0 0
sp_multiply_rows_for_qdistr 21881 21881 0 0.00 0 16739 159 0 0
sp_pay_from_customer 7320 7320 0 0.00 4 11501 108 0 0
sp_pay_to_supplier 6145 6145 0 0.00 5 11302 129 0 0
sp_payment_common 13458 13458 0 0.00 1 6985 23 0 0
sp_qd_handle_on_cancel_clo 1803 1803 0 0.00 0 9004 315 0 0
sp_qd_handle_on_invoice_upd_sts 10607 10607 0 0.00 0 27047 322 0 0
sp_qd_handle_on_reserve_upd_sts 10440 10440 0 0.00 0 19883 61 0 0
sp_reserve_write_off 10445 10445 0 0.00 6 19894 174 0 0
sp_supplier_invoice 5670 5670 0 0.00 6 109466 3262 0 0
sp_supplier_order 5829 5829 0 0.00 4 34313 1232 0 0
srv_aggregate_perf_data 116 116 0 0.00 2445 284577 17731 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 3951 129030 43508 0 0
srv_find_qd_qs_mism 1047786 1047786 0 0.00 0 15731 6 0 0
srv_make_invnt_saldo 661 661 0 0.00 32 36668 846 0 0
srv_make_money_saldo 817 817 0 0.00 1 13171 45 0 0
srv_recalc_idx_stat 4 4 0 0.00 112605 444025 270760 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5263 5263 0 0.00 0 12937 149 0 0
t$perf-norm:sp_cancel_adding_invoice 444 444 0 0.00 0 2715 70 0 0
t$perf-norm:sp_cancel_client_order 1803 1803 0 0.00 0 3203 15 0 0
t$perf-norm:sp_cancel_customer_reserve 756 756 0 0.00 0 3608 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 772 772 0 0.00 0 3110 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 3628 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 444 444 0 0.00 0 1482 18 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 16337 1631 0 0
t$perf-norm:sp_cancel_write_off 1072 1072 0 0.00 0 3179 11 0 0
t$perf-norm:sp_client_order 21881 21881 0 0.00 0 11587 20 0 0
t$perf-norm:sp_customer_reserve 1039 1039 0 0.00 0 3959 9 0 0
t$perf-norm:sp_pay_from_customer 7320 7320 0 0.00 0 10268 11 0 0
t$perf-norm:sp_pay_to_supplier 6145 6145 0 0.00 0 16761 20 0 0
t$perf-norm:sp_reserve_write_off 10445 10445 0 0.00 0 13075 14 0 0
t$perf-norm:sp_supplier_invoice 5670 5670 0 0.00 0 10690 18 0 0
t$perf-norm:sp_supplier_order 5829 5829 0 0.00 0 13998 16 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 705 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 377 10 0 0
t$perf-norm:srv_make_invnt_saldo 661 661 0 0.00 0 5120 22 0 0
t$perf-norm:srv_make_money_saldo 817 817 0 0.00 0 2341 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 84 23 0 0
v_add_invoice_to_stock 10760 10508 252 2.34 0 8312 21 0 252
v_all_customers 39632 39632 0 0.00 0 115 0 0 0
v_all_suppliers 31892 31892 0 0.00 0 19 1 0 0
v_all_wares 43748 43748 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 888 888 0 0.00 0 1370 8 0 0
v_cancel_client_order 3606 3606 0 0.00 0 2874 7 0 0
v_cancel_customer_prepayment 1544 1544 0 0.00 0 3829 7 0 0
v_cancel_customer_reserve 1512 1512 0 0.00 0 8322 15 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 2152 7 0 0
v_cancel_supplier_invoice 914 888 26 2.84 0 493 15 0 26
v_cancel_supplier_order 812 812 0 0.00 0 240 3 0 0
v_cancel_write_off 2144 2144 0 0.00 0 8348 27 0 0
v_max_id_clo_ord 5829 5829 0 0.00 0 3577 2 0 0
v_max_id_clo_res 1039 1039 0 0.00 0 2933 17 0 0
v_max_id_ord_sup 5670 5670 0 0.00 0 6279 3 0 0
v_max_non_paid_invoice 1693 1693 0 0.00 0 3145 4 0 0
v_max_non_paid_realizn 4038 4038 0 0.00 0 965 3 0 0
v_min_id_clo_ord 5829 5829 0 0.00 0 2598 7 0 0
v_min_id_clo_res 1039 1039 0 0.00 0 1633 7 0 0
v_min_id_ord_sup 5670 5670 0 0.00 0 4845 5 0 0
v_min_non_paid_invoice 6140 6140 0 0.00 0 5071 12 0 0
v_min_non_paid_realizn 7318 7318 0 0.00 0 6192 20 0 0
v_our_firm 10676 10676 0 0.00 0 35 1 0 0
v_reserve_write_off 20880 20880 0 0.00 0 8323 8 0 0
x_lock_depdocs_on_canc_invoice 10871 10871 0 0.00 0 41196 118 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 34447 392 0 0
x_make_qstorno_1000_1200 5829 5829 0 0.00 2 34305 1147 0 0
x_make_qstorno_1200_2000 5670 5670 0 0.00 3 98199 3149 0 0
x_make_qstorno_2100_3300 481359 481359 0 0.00 0 61587 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-12-2021 19:40:22 to 07-12-2021 19:40:26.

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:07:58 1 8192000000 8797937760 8808726528 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:00 12967 8192000000 9888473984 10198487040 978889856 10.673 100 85 170 0 1007370416 6405136 22075424
17:10:16 19810 8192000000 9936334048 10257432576 1022925488 11.100 100 89 177 0 1054041840 8416272 22700080
17:11:42 15559 8192000000 9976595584 10307596288 1056749648 11.425 100 87 174 0 1089009632 10581072 21678912
17:13:07 9426 8192000000 10028620400 10372444160 1111859696 11.950 100 81 162 0 1142939088 12016576 19062816
17:14:26 10660 8192000000 10062059040 10421145600 1150814400 12.317 100 85 170 0 1183791088 12974416 20002272
17:15:40 6634 8192000000 10118327712 10489282560 1208966416 12.860 100 81 160 2 1239649264 13046000 17636848
17:16:50 4018 8192000000 10162982784 10541834240 1250014464 13.238 100 82 160 3 1280334400 12096528 18223408
17:17:56 3150 8192000000 10191703424 10574913536 1282413392 13.535 100 85 170 0 1314122112 12956560 18752160
17:19:01 1714 8192000000 10214064848 10602164224 1302590240 13.719 100 90 181 1 1335752880 12952448 20210192
17:20:05 5806 8192000000 10199168864 10602536960 1306242048 13.752 100 43 86 2 1323404656 7878416 9284192
17:21:15 7924 8192000000 10196489472 10614349824 1314565920 13.827 100 29 58 1 1328809088 7909520 6333648
17:22:29 11227 8192000000 10206658896 10628399104 1325717264 13.928 100 12 24 0 1334612416 5871696 3023456
17:23:41 71756 8192000000 10216754400 10642034688 1329808816 13.965 100 21 42 0 1340096464 5026304 5261344
17:25:54 7047 8192000000 10236189792 10656776192 1334513952 14.008 100 23 46 0 1350000944 10519632 4967360
17:27:02 5843 8192000000 10222112288 10638344192 1306774976 13.757 98 13 25 1 1315256992 4893952 3588064
17:28:13 55954 8192000000 10226938352 10642300928 1308827376 13.775 98 41 82 1 1325252896 5658272 10767248
17:30:09 7930 8192000000 10166015424 10569797632 1246073376 13.202 97 27 53 0 1257656352 5848688 5734288
17:31:17 9300 8192000000 10114580576 10505138176 1169331808 12.491 94 18 35 1 1178584832 5367536 3885488
17:32:29 5720 8192000000 10142373632 10541277184 1238988448 13.137 99 32 64 0 1252721632 5640640 8092544
17:33:37 42572 8192000000 10163169248 10572414976 1271003872 13.431 100 34 68 1 1285262032 6017344 8240816
17:35:20 7229 8192000000 10179961328 10590294016 1281717600 13.529 100 35 70 0 1295454144 5861952 7874592
17:36:30 9969 8192000000 10187017216 10605707264 1298951424 13.686 100 25 50 0 1310293152 5178720 6163008
17:37:41 6917 8192000000 10220356320 10640244736 1328008720 13.949 100 27 56 3 1339251488 4347040 6895728
17:38:50 42766 8192000000 10244816736 10661871616 1340015632 14.058 100 45 88 1 1357928480 8194304 9718544
17:40:52 15546 8192000000 10279123472 10695131136 1365814336 14.290 100 59 118 1 1394210032 14937376 13458320
17:42:10 13103 8192000000 10292497408 10712485888 1378568256 14.404 100 47 94 0 1406867936 16903472 11396208
17:43:26 7267 8192000000 10307563424 10728013824 1388584256 14.493 100 61 122 0 1422072992 19520336 13968400
17:44:40 8027 8192000000 10328037360 10746019840 1402919264 14.621 100 55 110 1 1435569744 19717776 12932704
17:45:52 9539 8192000000 10326924064 10745937920 1397049168 14.569 99 60 116 1 1435598576 23995360 14554048
17:47:14 4288 8192000000 10340239520 10768261120 1416416320 14.741 100 52 105 2 1457463088 27249296 13797472
17:48:24 5860 8192000000 10351160800 10771791872 1408899872 14.674 99 69 138 0 1454564592 29253280 16411440
17:49:33 4648 8192000000 10376347280 10804576256 1436143296 14.916 100 52 104 0 1487646656 37947632 13555728
17:50:42 3951 8192000000 10372320928 10796273664 1431533120 14.875 100 61 123 0 1481059296 34015424 15510752
17:51:50 4911 8192000000 10361641536 10770673664 1400828368 14.602 99 84 166 0 1458039072 37079552 20131152
17:53:01 4377 8192000000 10362573008 10777956352 1403691168 14.628 99 69 138 1 1461718448 40923504 17103776
17:54:11 5737 8192000000 10338877456 10752221184 1373451616 14.358 98 68 136 0 1432867184 41390496 18025072
17:55:23 7555 8192000000 10337055648 10759274496 1381864928 14.433 100 59 116 1 1446583824 49170976 15547920
17:56:40 19578 8192000000 10304639696 10731294720 1353612096 14.180 98 29 58 0 1393545184 32004784 7928304
17:58:05 7971 8192000000 10305975696 10731487232 1372758992 14.352 100 32 64 0 1411707488 30359888 8588608
17:59:17 5568 8192000000 10328866336 10754330624 1384369632 14.456 100 49 98 1 1434954720 37429968 13155120
18:00:27 26817 8192000000 10309716528 10747043840 1334994240 14.012 96 30 60 1 1382914080 39451568 8468272
18:01:58 23749 8192000000 10295746592 10737291264 1360213328 14.239 100 24 47 3 1403651328 36627136 6810864
18:03:25 29127 8192000000 10267306112 10703020032 1307964736 13.768 97 24 46 1 1338977680 24461056 6551888
18:04:57 30031 8192000000 10279489760 10710958080 1342973184 14.084 99 33 66 3 1367311456 16778224 7560048
18:06:35 63232 8192000000 10278158896 10717368320 1343039600 14.085 99 13 27 0 1357383040 11248144 3095296
18:08:41 48719 8192000000 10297595600 10725240832 1362872992 14.263 99 32 65 2 1378159888 8738848 6548048
18:10:33 120131 8192000000 10284669184 10733588480 1346379824 14.115 98 37 74 0 1375194944 19912608 8902512
18:13:35 54328 8192000000 10312176144 10749997056 1393322176 14.535 100 30 60 0 1410737008 10075808 7339024
18:15:31 30781 8192000000 10311253264 10754420736 1389539744 14.502 99 33 66 0 1409575360 11974192 8061424
18:17:07 61103 8192000000 10299044176 10764582912 1378629600 14.404 99 10 20 2 1401089440 19867312 2592528
18:19:23 45890 8192000000 10287773360 10743529472 1384111264 14.453 100 17 34 0 1400194672 12149744 3933664
18:21:18 47139 8192000000 10307212000 10762174464 1396935904 14.568 100 19 38 1 1414786944 13076304 4774736
18:23:18 24556 8192000000 10313489248 10760478720 1392167360 14.525 99 41 81 2 1413678064 12339632 9171072
18:25:11 70012 8192000000 10299649536 10763350016 1404615296 14.636 100 10 20 1 1416973888 10412032 1946560
18:27:45 42682 8192000000 10295546448 10768125952 1398651792 14.583 99 21 42 0 1412163504 8622208 4889504
18:29:41 40262 8192000000 10325184208 10784579584 1412930800 14.710 100 22 44 2 1429912944 11842160 5139984
18:31:23 28428 8192000000 10305964240 10762174464 1394712784 14.548 99 23 46 1 1408916240 9356816 4846640
18:32:52 52694 8192000000 10311167600 10781458432 1396085408 14.560 99 12 24 0 1413806416 14843408 2877600
18:34:48 46874 8192000000 10278126720 10729324544 1309232672 13.779 93 26 51 1 1323293136 8640432 5420032
18:36:37 88728 8192000000 10286549728 10747318272 1327913296 13.948 96 15 30 0 1341508752 10623888 2971568
18:39:05 75662 8192000000 10293247808 10744406016 1362595040 14.261 98 13 26 0 1386865856 21287312 2983504
18:41:21 53612 8192000000 10266692656 10712715264 1285771712 13.566 93 13 26 0 1306897712 18081632 3044368
18:43:19 82208 8192000000 10256637792 10706685952 1293530672 13.636 95 12 24 0 1314734256 18526192 2677392
18:45:43 55724 8192000000 10233360352 10665566208 1269179520 13.414 94 38 76 1 1286822032 8762864 8879648
18:47:41 60312 8192000000 10235633760 10673049600 1321370112 13.889 99 42 84 0 1341835152 10573392 9891648
18:49:42 22522 8192000000 10248379888 10689904640 1317374576 13.853 97 48 89 2 1334178592 5326160 11477856
18:51:06 77220 8192000000 10258023840 10709856256 1337179696 14.032 98 19 38 0 1353269536 11351424 4738416
18:53:24 60097 8192000000 10251864624 10707898368 1351735808 14.163 100 16 32 0 1370452208 14574032 4142368
18:56:12 39487 8192000000 10255738688 10695364608 1320289360 13.879 97 25 50 0 1333779248 7663504 5826384
18:57:54 17463 8192000000 10275957952 10721026048 1364966224 14.282 100 27 54 1 1378818768 8222512 5630032
18:59:12 44193 8192000000 10287859056 10739015680 1384101200 14.453 100 26 52 1 1400098000 9857472 6139328
19:00:57 40090 8192000000 10274765120 10726666240 1349660912 14.144 98 28 56 0 1368393552 12464064 6268576
19:02:38 52095 8192000000 10296166160 10744451072 1363650992 14.270 99 26 52 0 1385284528 15607728 6025808
19:04:52 50838 8192000000 10272055232 10747588608 1351318192 14.159 98 17 34 1 1378411664 22384544 4708928
19:06:47 97813 8192000000 10302725184 10750955520 1389790592 14.504 100 19 38 0 1403847984 9820816 4236576
19:09:26 74529 8192000000 10301956320 10759987200 1398283712 14.580 100 29 58 0 1412375680 7220480 6871488
19:11:48 61716 8192000000 10301942672 10768519168 1391173008 14.516 99 15 29 0 1402421920 7914960 3333952
19:14:05 51007 8192000000 10292509536 10762969088 1393211344 14.535 100 7 14 0 1407185008 12342688 1630976
19:16:30 93659 8192000000 10281705616 10738089984 1360335264 14.240 97 24 48 0 1377745104 11907696 5502144
19:19:32 64795 8192000000 10271290768 10731991040 1333130208 13.995 96 14 28 0 1344266128 8127840 3008080
19:21:37 39959 8192000000 10287117424 10742595584 1369727792 14.325 99 33 65 0 1385893536 8734432 7431312
19:23:25 43697 8192000000 10285922528 10742833152 1370275824 14.330 99 16 32 0 1382835632 8903440 3656368
19:25:09 29134 8192000000 10275692464 10731143168 1345724944 14.109 97 21 42 0 1362834048 11889040 5220064
19:26:39 43707 8192000000 10294914880 10740985856 1358097536 14.220 98 41 82 0 1379154208 12107696 8948976
19:28:26 129029 8192000000 10232297360 10672730112 1199898320 12.775 88 23 46 1 1218538752 13740592 4899840
19:31:41 126859 8192000000 10259151152 10716049408 1333485504 13.999 98 31 60 2 1350960784 10743264 6732016
19:35:23 50991 8192000000 10253451840 10707746816 1344833520 14.101 99 11 22 3 1354642608 7152368 2656720

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-12-2021 19:40:28 to 07-12-2021 19:40:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 126 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-12-2021 19:40:44 to 07-12-2021 19:40:46.

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          245283
MON$OLDEST_ACTIVE               247379
MON$OLDEST_SNAPSHOT             247379
MON$NEXT_TRANSACTION            247379
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                       2025568
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.33542 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33542 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33542 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33542 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.12.21 19:40:47. 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
2021-12-07 19:40:49 No severe PSQL-related problems occured
07.12.21 19:40:50. End of report.