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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16675732 kB
MemAvailable 31616312 kB
Buffers 0 kB
Cached 15012112 kB
SwapCached 2236 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-03-24 19:38:10
DTS_END                         2022-03-24 19:38:10.2510
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/34974
TRN_ID                          249456
ATT_ID                          695
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: 9291.67 18914 1115000
customer order: creation 185.13 344 22215
customer order: refuse 16.42 357 1970
order to supplier: creation 48.72 1249 5846
order to supplier: removal 3.51 88218 421
invoice (draft): creation 48.35 3197 5802
invoice (draft): removal 52.13 751 6256
invoice accept: apply 44.29 17917 5315
invoice accept: cancel 47.11 865 5653
customer reserve: creation 3980.40 181 477648
customer reserve: removal 4545.24 53 545429
realization accept: apply 90.45 184 10854
realization accept: cancel 91.48 112 10977
payment from customer: creation 63.24 127 7589
payment from customer: removal 5.98 102 718
payment to supplier: creation 53.04 123 6365
payment to supplier: removal 3.40 113 408
service: total inventory turnovers 5.00 1029 600
service: total monetary turnovers 6.93 28 831
service: aggregate perf. data 0.83 22290 99
service: refresh index statistics 0.03 241034 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 24-03-2022 19:38:45 to 24-03-2022 19:38:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7820
TEST_TIME 2 5971
TEST_TIME 3 5169
TEST_TIME 4 5174
TEST_TIME 5 5432
TEST_TIME 6 5568
TEST_TIME 7 6072
TEST_TIME 8 6495
TEST_TIME 9 6485
TEST_TIME 10 6635
TEST_TIME 11 6816
TEST_TIME 12 6990
TEST_TIME 13 7183
TEST_TIME 14 7140
TEST_TIME 15 7757
TEST_TIME 16 7960
TEST_TIME 17 7974
TEST_TIME 18 8568
TEST_TIME 19 8879
TEST_TIME 20 8814
TEST_TIME 21 9261
TEST_TIME 22 9159
TEST_TIME 23 9484
TEST_TIME 24 9689
TEST_TIME 25 10115
TEST_TIME 26 10160
TEST_TIME 27 10388
TEST_TIME 28 10853
TEST_TIME 29 11039
TEST_TIME 30 10854
TEST_TIME 31 10679
TEST_TIME 32 10822
TEST_TIME 33 10716
TEST_TIME 34 10490
TEST_TIME 35 10214
TEST_TIME 36 10353
TEST_TIME 37 10288
TEST_TIME 38 10172
TEST_TIME 39 10037
TEST_TIME 40 9965
TEST_TIME 41 10002
TEST_TIME 42 10063
TEST_TIME 43 10050
TEST_TIME 44 9955
TEST_TIME 45 9920
TEST_TIME 46 9888
TEST_TIME 47 9791
TEST_TIME 48 9785
TEST_TIME 49 9730
TEST_TIME 50 9697
TEST_TIME 51 9612
TEST_TIME 52 9662
TEST_TIME 53 9607
TEST_TIME 54 9658
TEST_TIME 55 9626
TEST_TIME 56 9485
TEST_TIME 57 9524
TEST_TIME 58 9490
TEST_TIME 59 9577
TEST_TIME 60 9540
TEST_TIME 61 9507
TEST_TIME 62 9580
TEST_TIME 63 9683
TEST_TIME 64 9672
TEST_TIME 65 9607
TEST_TIME 66 9718
TEST_TIME 67 9757
TEST_TIME 68 9702
TEST_TIME 69 9708
TEST_TIME 70 9695
TEST_TIME 71 9691
TEST_TIME 72 9688
TEST_TIME 73 9668
TEST_TIME 74 9558
TEST_TIME 75 9546
TEST_TIME 76 9509
TEST_TIME 77 9474
TEST_TIME 78 9467
TEST_TIME 79 9426
TEST_TIME 80 9366
TEST_TIME 81 9349
TEST_TIME 82 9289
TEST_TIME 83 9243
TEST_TIME 84 9188
TEST_TIME 85 9187
TEST_TIME 86 9195
TEST_TIME 87 9186
TEST_TIME 88 9187
TEST_TIME 89 9171
TEST_TIME 90 9276
TEST_TIME 91 9277
TEST_TIME 92 9316
TEST_TIME 93 9397
TEST_TIME 94 9410
TEST_TIME 95 9416
TEST_TIME 96 9448
TEST_TIME 97 9400
TEST_TIME 98 9451
TEST_TIME 99 9443
TEST_TIME 100 9419
TEST_TIME 101 9400
TEST_TIME 102 9355
TEST_TIME 103 9337
TEST_TIME 104 9392
TEST_TIME 105 9341
TEST_TIME 106 9307
TEST_TIME 107 9273
TEST_TIME 108 9290
TEST_TIME 109 9315
TEST_TIME 110 9288
TEST_TIME 111 9312
TEST_TIME 112 9302
TEST_TIME 113 9319
TEST_TIME 114 9305
TEST_TIME 115 9370
TEST_TIME 116 9378
TEST_TIME 117 9357
TEST_TIME 118 9388
TEST_TIME 119 9364
TEST_TIME 120 9295

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-03-2022 19:38:49 to 24-03-2022 19:38:52.

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 1557937 1557937 0 0.00 0 17821 7 0 0
doc_list_biud 1557935 1557935 0 0.00 0 35343 24 0 0
sp_add_invoice_to_stock 5459 5315 144 2.63 28 161742 17917 0 144
sp_cancel_adding_invoice 5653 5653 0 0.00 4 66378 866 0 0
sp_cancel_client_order 1970 1970 0 0.00 3 18843 357 0 0
sp_cancel_customer_reserve 545429 545429 0 0.00 1 35345 54 0 0
sp_cancel_pay_from_customer 718 718 0 0.00 2 11830 102 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 3 7864 114 0 0
sp_cancel_supplier_invoice 6265 6256 9 0.14 2 21566 752 0 9
sp_cancel_supplier_order 421 421 0 0.00 6 945997 88218 0 0
sp_cancel_write_off 10977 10977 0 0.00 1 17867 113 0 0
sp_client_order 22215 22215 0 0.00 3 19267 344 0 0
sp_customer_reserve 477648 477648 0 0.00 0 27303 182 0 0
sp_fill_shopping_cart 33859 33859 0 0.00 1 14203 36 0 0
sp_fill_shopping_cart_clo_res 477643 477643 0 0.00 0 20547 13 0 0
sp_get_clo_for_invoice 5310 5310 0 0.00 0 45127 293 0 0
sp_kill_cost_storno 17756 17756 0 0.00 0 17813 55 0 0
sp_kill_qstorno_ret_qs2qd 1108512 1108512 0 0.00 0 35335 29 0 0
sp_kill_qty_storno 586868 586868 0 0.00 0 35341 64 0 0
sp_lock_selected_doc 22378 22378 0 0.00 0 9447 7 0 0
sp_make_cost_storno 30118 30118 0 0.00 0 12897 57 0 0
sp_multiply_rows_for_pdistr 30117 30117 0 0.00 0 17703 32 0 0
sp_multiply_rows_for_qdistr 22215 22215 0 0.00 0 15269 151 0 0
sp_pay_from_customer 7589 7589 0 0.00 4 16862 127 0 0
sp_pay_to_supplier 6365 6365 0 0.00 4 14632 123 0 0
sp_payment_common 13954 13954 0 0.00 1 16835 28 0 0
sp_qd_handle_on_cancel_clo 1970 1970 0 0.00 0 18835 321 0 0
sp_qd_handle_on_invoice_upd_sts 10963 10963 0 0.00 0 33859 320 0 0
sp_qd_handle_on_reserve_upd_sts 10852 10852 0 0.00 0 12692 56 0 0
sp_reserve_write_off 10854 10854 0 0.00 7 16412 184 0 0
sp_supplier_invoice 5802 5802 0 0.00 6 82840 3198 0 0
sp_supplier_order 5846 5846 0 0.00 5 23519 1249 0 0
srv_aggregate_perf_data 99 99 0 0.00 2513 253766 22291 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1923 131671 46521 0 0
srv_find_qd_qs_mism 1064866 1064866 0 0.00 0 17679 6 0 0
srv_make_invnt_saldo 600 600 0 0.00 34 47515 1030 0 0
srv_make_money_saldo 831 831 0 0.00 0 2444 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 129302 332373 241034 0 0
t$perf-abend:sp_add_invoice_to_stock 144 144 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 5315 5315 0 0.00 0 8261 157 0 0
t$perf-norm:sp_cancel_adding_invoice 476 476 0 0.00 1 5589 106 0 0
t$perf-norm:sp_cancel_client_order 1970 1970 0 0.00 0 18935 29 0 0
t$perf-norm:sp_cancel_customer_reserve 736 736 0 0.00 0 442 5 0 0
t$perf-norm:sp_cancel_pay_from_customer 718 718 0 0.00 0 2899 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 616 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 387 387 0 0.00 0 611 10 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 30516 1327 0 0
t$perf-norm:sp_cancel_write_off 1100 1100 0 0.00 0 5478 13 0 0
t$perf-norm:sp_client_order 22215 22215 0 0.00 0 8261 18 0 0
t$perf-norm:sp_customer_reserve 1090 1090 0 0.00 0 3470 9 0 0
t$perf-norm:sp_pay_from_customer 7589 7589 0 0.00 0 11022 10 0 0
t$perf-norm:sp_pay_to_supplier 6365 6365 0 0.00 0 7375 14 0 0
t$perf-norm:sp_reserve_write_off 10854 10854 0 0.00 0 8227 14 0 0
t$perf-norm:sp_supplier_invoice 5802 5802 0 0.00 0 6564 18 0 0
t$perf-norm:sp_supplier_order 5846 5846 0 0.00 0 8747 22 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 122 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 79 2 0 0
t$perf-norm:srv_make_invnt_saldo 600 600 0 0.00 0 2043 20 0 0
t$perf-norm:srv_make_money_saldo 831 831 0 0.00 0 3621 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10908 10620 288 2.64 0 4484 17 0 288
v_all_customers 40188 40188 0 0.00 0 33 1 0 0
v_all_suppliers 32552 32552 0 0.00 0 33 1 0 0
v_all_wares 44430 44430 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 952 952 0 0.00 0 4395 12 0 0
v_cancel_client_order 3940 3940 0 0.00 0 3722 6 0 0
v_cancel_customer_prepayment 1436 1436 0 0.00 0 2386 5 0 0
v_cancel_customer_reserve 1472 1472 0 0.00 0 2861 6 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 3295 10 0 0
v_cancel_supplier_invoice 792 774 18 2.27 0 904 12 0 18
v_cancel_supplier_order 842 842 0 0.00 0 1088 6 0 0
v_cancel_write_off 2200 2200 0 0.00 0 3727 16 0 0
v_max_id_clo_ord 5845 5845 0 0.00 0 1491 1 0 0
v_max_id_clo_res 1090 1090 0 0.00 0 4086 17 0 0
v_max_id_ord_sup 5797 5797 0 0.00 0 6390 6 0 0
v_max_non_paid_invoice 1731 1731 0 0.00 0 393 2 0 0
v_max_non_paid_realizn 4082 4082 0 0.00 0 2128 3 0 0
v_min_id_clo_ord 5845 5845 0 0.00 0 3537 10 0 0
v_min_id_clo_res 1090 1090 0 0.00 0 1933 8 0 0
v_min_id_ord_sup 5797 5797 0 0.00 0 5709 6 0 0
v_min_non_paid_invoice 6365 6365 0 0.00 0 6530 14 0 0
v_min_non_paid_realizn 7589 7589 0 0.00 0 16811 28 0 0
v_our_firm 11256 11256 0 0.00 0 20 1 0 0
v_reserve_write_off 21704 21704 0 0.00 0 4036 6 0 0
x_lock_depdocs_on_canc_invoice 11522 11522 0 0.00 0 22841 107 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 30279 416 0 0
x_make_qstorno_1000_1200 5846 5846 0 0.00 1 23510 1161 0 0
x_make_qstorno_1200_2000 5802 5802 0 0.00 2 82830 3077 0 0
x_make_qstorno_2100_3300 477648 477648 0 0.00 0 27302 167 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 24-03-2022 19:38:53 to 24-03-2022 19:38:58.

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:13 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:14 33513 8192000000 9906012576 10214133760 982725568 10.711 100 94 188 0 1013404832 6177312 24501952
17:10:55 22533 8192000000 9951444240 10273370112 1034568416 11.212 100 88 176 0 1065947616 8899296 22479904
17:12:22 17409 8192000000 9987725056 10324664320 1072833584 11.579 100 78 156 0 1102013056 10159760 19019712
17:13:45 11895 8192000000 10050566816 10399948800 1132956752 12.149 100 82 164 1 1164446192 12462016 19027424
17:14:58 8571 8192000000 10090145168 10451075072 1175746160 12.551 100 78 156 1 1207361360 13425008 18190192
17:16:09 5387 8192000000 10132185984 10499481600 1213378352 12.900 100 86 172 1 1248139104 15401984 19358768
17:17:17 6861 8192000000 10147982048 10528026624 1241878640 13.164 100 78 155 2 1271458016 12730496 16848880
17:18:25 2580 8192000000 10192317280 10570772480 1274723888 13.465 100 90 180 0 1308654800 13677408 20253504
17:19:29 25565 8192000000 10206380672 10602074112 1299142464 13.687 100 51 103 0 1320135040 9109376 11883200
17:20:57 6225 8192000000 10215953344 10615570432 1312005424 13.804 100 46 90 2 1330832544 8837568 9989552
17:22:04 8077 8192000000 10207287264 10628579328 1326644672 13.937 100 15 30 1 1336293488 6028496 3620320
17:23:14 9882 8192000000 10218031216 10639495168 1334346576 14.006 100 11 22 1 1342476112 5823248 2306288
17:24:25 70963 8192000000 10234776064 10654744576 1341879360 14.074 100 38 76 0 1356372224 5289520 9203344
17:26:36 7499 8192000000 10234685488 10658709504 1333329904 13.997 99 25 51 0 1344864576 5733664 5801008
17:27:47 7619 8192000000 10230918064 10645106688 1323837232 13.911 99 27 54 1 1336550672 6905696 5807744
17:28:56 11303 8192000000 10179869744 10587222016 1250891216 13.246 96 23 46 0 1261835680 5795312 5149152
17:30:12 34539 8192000000 10152628048 10562973696 1208156416 12.852 94 27 54 1 1220159456 5347920 6655120
17:31:47 3271 8192000000 10126102272 10523340800 1213612912 12.903 98 29 58 0 1226415680 5365840 7436928
17:32:51 7984 8192000000 10162871648 10558386176 1239080464 13.138 98 34 68 1 1253020576 5022096 8918016
17:34:00 8957 8192000000 10175685216 10582949888 1280137968 13.514 100 21 42 0 1291454480 5678224 5638288
17:35:54 5550 8192000000 10180518800 10592870400 1293455456 13.636 100 30 59 0 1306258128 6686192 6116480
17:37:00 12635 8192000000 10191990160 10610225152 1301448752 13.708 100 29 56 1 1314288960 5872432 6967776
17:38:22 28625 8192000000 10231511472 10639118336 1325061264 13.923 100 30 60 1 1336514336 4479392 6973680
17:39:53 70640 8192000000 10254887200 10680360960 1354673632 14.190 100 34 66 1 1370700704 8326176 7700896
17:42:09 20179 8192000000 10276674000 10701635584 1372325344 14.348 100 34 68 1 1392545680 11809856 8410480
17:43:33 9495 8192000000 10300452848 10721705984 1384406624 14.456 100 51 102 3 1413223040 17221632 11594784
17:44:48 7760 8192000000 10315786864 10731716608 1389382272 14.500 100 56 112 0 1418988240 16033008 13572960
17:46:00 4566 8192000000 10326421456 10748416000 1401656368 14.610 100 61 121 1 1440796272 24935600 14204304
17:47:08 7718 8192000000 10308198240 10735300608 1380664496 14.422 99 48 95 2 1413623232 21849072 11109664
17:48:23 7729 8192000000 10325217664 10751737856 1396834192 14.567 100 53 104 2 1436679152 27719552 12125408
17:49:33 2702 8192000000 10324211584 10731642880 1380719136 14.423 99 78 156 3 1430165808 30883328 18563344
17:50:39 5303 8192000000 10329387088 10745733120 1375887360 14.380 99 63 120 2 1426687936 36218128 14582448
17:51:50 1923 8192000000 10303592624 10710450176 1355058624 14.193 99 77 154 0 1409988560 34817184 20112752
17:52:57 2971 8192000000 10331472768 10743877632 1380349344 14.420 100 77 151 2 1439850592 40569664 18931584
17:54:07 5744 8192000000 10332204480 10748018688 1385472240 14.465 100 64 126 3 1438949248 37833216 15643792
17:55:18 3491 8192000000 10323312800 10729222144 1366043760 14.292 99 77 154 1 1425538688 40776688 18718240
17:56:24 2607 8192000000 10336737488 10744233984 1362733536 14.262 99 84 168 2 1427443344 43523376 21186432
17:57:29 3376 8192000000 10326152816 10734493696 1365927872 14.291 99 67 135 2 1424566768 41603360 17035536
17:58:38 4260 8192000000 10329165776 10743255040 1361786016 14.253 99 69 136 0 1423998272 44130288 18081968
17:59:45 9160 8192000000 10319786304 10746458112 1363742592 14.271 98 40 80 1 1409456752 35332048 10382112
18:00:57 18021 8192000000 10325349472 10752249856 1377038384 14.390 100 41 82 1 1430309744 42160720 11110640
18:02:22 16480 8192000000 10319052384 10750853120 1380890496 14.425 100 35 69 1 1420219008 30152816 9175696
18:03:40 23353 8192000000 10302577328 10752888832 1362798576 14.262 99 23 46 1 1402731296 33570032 6362688
18:05:05 34066 8192000000 10289365712 10733457408 1362258192 14.258 100 21 41 1 1401700320 33436960 6005168
18:07:34 71244 8192000000 10286926240 10734518272 1359403984 14.232 99 8 16 0 1375485248 14624656 1456608
18:09:55 54190 8192000000 10292013440 10741493760 1380917024 14.425 100 17 32 1 1395645552 11413472 3315056
18:11:51 55480 8192000000 10303184032 10744676352 1374312240 14.366 99 23 46 0 1395828368 16493792 5022336
18:14:28 86732 8192000000 10303088352 10768478208 1394296560 14.544 100 8 16 0 1413221168 17355200 1569408
18:16:56 35332 8192000000 10321746720 10782535680 1410343744 14.687 100 19 38 2 1431669504 16317136 5008624
18:18:32 78787 8192000000 10304969456 10763165696 1374810688 14.370 97 28 56 0 1394622368 12876336 6935344
18:21:00 38213 8192000000 10303955920 10768281600 1388231280 14.490 99 12 24 0 1401584528 10728560 2624688
18:22:40 59378 8192000000 10300717440 10771476480 1398384176 14.581 100 6 12 0 1413808928 14143536 1281216
18:24:45 69401 8192000000 10308413696 10778140672 1407903200 14.665 100 14 28 1 1422104144 11187328 3013616
18:26:56 57074 8192000000 10333307136 10794012672 1415643200 14.734 100 30 60 2 1432712480 10452224 6617056
18:29:00 58080 8192000000 10308446384 10778710016 1379236848 14.410 98 12 24 0 1399387136 17119056 3031232
18:30:59 56852 8192000000 10296472432 10764267520 1371352656 14.339 98 11 22 0 1390850288 16760272 2737360
18:32:58 98980 8192000000 10297951328 10749083648 1357448496 14.214 97 19 38 0 1378057264 16317584 4291184
18:35:38 49579 8192000000 10307006160 10769043456 1375408912 14.375 98 16 32 0 1391310064 12447264 3453888
18:37:29 59105 8192000000 10287824880 10755801088 1354639440 14.189 97 8 16 0 1368552656 12412464 1500752
18:39:29 31402 8192000000 10279897168 10728656896 1349699856 14.145 98 20 40 0 1365361776 11524352 4137568
18:41:09 53754 8192000000 10270607216 10723033088 1325173008 13.924 96 10 20 0 1337691200 10404784 2113408
18:43:03 53379 8192000000 10283403712 10721009664 1314030368 13.823 96 31 62 0 1338568816 17907168 6631280
18:45:03 120287 8192000000 10278073904 10724388864 1304945664 13.740 95 13 26 0 1322760016 15106080 2708272
18:48:03 52725 8192000000 10254618096 10688692224 1292284912 13.625 95 21 42 1 1304597520 8003392 4309216
18:49:57 49607 8192000000 10262325728 10702671872 1302850448 13.721 96 30 60 1 1320904672 11024528 7029696
18:51:47 110451 8192000000 10247937152 10696880128 1324746720 13.920 98 15 30 0 1341307792 13302512 3258560
18:54:41 43889 8192000000 10226627808 10666557440 1302449968 13.718 96 20 38 2 1314276144 7532800 4293376
18:56:36 53134 8192000000 10232251472 10692788224 1330751568 13.974 99 6 12 0 1341104128 9057216 1295344
18:58:31 62430 8192000000 10246391088 10705264640 1342746768 14.082 99 10 21 0 1352161840 6932304 2482768
19:00:36 43287 8192000000 10265962128 10717134848 1363900736 14.272 100 24 48 1 1375470016 6366480 5202800
19:02:20 74772 8192000000 10246778928 10710061056 1347800368 14.128 99 12 24 1 1358834352 8341696 2692288
19:04:35 80148 8192000000 10282672800 10735833088 1353106048 14.175 98 17 34 0 1373877776 16980256 3791472
19:07:10 37108 8192000000 10292704768 10746310656 1381706240 14.432 100 30 60 1 1404734352 15322432 7705680
19:08:59 56485 8192000000 10307775856 10773872640 1392983456 14.532 100 13 26 0 1411543952 15848080 2712416
19:11:55 131670 8192000000 10282815792 10740948992 1278387536 13.498 91 14 28 1 1290949856 9942272 2620048
19:15:07 26063 8192000000 10299844832 10740666368 1378723280 14.405 100 40 78 1 1396156304 8761392 8671632
19:16:39 51949 8192000000 10285957808 10742681600 1367933168 14.309 99 21 42 0 1383745568 11389296 4423104
19:18:39 90549 8192000000 10295601552 10745417728 1362115392 14.256 98 33 66 1 1375567216 5753280 7698544
19:21:19 42603 8192000000 10278725760 10736996352 1361602896 14.252 98 22 44 0 1381924752 15232240 5089616
19:23:02 73083 8192000000 10275022480 10740203520 1379112624 14.409 100 6 12 0 1392723504 12181984 1428896
19:25:16 71181 8192000000 10269725984 10732056576 1366927104 14.300 99 17 34 1 1384574848 13533824 4113920
19:27:37 71688 8192000000 10275452624 10721222656 1317713840 13.856 96 21 40 1 1332828368 10414192 4700336
19:29:55 61022 8192000000 10277292928 10721972224 1306715824 13.756 95 27 54 0 1324521888 11617184 6188880
19:31:56 42477 8192000000 10267743456 10708033536 1336440752 14.025 99 26 52 4 1355263280 12319168 6503360
19:33:39 101049 8192000000 10274571856 10724323328 1340483488 14.062 98 14 28 0 1352515520 9061984 2970048
19:36:26 72433 8192000000 10273651072 10712838144 1309133696 13.778 96 22 43 0 1328676528 15100272 4442560

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-03-2022 19:38:59 to 24-03-2022 19:39:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 144 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 24-03-2022 19:39:15 to 24-03-2022 19:39:17.

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          249500
MON$OLDEST_ACTIVE               251005
MON$OLDEST_SNAPSHOT             251005
MON$NEXT_TRANSACTION            251005
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                       2035808
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.33574 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33574 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33574 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33574 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 ---
:375: 
:376: mscs-test-161.budzdorov.ru    Thu Mar 24 19:38:14 2022
:377:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:378: 
:379: 
:380: mscs-test-161.budzdorov.ru    Thu Mar 24 19:38:15 2022
:381:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:382: 
:383: 
:384: mscs-test-161.budzdorov.ru    Thu Mar 24 19:38:15 2022
:385:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:386: 
:387: 
:388: mscs-test-161.budzdorov.ru    Thu Mar 24 19:38:15 2022
:389:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:390: 
:391: 
:392: mscs-test-161.budzdorov.ru    Thu Mar 24 19:38:16 2022
:393:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:394: 
--- end of diff output ---

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

24.03.22 19:39:18. 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-03-24 19:39:21 No severe PSQL-related problems occured
24.03.22 19:39:22. End of report.