Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.10.2022 22: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 15592188 kB
MemAvailable 30477984 kB
Buffers 0 kB
Cached 16115232 kB
SwapCached 2056 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.11
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-10-18 22:38:31
DTS_END                         2022-10-18 22:38:31.8080
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/49956
TRN_ID                          241861
ATT_ID                          662
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: 8903.45 23449 1068414
customer order: creation 171.38 399 20566
customer order: refuse 14.83 341 1780
order to supplier: creation 45.57 1294 5468
order to supplier: removal 3.47 105688 416
invoice (draft): creation 45.23 3604 5428
invoice (draft): removal 50.53 885 6064
invoice accept: apply 40.23 17725 4828
invoice accept: cancel 45.72 735 5486
customer reserve: creation 3724.99 174 446999
customer reserve: removal 4475.58 63 537070
realization accept: apply 84.16 182 10099
realization accept: cancel 72.29 113 8675
payment from customer: creation 59.66 116 7159
payment from customer: removal 5.52 88 662
payment to supplier: creation 48.29 142 5795
payment to supplier: removal 3.68 101 441
service: total inventory turnovers 4.83 1034 579
service: total monetary turnovers 6.68 37 802
service: aggregate perf. data 0.78 23562 93
service: refresh index statistics 0.03 312691 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-10-2022 22:39:45 to 18-10-2022 22:39:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6272
TEST_TIME 2 4463
TEST_TIME 3 4651
TEST_TIME 4 5152
TEST_TIME 5 5248
TEST_TIME 6 5434
TEST_TIME 7 5506
TEST_TIME 8 5393
TEST_TIME 9 5777
TEST_TIME 10 6324
TEST_TIME 11 6730
TEST_TIME 12 6942
TEST_TIME 13 6701
TEST_TIME 14 6837
TEST_TIME 15 7251
TEST_TIME 16 7359
TEST_TIME 17 7290
TEST_TIME 18 7341
TEST_TIME 19 7625
TEST_TIME 20 7726
TEST_TIME 21 7843
TEST_TIME 22 8321
TEST_TIME 23 8932
TEST_TIME 24 9173
TEST_TIME 25 9423
TEST_TIME 26 10089
TEST_TIME 27 10864
TEST_TIME 28 10783
TEST_TIME 29 10522
TEST_TIME 30 10484
TEST_TIME 31 10292
TEST_TIME 32 10233
TEST_TIME 33 10140
TEST_TIME 34 10005
TEST_TIME 35 9841
TEST_TIME 36 9882
TEST_TIME 37 9909
TEST_TIME 38 9789
TEST_TIME 39 9655
TEST_TIME 40 9684
TEST_TIME 41 9618
TEST_TIME 42 9559
TEST_TIME 43 9532
TEST_TIME 44 9462
TEST_TIME 45 9365
TEST_TIME 46 9267
TEST_TIME 47 9161
TEST_TIME 48 9103
TEST_TIME 49 9092
TEST_TIME 50 9031
TEST_TIME 51 9004
TEST_TIME 52 8872
TEST_TIME 53 9025
TEST_TIME 54 8963
TEST_TIME 55 8962
TEST_TIME 56 9049
TEST_TIME 57 9067
TEST_TIME 58 9112
TEST_TIME 59 9139
TEST_TIME 60 9264
TEST_TIME 61 9201
TEST_TIME 62 9229
TEST_TIME 63 9318
TEST_TIME 64 9364
TEST_TIME 65 9323
TEST_TIME 66 9331
TEST_TIME 67 9265
TEST_TIME 68 9237
TEST_TIME 69 9190
TEST_TIME 70 9199
TEST_TIME 71 9105
TEST_TIME 72 9052
TEST_TIME 73 9057
TEST_TIME 74 9001
TEST_TIME 75 8973
TEST_TIME 76 8898
TEST_TIME 77 8957
TEST_TIME 78 8908
TEST_TIME 79 8909
TEST_TIME 80 8907
TEST_TIME 81 8932
TEST_TIME 82 8950
TEST_TIME 83 9022
TEST_TIME 84 9021
TEST_TIME 85 9005
TEST_TIME 86 9061
TEST_TIME 87 9058
TEST_TIME 88 9032
TEST_TIME 89 9071
TEST_TIME 90 9136
TEST_TIME 91 9121
TEST_TIME 92 9035
TEST_TIME 93 9018
TEST_TIME 94 9013
TEST_TIME 95 8978
TEST_TIME 96 8959
TEST_TIME 97 8926
TEST_TIME 98 8948
TEST_TIME 99 8962
TEST_TIME 100 8954
TEST_TIME 101 8963
TEST_TIME 102 8987
TEST_TIME 103 9010
TEST_TIME 104 9050
TEST_TIME 105 9037
TEST_TIME 106 9101
TEST_TIME 107 9145
TEST_TIME 108 9108
TEST_TIME 109 9109
TEST_TIME 110 9051
TEST_TIME 111 9048
TEST_TIME 112 8999
TEST_TIME 113 8997
TEST_TIME 114 8950
TEST_TIME 115 8927
TEST_TIME 116 8906
TEST_TIME 117 8952
TEST_TIME 118 8954
TEST_TIME 119 8949
TEST_TIME 120 8906

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-10-2022 22:39:49 to 18-10-2022 22:39: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 1481963 1481963 0 0.00 0 20577 6 0 0
doc_list_biud 1481963 1481963 0 0.00 0 81537 29 0 0
sp_add_invoice_to_stock 4948 4828 120 2.42 22 170683 17726 0 120
sp_cancel_adding_invoice 5486 5486 0 0.00 4 56815 736 0 0
sp_cancel_client_order 1780 1780 0 0.00 4 8171 342 0 0
sp_cancel_customer_reserve 537070 537070 0 0.00 0 37730 64 0 0
sp_cancel_pay_from_customer 662 662 0 0.00 2 6591 89 0 0
sp_cancel_pay_to_supplier 441 441 0 0.00 3 8409 101 0 0
sp_cancel_supplier_invoice 6075 6064 11 0.18 2 81543 886 0 11
sp_cancel_supplier_order 416 416 0 0.00 9 1056358 105688 0 0
sp_cancel_write_off 8675 8675 0 0.00 1 7753 113 0 0
sp_client_order 20566 20566 0 0.00 3 25943 400 0 0
sp_customer_reserve 446999 446999 0 0.00 0 102501 174 0 0
sp_fill_shopping_cart 31462 31462 0 0.00 1 15572 40 0 0
sp_fill_shopping_cart_clo_res 446995 446995 0 0.00 0 12951 16 0 0
sp_get_clo_for_invoice 4819 4819 0 0.00 0 20134 357 0 0
sp_kill_cost_storno 15264 15264 0 0.00 0 6585 55 0 0
sp_kill_qstorno_ret_qs2qd 1089295 1089295 0 0.00 0 81531 35 0 0
sp_kill_qty_storno 574403 574403 0 0.00 0 81535 75 0 0
sp_lock_selected_doc 20687 20687 0 0.00 0 8403 5 0 0
sp_make_cost_storno 27866 27866 0 0.00 0 15994 57 0 0
sp_multiply_rows_for_pdistr 27866 27866 0 0.00 0 11108 26 0 0
sp_multiply_rows_for_qdistr 20566 20566 0 0.00 0 13602 166 0 0
sp_pay_from_customer 7159 7159 0 0.00 4 16844 117 0 0
sp_pay_to_supplier 5795 5795 0 0.00 4 26555 142 0 0
sp_payment_common 12954 12954 0 0.00 1 26509 29 0 0
sp_qd_handle_on_cancel_clo 1780 1780 0 0.00 0 8165 314 0 0
sp_qd_handle_on_invoice_upd_sts 10299 10299 0 0.00 0 21635 345 0 0
sp_qd_handle_on_reserve_upd_sts 10099 10099 0 0.00 0 10975 68 0 0
sp_reserve_write_off 10099 10099 0 0.00 6 14514 182 0 0
sp_supplier_invoice 5428 5428 0 0.00 8 84169 3605 0 0
sp_supplier_order 5468 5468 0 0.00 5 44559 1295 0 0
srv_aggregate_perf_data 93 93 0 0.00 2472 279133 23562 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1621 137086 43096 0 0
srv_find_qd_qs_mism 1021156 1021156 0 0.00 0 20577 5 0 0
srv_make_invnt_saldo 579 579 0 0.00 19 29502 1035 0 0
srv_make_money_saldo 802 802 0 0.00 1 9175 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 197556 358237 312691 0 0
t$perf-abend:sp_add_invoice_to_stock 120 120 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4828 4828 0 0.00 0 10741 174 0 0
t$perf-norm:sp_cancel_adding_invoice 358 358 0 0.00 1 3141 68 0 0
t$perf-norm:sp_cancel_client_order 1780 1780 0 0.00 0 1009 10 0 0
t$perf-norm:sp_cancel_customer_reserve 738 738 0 0.00 0 1040 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 662 662 0 0.00 0 1950 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 441 441 0 0.00 0 1046 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 382 382 0 0.00 0 1978 22 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 30377 1708 0 0
t$perf-norm:sp_cancel_write_off 1000 1000 0 0.00 0 2964 15 0 0
t$perf-norm:sp_client_order 20566 20566 0 0.00 0 7950 16 0 0
t$perf-norm:sp_customer_reserve 1063 1063 0 0.00 0 10871 19 0 0
t$perf-norm:sp_pay_from_customer 7159 7159 0 0.00 0 15603 15 0 0
t$perf-norm:sp_pay_to_supplier 5795 5795 0 0.00 0 15573 16 0 0
t$perf-norm:sp_reserve_write_off 10099 10099 0 0.00 0 9381 14 0 0
t$perf-norm:sp_supplier_invoice 5428 5428 0 0.00 0 5423 15 0 0
t$perf-norm:sp_supplier_order 5468 5468 0 0.00 0 6135 14 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 261 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 87 2 0 0
t$perf-norm:srv_make_invnt_saldo 579 579 0 0.00 0 892 23 0 0
t$perf-norm:srv_make_money_saldo 802 802 0 0.00 0 749 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 9862 9622 240 2.43 0 7173 24 0 240
v_all_customers 37360 37360 0 0.00 0 26 1 0 0
v_all_suppliers 29962 29962 0 0.00 0 64 1 0 0
v_all_wares 41132 41132 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 716 716 0 0.00 0 397 5 0 0
v_cancel_client_order 3560 3560 0 0.00 0 4294 6 0 0
v_cancel_customer_prepayment 1324 1324 0 0.00 0 2600 7 0 0
v_cancel_customer_reserve 1476 1476 0 0.00 0 5181 9 0 0
v_cancel_payment_to_supplier 882 882 0 0.00 0 1204 6 0 0
v_cancel_supplier_invoice 786 764 22 2.79 0 5008 19 0 22
v_cancel_supplier_order 832 832 0 0.00 0 4456 18 0 0
v_cancel_write_off 2000 2000 0 0.00 0 3385 16 0 0
v_max_id_clo_ord 5468 5468 0 0.00 0 313 1 0 0
v_max_id_clo_res 1063 1063 0 0.00 0 4171 13 0 0
v_max_id_ord_sup 5428 5428 0 0.00 0 5270 6 0 0
v_max_non_paid_invoice 1710 1710 0 0.00 0 132 2 0 0
v_max_non_paid_realizn 3809 3809 0 0.00 0 6867 5 0 0
v_min_id_clo_ord 5468 5468 0 0.00 0 2764 6 0 0
v_min_id_clo_res 1063 1063 0 0.00 0 3407 12 0 0
v_min_id_ord_sup 5428 5428 0 0.00 0 4973 8 0 0
v_min_non_paid_invoice 5795 5795 0 0.00 0 26504 20 0 0
v_min_non_paid_realizn 7159 7159 0 0.00 0 7663 20 0 0
v_our_firm 10472 10472 0 0.00 0 21 1 0 0
v_reserve_write_off 20198 20198 0 0.00 0 5871 7 0 0
x_lock_depdocs_on_canc_invoice 11168 11168 0 0.00 0 20139 125 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 14214 385 0 0
x_make_qstorno_1000_1200 5468 5468 0 0.00 1 44551 1226 0 0
x_make_qstorno_1200_2000 5428 5428 0 0.00 4 83804 3449 0 0
x_make_qstorno_2100_3300 446999 446999 0 0.00 0 102499 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-10-2022 22:39:53 to 18-10-2022 22:39:57.

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:35 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:36 38976 8192000000 9929232928 10245738496 1011782160 10.993 100 91 182 0 1042836768 6920656 24133952
20:11:24 24985 8192000000 9955807248 10281091072 1040567552 11.270 100 87 174 1 1071663008 9380624 21714832
20:12:54 9639 8192000000 9991839024 10327560192 1078014096 11.629 100 81 163 1 1107298288 9710928 19573264
20:14:10 12408 8192000000 10044603136 10396049408 1129439776 12.116 100 75 150 0 1157057984 10312800 17305408
20:15:25 5795 8192000000 10101763664 10461913088 1183562880 12.623 100 81 162 0 1214388512 12679408 18146224
20:16:34 3017 8192000000 10133311040 10504863744 1228134272 13.037 100 84 167 0 1260489904 14098912 18256720
20:17:42 6772 8192000000 10177753424 10556792832 1266355792 13.388 100 79 153 0 1296464192 13344400 16764000
20:18:50 1393 8192000000 10214127392 10595995648 1297957056 13.677 100 83 167 1 1329105696 13002144 18146496
20:19:53 1089 8192000000 10218719152 10611609600 1313307600 13.816 100 76 153 1 1342528144 12348384 16872160
20:20:55 1417 8192000000 10212530640 10616041472 1312667296 13.810 100 48 94 1 1331243968 8206976 10369696
20:21:57 2450 8192000000 10220226672 10627047424 1319513872 13.872 100 48 95 0 1342425904 12338048 10573984
20:23:02 9731 8192000000 10208268672 10626072576 1326190336 13.933 100 17 34 0 1335360960 5596336 3574288
20:24:15 47607 8192000000 10234775824 10646450176 1334919104 14.012 100 54 106 0 1353378528 5483056 12976368
20:26:04 5706 8192000000 10231601664 10646810624 1334972432 14.012 100 32 63 1 1347974752 5732240 7270080
20:27:12 13795 8192000000 10213587744 10639364096 1322941008 13.903 99 16 33 2 1331870448 5283776 3645664
20:28:26 48290 8192000000 10208266944 10625961984 1320563696 13.882 100 41 77 2 1335955680 5056064 10335920
20:30:18 4930 8192000000 10145842832 10538029056 1214204560 12.908 95 29 54 1 1225001344 5246144 5550640
20:31:23 4065 8192000000 10131657936 10526482432 1227006592 13.026 99 24 48 0 1242426560 10275344 5144624
20:32:28 12061 8192000000 10146370272 10548781056 1237771168 13.126 98 12 24 0 1245795008 5244928 2778912
20:33:40 30320 8192000000 10163322672 10573082624 1276029584 13.477 100 16 32 0 1284846720 5177456 3639680
20:35:12 7353 8192000000 10186562960 10600275968 1295095552 13.651 100 14 28 0 1308171104 9727568 3347984
20:36:20 6232 8192000000 10193654864 10614128640 1306131520 13.751 100 32 64 0 1323545136 9493616 7920000
20:37:30 71424 8192000000 10229959984 10643558400 1325406256 13.926 100 36 73 1 1338472480 4878560 8187664
20:39:48 6876 8192000000 10249902560 10665889792 1351619536 14.162 100 45 88 1 1370553088 8838160 10095392
20:41:00 17881 8192000000 10270931168 10691444736 1371365680 14.339 100 42 82 1 1392106288 11383728 9356880
20:42:21 16737 8192000000 10285341712 10708951040 1378950144 14.407 100 44 85 1 1400613680 11330960 10332576
20:43:41 7797 8192000000 10305157888 10723299328 1388122416 14.489 100 59 116 1 1416072256 14716624 13233216
20:44:54 5415 8192000000 10315046656 10739548160 1399666080 14.592 100 46 92 0 1427043696 16504688 10872928
20:46:11 5233 8192000000 10340299888 10763042816 1413090224 14.711 100 52 101 1 1448372208 23512944 11769040
20:47:20 5564 8192000000 10343519728 10765713408 1402965168 14.621 99 60 119 0 1443513872 26642352 13906352
20:48:29 9490 8192000000 10341210384 10766327808 1408418816 14.670 100 60 120 0 1455503232 32030416 15054000
20:49:42 9966 8192000000 10348526432 10775564288 1407695856 14.663 100 59 118 0 1455182704 33005680 14481168
20:51:05 13454 8192000000 10330399872 10746540032 1369415296 14.322 98 55 110 0 1419786976 36537104 13834576
20:52:27 5063 8192000000 10346655936 10765709312 1385906512 14.469 99 62 122 1 1442788304 41266912 15614880
20:53:42 4638 8192000000 10332344000 10741657600 1380222704 14.419 99 69 138 1 1433968272 36364528 17381040
20:54:50 4961 8192000000 10341013264 10760450048 1373481008 14.358 98 56 112 0 1434962896 46819200 14662688
20:55:59 1621 8192000000 10343698592 10749067264 1376249824 14.383 99 76 149 0 1439227440 44345248 18632368
20:57:08 2771 8192000000 10350740000 10754678784 1373190288 14.356 99 74 148 0 1443609744 51537136 18882320
20:58:15 9499 8192000000 10373812464 10792337408 1409615328 14.681 100 55 110 1 1483285600 59042208 14628064
20:59:32 7817 8192000000 10370909904 10787995648 1397048576 14.569 100 57 114 0 1478189312 65483696 15657040
21:01:00 5451 8192000000 10371544496 10796097536 1398205072 14.579 100 52 105 0 1475103168 62922416 13975680
21:02:07 11316 8192000000 10347300784 10794311680 1398619008 14.583 100 27 54 0 1463417488 56916752 7881728
21:03:19 25215 8192000000 10339131696 10785710080 1387658624 14.485 100 26 52 0 1452489744 57152144 7678976
21:04:48 34171 8192000000 10315138960 10759794688 1344110176 14.094 98 23 47 0 1398810144 48360544 6339424
21:06:49 42856 8192000000 10295555808 10737852416 1376052192 14.381 100 41 84 0 1397545472 11573328 9919952
21:08:47 21617 8192000000 10286102272 10736062464 1372045872 14.345 100 15 29 1 1391749200 16075392 3627936
21:10:10 80518 8192000000 10277615072 10708172800 1316325072 13.843 96 20 40 0 1333462240 12055680 5081488
21:12:34 89871 8192000000 10295543008 10738016256 1367218720 14.302 99 30 59 0 1387249216 13879696 6150800
21:15:10 31537 8192000000 10276854496 10726825984 1362516576 14.260 99 15 30 0 1374071904 8744400 2810928
21:16:47 36773 8192000000 10306624752 10747621376 1387612960 14.485 100 27 54 0 1401086800 7092032 6381808
21:18:41 45491 8192000000 10295153728 10744156160 1376205792 14.383 99 24 50 0 1393581328 11621152 5754384
21:20:28 55942 8192000000 10292263920 10758221824 1398086992 14.578 100 15 30 0 1416291152 14684160 3520000
21:22:24 77309 8192000000 10296225984 10760646656 1390976000 14.515 99 12 24 1 1405741760 11916928 2848832
21:24:42 74671 8192000000 10304083184 10754818048 1377446304 14.394 98 19 38 1 1387715632 6031280 4238048
21:27:00 59496 8192000000 10290102016 10760130560 1379354784 14.411 98 12 25 0 1393482432 10961632 3166016
21:29:01 46541 8192000000 10300358144 10760556544 1385929584 14.470 98 7 14 0 1397757808 10290528 1537696
21:31:15 72998 8192000000 10302216608 10772017152 1393007600 14.533 99 11 22 0 1410030304 14401888 2620816
21:33:29 62903 8192000000 10296807200 10760417280 1352024800 14.166 96 15 30 0 1365165376 9298976 3841600
21:35:35 31893 8192000000 10330695472 10777464832 1414181888 14.721 100 44 86 1 1435355168 10702144 10471136
21:37:09 79690 8192000000 10295618224 10760855552 1361226784 14.248 97 17 34 0 1378875952 13652320 3996848
21:39:35 36052 8192000000 10295193200 10738536448 1372652592 14.351 99 23 46 0 1389547936 11823664 5071680
21:41:11 111300 8192000000 10254451984 10707091456 1246579840 13.207 90 16 33 0 1265793792 15854464 3359488
21:44:02 72838 8192000000 10272730448 10727067648 1355420960 14.196 99 33 66 1 1377496480 14968944 7106576
21:46:44 54839 8192000000 10272875184 10726772736 1349692288 14.145 98 27 54 1 1368914768 13318672 5903808
21:48:40 47700 8192000000 10261483024 10724003840 1327079312 13.941 96 7 12 0 1340173504 11383152 1711040
21:51:20 19818 8192000000 10251893680 10716205056 1339688608 14.055 98 19 39 0 1359617264 15382176 4546480
21:52:40 86238 8192000000 10267255344 10730217472 1364063200 14.274 99 15 30 0 1376210096 8326368 3820528
21:55:06 54858 8192000000 10260693792 10724847616 1345174272 14.104 98 11 20 0 1358377648 11113216 2090160
21:57:06 82116 8192000000 10254897616 10713989120 1307194800 13.761 95 13 27 0 1318853168 8924640 2733728
21:59:35 41814 8192000000 10264021920 10709995520 1346828784 14.119 99 24 48 0 1361149296 9223376 5097136
22:01:37 40436 8192000000 10271436096 10734231552 1367986080 14.309 100 13 26 0 1386731824 15777568 2968176
22:03:17 80067 8192000000 10268200000 10732269568 1344679504 14.100 98 19 38 0 1361795264 12849648 4266112
22:05:38 41081 8192000000 10272670816 10711216128 1322009616 13.895 96 37 74 0 1337986240 8168176 7808448
22:07:22 47302 8192000000 10268264736 10714124288 1349934368 14.147 98 27 54 0 1370104752 14149328 6021056
22:09:11 65904 8192000000 10294696640 10747154432 1386614288 14.476 100 16 30 3 1405817472 15769120 3434064
22:11:26 40691 8192000000 10290870144 10756067328 1394910096 14.550 100 31 61 0 1413822144 11673056 7238992
22:13:11 28678 8192000000 10292264992 10765406208 1397684064 14.574 100 3 6 0 1408408752 10592896 131792
22:14:40 69120 8192000000 10290087728 10749190144 1341428688 14.070 96 9 18 0 1349779136 6548912 1801536
22:16:51 60981 8192000000 10292319264 10753691648 1376498368 14.385 99 17 32 0 1394464400 13715408 4250624
22:18:52 63588 8192000000 10303851952 10762588160 1371204848 14.338 98 18 36 0 1390798976 15483392 4110736
22:20:56 60867 8192000000 10289599536 10752077824 1336308208 14.024 96 10 20 0 1352894240 14412992 2173040
22:23:58 70151 8192000000 10299751136 10766921728 1366764128 14.298 98 22 44 0 1387390288 15339824 5286336
22:26:12 49151 8192000000 10300433312 10730549248 1334573904 14.008 97 35 67 0 1357969680 15691360 7704416
22:28:01 137086 8192000000 10253108560 10712018944 1321440800 13.890 97 33 66 0 1337318752 8716688 7161264
22:31:22 40380 8192000000 10266862528 10729689088 1360615152 14.243 99 26 49 0 1377551440 10995840 5940448
22:33:08 57574 8192000000 10268795440 10731089920 1350702464 14.154 98 11 22 0 1368641440 15210272 2728704
22:35:06 60417 8192000000 10252407024 10706608128 1324651088 13.919 97 10 20 0 1343934720 16816624 2467008
22:37:48 30760 8192000000 10278875360 10722824192 1356644928 14.207 99 27 56 0 1370434912 7567840 6222144

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 18-10-2022 22:39:59 to 18-10-2022 22:40:15.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-10-2022 22:40:16 to 18-10-2022 22:40:18.

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          240292
MON$OLDEST_ACTIVE               242047
MON$OLDEST_SNAPSHOT             242047
MON$NEXT_TRANSACTION            242047
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                       2015488
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33635 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33635 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33635 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33635 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 ---
:112: 
:113: mscs-test-161.budzdorov.ru    Tue Oct 18 22:38:39 2022
:114:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:115: 
:116: 
:117: mscs-test-161.budzdorov.ru    Tue Oct 18 22:38:40 2022
:118:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:119: 
:120: 
:121: mscs-test-161.budzdorov.ru    Tue Oct 18 22:38:40 2022
:122:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:123: 
--- end of diff output ---

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

18.10.22 22:40:19. 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-10-18 22:40:21 No severe PSQL-related problems occured
18.10.22 22:40:23. End of report.