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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16776296 kB
MemAvailable 31489024 kB
Buffers 0 kB
Cached 14915684 kB
SwapCached 2688 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.10
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-04-15 22:38:14
DTS_END                         2022-04-15 22:38:14.5310
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/43868
TRN_ID                          234488
ATT_ID                          664
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: 9023.95 21313 1082874
customer order: creation 167.16 401 20059
customer order: refuse 14.35 381 1722
order to supplier: creation 44.31 1370 5317
order to supplier: removal 3.44 98520 413
invoice (draft): creation 45.59 3714 5471
invoice (draft): removal 53.21 749 6385
invoice accept: apply 40.26 18081 4831
invoice accept: cancel 47.83 765 5739
customer reserve: creation 3667.95 181 440154
customer reserve: removal 4653.98 57 558478
realization accept: apply 80.93 182 9712
realization accept: cancel 80.95 105 9714
payment from customer: creation 56.50 107 6780
payment from customer: removal 5.14 110 617
payment to supplier: creation 47.33 148 5679
payment to supplier: removal 3.24 62 389
service: total inventory turnovers 4.55 1034 546
service: total monetary turnovers 6.43 26 771
service: aggregate perf. data 0.78 23903 93
service: refresh index statistics 0.03 276362 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-04-2022 22:39:14 to 15-04-2022 22:39:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5620
TEST_TIME 2 6453
TEST_TIME 3 5977
TEST_TIME 4 5639
TEST_TIME 5 5628
TEST_TIME 6 5696
TEST_TIME 7 5820
TEST_TIME 8 5801
TEST_TIME 9 6007
TEST_TIME 10 6208
TEST_TIME 11 6254
TEST_TIME 12 6581
TEST_TIME 13 7059
TEST_TIME 14 7302
TEST_TIME 15 7622
TEST_TIME 16 7922
TEST_TIME 17 8415
TEST_TIME 18 8644
TEST_TIME 19 8740
TEST_TIME 20 8935
TEST_TIME 21 9096
TEST_TIME 22 9433
TEST_TIME 23 9469
TEST_TIME 24 9549
TEST_TIME 25 9872
TEST_TIME 26 10150
TEST_TIME 27 10328
TEST_TIME 28 10339
TEST_TIME 29 10630
TEST_TIME 30 10967
TEST_TIME 31 10664
TEST_TIME 32 10374
TEST_TIME 33 10228
TEST_TIME 34 10031
TEST_TIME 35 9903
TEST_TIME 36 9829
TEST_TIME 37 9796
TEST_TIME 38 9878
TEST_TIME 39 9712
TEST_TIME 40 9652
TEST_TIME 41 9495
TEST_TIME 42 9558
TEST_TIME 43 9683
TEST_TIME 44 9602
TEST_TIME 45 9438
TEST_TIME 46 9333
TEST_TIME 47 9255
TEST_TIME 48 9236
TEST_TIME 49 9154
TEST_TIME 50 9361
TEST_TIME 51 9265
TEST_TIME 52 9176
TEST_TIME 53 9143
TEST_TIME 54 9066
TEST_TIME 55 8980
TEST_TIME 56 8838
TEST_TIME 57 8913
TEST_TIME 58 9026
TEST_TIME 59 9015
TEST_TIME 60 9027
TEST_TIME 61 9023
TEST_TIME 62 8986
TEST_TIME 63 9124
TEST_TIME 64 9210
TEST_TIME 65 9263
TEST_TIME 66 9212
TEST_TIME 67 9191
TEST_TIME 68 9342
TEST_TIME 69 9301
TEST_TIME 70 9372
TEST_TIME 71 9370
TEST_TIME 72 9310
TEST_TIME 73 9393
TEST_TIME 74 9359
TEST_TIME 75 9250
TEST_TIME 76 9275
TEST_TIME 77 9225
TEST_TIME 78 9215
TEST_TIME 79 9164
TEST_TIME 80 9126
TEST_TIME 81 9102
TEST_TIME 82 9125
TEST_TIME 83 9051
TEST_TIME 84 8981
TEST_TIME 85 8973
TEST_TIME 86 9058
TEST_TIME 87 9032
TEST_TIME 88 9028
TEST_TIME 89 9115
TEST_TIME 90 9124
TEST_TIME 91 9113
TEST_TIME 92 9106
TEST_TIME 93 9140
TEST_TIME 94 9190
TEST_TIME 95 9201
TEST_TIME 96 9212
TEST_TIME 97 9282
TEST_TIME 98 9286
TEST_TIME 99 9299
TEST_TIME 100 9242
TEST_TIME 101 9182
TEST_TIME 102 9189
TEST_TIME 103 9151
TEST_TIME 104 9110
TEST_TIME 105 9108
TEST_TIME 106 9057
TEST_TIME 107 9013
TEST_TIME 108 9030
TEST_TIME 109 9012
TEST_TIME 110 8965
TEST_TIME 111 9022
TEST_TIME 112 9042
TEST_TIME 113 9012
TEST_TIME 114 8998
TEST_TIME 115 9036
TEST_TIME 116 9033
TEST_TIME 117 9101
TEST_TIME 118 9071
TEST_TIME 119 9091
TEST_TIME 120 9026

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-04-2022 22:39:18 to 15-04-2022 22:39: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 1491129 1491129 0 0.00 0 19302 6 0 0
doc_list_biud 1491129 1491129 0 0.00 0 80452 27 0 0
sp_add_invoice_to_stock 4960 4831 129 2.60 34 165511 18082 0 129
sp_cancel_adding_invoice 5739 5739 0 0.00 5 52316 766 0 0
sp_cancel_client_order 1722 1722 0 0.00 4 8936 381 0 0
sp_cancel_customer_reserve 558478 558478 0 0.00 1 80454 57 0 0
sp_cancel_pay_from_customer 617 617 0 0.00 3 12779 110 0 0
sp_cancel_pay_to_supplier 389 389 0 0.00 2 2660 62 0 0
sp_cancel_supplier_invoice 6393 6385 8 0.12 2 27133 750 0 8
sp_cancel_supplier_order 413 413 0 0.00 9 914250 98521 0 0
sp_cancel_write_off 9714 9714 0 0.00 1 17909 105 0 0
sp_client_order 20059 20059 0 0.00 3 80291 402 0 0
sp_customer_reserve 440154 440154 0 0.00 0 31694 181 0 0
sp_fill_shopping_cart 30829 30829 0 0.00 1 13871 41 0 0
sp_fill_shopping_cart_clo_res 440148 440148 0 0.00 0 14009 14 0 0
sp_get_clo_for_invoice 4823 4823 0 0.00 0 29045 344 0 0
sp_kill_cost_storno 16459 16459 0 0.00 0 17883 58 0 0
sp_kill_qstorno_ret_qs2qd 1133468 1133468 0 0.00 0 80450 31 0 0
sp_kill_qty_storno 596984 596984 0 0.00 0 80452 67 0 0
sp_lock_selected_doc 20125 20125 0 0.00 0 8504 5 0 0
sp_make_cost_storno 26992 26992 0 0.00 0 19021 52 0 0
sp_multiply_rows_for_pdistr 26992 26992 0 0.00 0 10444 31 0 0
sp_multiply_rows_for_qdistr 20059 20059 0 0.00 0 80105 165 0 0
sp_pay_from_customer 6780 6780 0 0.00 4 21207 108 0 0
sp_pay_to_supplier 5679 5679 0 0.00 4 19317 148 0 0
sp_payment_common 12459 12459 0 0.00 1 19866 30 0 0
sp_qd_handle_on_cancel_clo 1722 1722 0 0.00 0 8833 344 0 0
sp_qd_handle_on_invoice_upd_sts 10560 10560 0 0.00 0 24852 346 0 0
sp_qd_handle_on_reserve_upd_sts 9712 9712 0 0.00 0 20085 59 0 0
sp_reserve_write_off 9712 9712 0 0.00 6 20104 182 0 0
sp_supplier_invoice 5471 5471 0 0.00 7 87136 3714 0 0
sp_supplier_order 5317 5317 0 0.00 5 33343 1371 0 0
srv_aggregate_perf_data 93 93 0 0.00 2372 356077 23903 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2498 139335 49960 0 0
srv_find_qd_qs_mism 1037333 1037333 0 0.00 0 10313 5 0 0
srv_make_invnt_saldo 546 546 0 0.00 35 35039 1035 0 0
srv_make_money_saldo 771 771 0 0.00 1 1470 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 151327 630603 276363 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4831 4831 0 0.00 0 10401 152 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 0 5467 136 0 0
t$perf-norm:sp_cancel_client_order 1722 1722 0 0.00 0 7465 21 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 4727 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 617 617 0 0.00 0 2744 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 389 389 0 0.00 0 4297 33 0 0
t$perf-norm:sp_cancel_supplier_invoice 407 407 0 0.00 0 1864 14 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 22987 1620 0 0
t$perf-norm:sp_cancel_write_off 976 976 0 0.00 0 3882 8 0 0
t$perf-norm:sp_client_order 20059 20059 0 0.00 0 7954 20 0 0
t$perf-norm:sp_customer_reserve 972 972 0 0.00 0 11087 20 0 0
t$perf-norm:sp_pay_from_customer 6780 6780 0 0.00 0 6453 13 0 0
t$perf-norm:sp_pay_to_supplier 5679 5679 0 0.00 0 5615 12 0 0
t$perf-norm:sp_reserve_write_off 9713 9713 0 0.00 0 7250 14 0 0
t$perf-norm:sp_supplier_invoice 5471 5471 0 0.00 0 6107 22 0 0
t$perf-norm:sp_supplier_order 5317 5317 0 0.00 0 7378 18 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 112 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 154 4 0 0
t$perf-norm:srv_make_invnt_saldo 546 546 0 0.00 0 3631 31 0 0
t$perf-norm:srv_make_money_saldo 771 771 0 0.00 0 4866 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 10 5 0 0
v_add_invoice_to_stock 9900 9642 258 2.60 0 4141 17 0 258
v_all_customers 36712 36712 0 0.00 0 17 1 0 0
v_all_suppliers 29750 29750 0 0.00 0 15 1 0 0
v_all_wares 40118 40118 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 76 5 0 0
v_cancel_client_order 3444 3444 0 0.00 0 4120 9 0 0
v_cancel_customer_prepayment 1234 1234 0 0.00 0 5883 12 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 0 3069 6 0 0
v_cancel_payment_to_supplier 778 778 0 0.00 0 267 3 0 0
v_cancel_supplier_invoice 830 814 16 1.92 0 3633 31 0 16
v_cancel_supplier_order 826 826 0 0.00 0 312 3 0 0
v_cancel_write_off 1952 1952 0 0.00 0 3653 13 0 0
v_max_id_clo_ord 5316 5316 0 0.00 0 1640 2 0 0
v_max_id_clo_res 972 972 0 0.00 0 2401 17 0 0
v_max_id_ord_sup 5452 5452 0 0.00 0 6590 5 0 0
v_max_non_paid_invoice 1572 1572 0 0.00 0 131 2 0 0
v_max_non_paid_realizn 3497 3497 0 0.00 0 2293 4 0 0
v_min_id_clo_ord 5316 5316 0 0.00 0 4432 8 0 0
v_min_id_clo_res 972 972 0 0.00 0 7424 20 0 0
v_min_id_ord_sup 5452 5452 0 0.00 0 3796 6 0 0
v_min_non_paid_invoice 5679 5679 0 0.00 0 9727 20 0 0
v_min_non_paid_realizn 6780 6780 0 0.00 0 19862 25 0 0
v_our_firm 9972 9972 0 0.00 0 19 1 0 0
v_reserve_write_off 19424 19424 0 0.00 0 6778 8 0 0
x_lock_depdocs_on_canc_invoice 11717 11717 0 0.00 0 21867 114 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 11119 406 0 0
x_make_qstorno_1000_1200 5317 5317 0 0.00 1 33291 1287 0 0
x_make_qstorno_1200_2000 5471 5471 0 0.00 3 87105 3564 0 0
x_make_qstorno_2100_3300 440154 440154 0 0.00 0 26204 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-04-2022 22:39:22 to 15-04-2022 22:39: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
20:08:18 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:19 18872 8192000000 9916642304 10235367424 1001205232 10.890 100 81 161 0 1028900512 6831904 20863376
20:10:46 22469 8192000000 9963131312 10295377920 1052884688 11.388 100 81 162 0 1082722272 9441872 20395712
20:12:12 21999 8192000000 10008267616 10344288256 1089441920 11.737 100 95 190 0 1123972688 10711200 23819568
20:13:45 18942 8192000000 10038437648 10388709376 1126460448 12.088 100 78 156 0 1157081504 11770112 18850944
20:15:07 8488 8192000000 10089289568 10451025920 1172408752 12.519 100 82 164 1 1204405264 12904752 19091760
20:16:20 7424 8192000000 10124109952 10492719104 1215000160 12.915 100 79 156 1 1246031728 13417888 17613680
20:17:29 2923 8192000000 10171507456 10551451648 1257244720 13.305 100 76 152 1 1287098160 13016784 16836656
20:18:34 2615 8192000000 10202170560 10583244800 1286166272 13.569 100 84 167 0 1317970032 13214672 18589088
20:19:39 37543 8192000000 10216568544 10607497216 1304770912 13.739 100 75 148 1 1331846288 9815088 17260288
20:21:17 8143 8192000000 10204691920 10617032704 1310889552 13.794 100 30 60 1 1324259200 5744416 7625232
20:22:26 6338 8192000000 10205945120 10621997056 1320228752 13.879 100 17 34 1 1330416064 6571920 3615392
20:23:38 45292 8192000000 10237317392 10640113664 1323335936 13.907 100 74 152 3 1346813456 5026896 18450624
20:25:23 5098 8192000000 10217413696 10636353536 1325150256 13.923 100 21 43 2 1336025456 5698464 5176736
20:26:31 9490 8192000000 10216782640 10641924096 1329596304 13.964 100 21 42 1 1340324304 6160688 4567312
20:27:43 53533 8192000000 10209599232 10632597504 1325174336 13.924 100 32 64 2 1338772144 5730960 7866848
20:29:39 5494 8192000000 10145030592 10538577920 1234385424 13.095 98 29 58 0 1246930752 6019344 6525984
20:30:45 5739 8192000000 10131278928 10529869824 1229064400 13.045 99 28 56 1 1241389440 6297344 6027696
20:31:56 14184 8192000000 10141231888 10543144960 1241546016 13.160 99 22 44 0 1252064384 5404464 5113904
20:33:11 29686 8192000000 10146758832 10550808576 1241658816 13.162 98 18 36 1 1250923424 5410592 3854016
20:34:41 4975 8192000000 10166011296 10567536640 1267298048 13.397 100 35 70 1 1282171344 6483984 8389312
20:35:46 5585 8192000000 10171974640 10581360640 1282890688 13.539 100 21 42 1 1293153632 4829072 5433872
20:36:52 14628 8192000000 10184427296 10600304640 1294697216 13.647 100 8 16 1 1301610816 5102800 1810800
20:38:07 50728 8192000000 10218416288 10627395584 1313547568 13.818 100 53 103 2 1332327456 5914352 12865536
20:40:02 7535 8192000000 10251669536 10664370176 1341232592 14.069 100 54 107 1 1365776336 12374000 12169744
20:41:13 5679 8192000000 10267163712 10680414208 1357857760 14.218 100 54 107 3 1382586704 12471232 12257712
20:42:25 32677 8192000000 10286476816 10705022976 1374453824 14.367 100 50 100 1 1406456192 20300304 11702064
20:44:02 4975 8192000000 10306038304 10722033664 1389216160 14.499 100 53 106 3 1417177648 15825712 12135776
20:45:11 26114 8192000000 10306504704 10733248512 1385420448 14.465 99 53 104 1 1419345344 21583632 12341264
20:46:49 9079 8192000000 10326896672 10753413120 1404442960 14.635 100 50 99 2 1438639040 22498160 11697920
20:48:08 8380 8192000000 10333487168 10755346432 1410470128 14.688 100 64 128 0 1450407408 25156672 14780608
20:49:20 5726 8192000000 10349828880 10773667840 1417609792 14.752 100 60 120 0 1462281536 29879184 14792560
20:50:40 2538 8192000000 10362221952 10771382272 1410139760 14.685 99 84 167 3 1462011520 31763152 20108608
20:51:47 10896 8192000000 10341698816 10761863168 1389936304 14.505 99 52 104 0 1440624128 37151232 13536592
20:53:02 6076 8192000000 10345376416 10760257536 1379336320 14.411 98 75 147 0 1439817552 42494304 17986928
20:54:18 2497 8192000000 10340270720 10752143360 1390044656 14.506 100 77 153 1 1450816432 40598432 20173344
20:55:25 19923 8192000000 10336459328 10763939840 1376468480 14.385 98 62 124 1 1431901648 40049088 15384080
20:56:49 17705 8192000000 10333422400 10766053376 1391934800 14.523 100 31 62 2 1440753552 40390800 8427952
20:58:25 7251 8192000000 10333633056 10752823296 1360083088 14.238 98 50 98 2 1417873280 44362016 13428176
20:59:37 13989 8192000000 10303550288 10727387136 1348757184 14.136 99 33 66 1 1402013968 43970704 9286080
21:00:54 11737 8192000000 10309184976 10733699072 1366219008 14.293 100 37 74 0 1415931728 40323616 9389104
21:02:15 15307 8192000000 10317377344 10746048512 1366161184 14.293 100 28 57 2 1416114192 41877968 8075040
21:03:33 19485 8192000000 10290826640 10723598336 1347168656 14.122 100 25 50 0 1398094512 43674944 7250912
21:05:20 41908 8192000000 10276191600 10719657984 1337083168 14.031 99 22 44 0 1377931312 34122608 6725536
21:07:04 44800 8192000000 10278449344 10706243584 1356238496 14.204 100 23 46 3 1382233216 20295776 5698944
21:08:52 86112 8192000000 10278822672 10717188096 1358324160 14.222 100 22 44 1 1374156640 10859712 4972768
21:11:36 66656 8192000000 10276343264 10723422208 1363022336 14.264 99 18 36 0 1378530576 11399216 4109024
21:13:43 73919 8192000000 10285431936 10730532864 1370667728 14.333 99 21 42 0 1388101440 12119536 5314176
21:16:05 73762 8192000000 10280048640 10737053696 1346406512 14.115 97 11 22 0 1363788016 14947648 2433856
21:18:24 72233 8192000000 10292003952 10745020416 1387462048 14.483 100 21 42 1 1408184848 15587056 5135744
21:20:58 89656 8192000000 10289306160 10753777664 1378935824 14.407 99 17 35 0 1401861296 19428288 3497184
21:23:33 20951 8192000000 10319007200 10762694656 1402420064 14.617 100 62 126 1 1433575088 14339216 16815808
21:25:15 28258 8192000000 10329262400 10774077440 1406145056 14.650 100 39 78 0 1434278288 19088224 9045008
21:26:56 65732 8192000000 10312886688 10774315008 1382240272 14.437 98 13 26 0 1406802976 21238032 3324672
21:29:04 121175 8192000000 10296191776 10764611584 1403645744 14.627 100 20 40 1 1419746992 10524560 5576688
21:32:08 38733 8192000000 10301493216 10760667136 1406270096 14.651 100 9 18 1 1418080864 9087712 2723056
21:34:00 62130 8192000000 10312202224 10768834560 1395184128 14.552 99 22 43 1 1415813840 15529472 5100240
21:36:20 107569 8192000000 10304815712 10780360704 1368687104 14.315 97 11 22 0 1389419312 18274304 2457904
21:39:07 43396 8192000000 10308385168 10773917696 1410402352 14.688 100 15 30 0 1422639344 8947024 3289968
21:40:54 47096 8192000000 10302986912 10762948608 1384314992 14.455 99 44 88 0 1408725904 14520864 9890048
21:42:51 89733 8192000000 10297840320 10760646656 1377217984 14.392 99 7 14 0 1392690128 13928112 1544032
21:45:21 37260 8192000000 10315616160 10767962112 1401587408 14.609 100 17 34 0 1422712656 17041504 4083744
21:47:33 115741 8192000000 10282181200 10735382528 1299668144 13.692 94 23 46 0 1319868832 15002464 5198224
21:50:28 38969 8192000000 10299167696 10743349248 1381454512 14.430 100 33 66 0 1402370672 13448432 7467728
21:52:10 59140 8192000000 10271265872 10721271808 1338396112 14.043 97 6 12 1 1357289552 17202160 1691280
21:54:32 76275 8192000000 10223732304 10675769344 1282230512 13.533 96 9 18 0 1295326912 10660224 2436176
21:56:49 24328 8192000000 10240842624 10688483328 1332616768 13.991 99 25 50 1 1344291488 5593760 6080960
21:58:25 56584 8192000000 10233589888 10686480384 1305882480 13.749 97 15 30 1 1320700224 11207984 3609760
22:00:23 45830 8192000000 10229384816 10684055552 1328063552 13.950 99 9 18 0 1339662784 9264880 2334352
22:02:15 68087 8192000000 10246260576 10694905856 1330125728 13.968 98 21 42 1 1346239152 11581488 4531936
22:04:33 54789 8192000000 10258982352 10699812864 1325878144 13.930 98 24 48 2 1340972256 9389216 5704896
22:06:30 129499 8192000000 10264626912 10714099712 1355618384 14.198 100 15 30 0 1374809168 15690208 3500576
22:09:40 56847 8192000000 10300261872 10743664640 1385408704 14.465 100 39 78 1 1404986256 11445856 8131696
22:11:37 139335 8192000000 10306117648 10756993024 1361818960 14.254 97 21 41 2 1384647024 18446768 4381296
22:14:57 97133 8192000000 10286390832 10745880576 1344647696 14.099 97 18 37 0 1366631968 17980816 4003456
22:17:34 129921 8192000000 10304998368 10752143360 1385006336 14.461 99 33 63 1 1401978496 8700976 8271184
22:20:46 59310 8192000000 10301934432 10764595200 1401285280 14.606 100 13 26 0 1413603616 8872704 3445632
22:22:47 94971 8192000000 10278327824 10740535296 1332972256 13.994 95 10 20 0 1345147232 9639504 2535472
22:25:29 73895 8192000000 10292408480 10741866496 1361804224 14.254 97 36 72 3 1386066096 15380912 8880960
22:28:02 44489 8192000000 10284736528 10729189376 1352925200 14.174 98 23 47 0 1375596560 16916448 5754912
22:29:50 76815 8192000000 10267715504 10727067648 1332517680 13.990 97 10 20 0 1345061344 10473056 2070608
22:32:07 44801 8192000000 10287564688 10738278400 1372802912 14.352 99 29 58 0 1391756368 12167664 6785792
22:33:54 75078 8192000000 10271555456 10722643968 1325120912 13.923 96 13 26 0 1343380416 15243440 3016064
22:36:13 27016 8192000000 10283954944 10723389440 1361435328 14.250 99 30 60 1 1374999296 7175152 6388816
22:37:46 19173 8192000000 10282459632 10723319808 1338870416 14.047 98 29 58 0 1357573312 12426352 6276544

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-04-2022 22:39:28 to 15-04-2022 22:39:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 129 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 15-04-2022 22:39:44 to 15-04-2022 22:39:45.

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          231553
MON$OLDEST_ACTIVE               235163
MON$OLDEST_SNAPSHOT             235163
MON$NEXT_TRANSACTION            235163
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                       2008384
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.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:476: 
:477: mscs-test-161.budzdorov.ru    Fri Apr 15 22:38:17 2022
:478:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:479: 
:480: 
:481: mscs-test-161.budzdorov.ru    Fri Apr 15 22:38:19 2022
:482:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:483: 
:484: 
:485: mscs-test-161.budzdorov.ru    Fri Apr 15 22:38:20 2022
:486:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:487: 
--- end of diff output ---

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

15.04.22 22:39:46. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-04-15 22:39:49 No severe PSQL-related problems occured
15.04.22 22:39:50. End of report.