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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15044240 kB
MemAvailable 30069960 kB
Buffers 0 kB
Cached 16644780 kB
SwapCached 3192 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-12-23 16:38:34
DTS_END                         2022-12-23 16:38:34.9630
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/45560
TRN_ID                          231611
ATT_ID                          685
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: 9154.53 23581 1098544
customer order: creation 162.31 374 19477
customer order: refuse 13.36 398 1603
order to supplier: creation 43.12 1341 5174
order to supplier: removal 3.53 108617 423
invoice (draft): creation 42.84 3394 5141
invoice (draft): removal 54.34 810 6521
invoice accept: apply 38.71 18504 4645
invoice accept: cancel 49.14 803 5897
customer reserve: creation 3617.89 181 434147
customer reserve: removal 4847.64 62 581717
realization accept: apply 79.51 199 9541
realization accept: cancel 78.65 127 9438
payment from customer: creation 56.48 134 6777
payment from customer: removal 5.65 92 678
payment to supplier: creation 46.15 136 5538
payment to supplier: removal 3.29 87 395
service: total inventory turnovers 4.63 1584 556
service: total monetary turnovers 6.43 62 772
service: aggregate perf. data 0.83 20017 100
service: refresh index statistics 0.03 314692 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-12-2022 16:39:48 to 23-12-2022 16:39:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4636
TEST_TIME 2 5478
TEST_TIME 3 4683
TEST_TIME 4 4407
TEST_TIME 5 4757
TEST_TIME 6 5569
TEST_TIME 7 5426
TEST_TIME 8 5599
TEST_TIME 9 5319
TEST_TIME 10 5339
TEST_TIME 11 5210
TEST_TIME 12 5308
TEST_TIME 13 5595
TEST_TIME 14 5901
TEST_TIME 15 6097
TEST_TIME 16 6741
TEST_TIME 17 7057
TEST_TIME 18 7067
TEST_TIME 19 7182
TEST_TIME 20 7542
TEST_TIME 21 7790
TEST_TIME 22 7914
TEST_TIME 23 8024
TEST_TIME 24 8347
TEST_TIME 25 8270
TEST_TIME 26 8765
TEST_TIME 27 8806
TEST_TIME 28 8757
TEST_TIME 29 9783
TEST_TIME 30 10684
TEST_TIME 31 10830
TEST_TIME 32 10750
TEST_TIME 33 10751
TEST_TIME 34 10518
TEST_TIME 35 10483
TEST_TIME 36 10554
TEST_TIME 37 10352
TEST_TIME 38 10269
TEST_TIME 39 10121
TEST_TIME 40 10034
TEST_TIME 41 9924
TEST_TIME 42 9904
TEST_TIME 43 9828
TEST_TIME 44 9807
TEST_TIME 45 9597
TEST_TIME 46 9485
TEST_TIME 47 9364
TEST_TIME 48 9660
TEST_TIME 49 9568
TEST_TIME 50 9456
TEST_TIME 51 9376
TEST_TIME 52 9311
TEST_TIME 53 9312
TEST_TIME 54 9391
TEST_TIME 55 9455
TEST_TIME 56 9397
TEST_TIME 57 9340
TEST_TIME 58 9219
TEST_TIME 59 9190
TEST_TIME 60 9204
TEST_TIME 61 9140
TEST_TIME 62 9090
TEST_TIME 63 9110
TEST_TIME 64 9070
TEST_TIME 65 9063
TEST_TIME 66 9239
TEST_TIME 67 9189
TEST_TIME 68 9277
TEST_TIME 69 9203
TEST_TIME 70 9228
TEST_TIME 71 9330
TEST_TIME 72 9347
TEST_TIME 73 9338
TEST_TIME 74 9359
TEST_TIME 75 9413
TEST_TIME 76 9420
TEST_TIME 77 9410
TEST_TIME 78 9333
TEST_TIME 79 9267
TEST_TIME 80 9285
TEST_TIME 81 9250
TEST_TIME 82 9249
TEST_TIME 83 9202
TEST_TIME 84 9158
TEST_TIME 85 9127
TEST_TIME 86 9127
TEST_TIME 87 9097
TEST_TIME 88 9138
TEST_TIME 89 9143
TEST_TIME 90 9143
TEST_TIME 91 9218
TEST_TIME 92 9191
TEST_TIME 93 9283
TEST_TIME 94 9256
TEST_TIME 95 9259
TEST_TIME 96 9328
TEST_TIME 97 9306
TEST_TIME 98 9346
TEST_TIME 99 9364
TEST_TIME 100 9348
TEST_TIME 101 9260
TEST_TIME 102 9301
TEST_TIME 103 9257
TEST_TIME 104 9260
TEST_TIME 105 9278
TEST_TIME 106 9258
TEST_TIME 107 9231
TEST_TIME 108 9206
TEST_TIME 109 9192
TEST_TIME 110 9162
TEST_TIME 111 9184
TEST_TIME 112 9137
TEST_TIME 113 9124
TEST_TIME 114 9152
TEST_TIME 115 9126
TEST_TIME 116 9177
TEST_TIME 117 9171
TEST_TIME 118 9161
TEST_TIME 119 9138
TEST_TIME 120 9156

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-12-2022 16:39:52 to 23-12-2022 16:39:55.

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 1500106 1500106 0 0.00 0 20064 6 0 0
doc_list_biud 1500102 1500102 0 0.00 0 49638 30 0 0
sp_add_invoice_to_stock 4781 4645 136 2.84 41 172783 18504 0 136
sp_cancel_adding_invoice 5897 5897 0 0.00 4 83100 804 0 0
sp_cancel_client_order 1603 1603 0 0.00 4 17785 399 0 0
sp_cancel_customer_reserve 581717 581717 0 0.00 0 35372 63 0 0
sp_cancel_pay_from_customer 678 678 0 0.00 2 6153 92 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 3 17762 87 0 0
sp_cancel_supplier_invoice 6528 6521 7 0.10 2 49647 810 0 7
sp_cancel_supplier_order 423 423 0 0.00 7 1018087 108618 0 0
sp_cancel_write_off 9438 9438 0 0.00 1 15667 128 0 0
sp_client_order 19477 19477 0 0.00 3 34988 374 0 0
sp_customer_reserve 434147 434147 0 0.00 0 45423 182 0 0
sp_fill_shopping_cart 29783 29783 0 0.00 1 16182 43 0 0
sp_fill_shopping_cart_clo_res 434139 434139 0 0.00 0 15394 16 0 0
sp_get_clo_for_invoice 4637 4637 0 0.00 0 34814 340 0 0
sp_kill_cost_storno 16408 16408 0 0.00 0 19275 60 0 0
sp_kill_qstorno_ret_qs2qd 1179816 1179816 0 0.00 0 49631 34 0 0
sp_kill_qty_storno 619777 619777 0 0.00 0 49635 73 0 0
sp_lock_selected_doc 19668 19668 0 0.00 0 7693 5 0 0
sp_make_cost_storno 26493 26493 0 0.00 0 19191 61 0 0
sp_multiply_rows_for_pdistr 26492 26492 0 0.00 0 11379 27 0 0
sp_multiply_rows_for_qdistr 19477 19477 0 0.00 0 34984 160 0 0
sp_pay_from_customer 6777 6777 0 0.00 4 16359 135 0 0
sp_pay_to_supplier 5538 5538 0 0.00 4 11390 137 0 0
sp_payment_common 12314 12314 0 0.00 1 15540 37 0 0
sp_qd_handle_on_cancel_clo 1603 1603 0 0.00 0 10889 356 0 0
sp_qd_handle_on_invoice_upd_sts 10534 10534 0 0.00 0 17403 322 0 0
sp_qd_handle_on_reserve_upd_sts 9541 9541 0 0.00 0 24566 72 0 0
sp_reserve_write_off 9541 9541 0 0.00 5 24732 199 0 0
sp_supplier_invoice 5141 5141 0 0.00 5 83656 3395 0 0
sp_supplier_order 5174 5174 0 0.00 5 49343 1341 0 0
srv_aggregate_perf_data 100 100 0 0.00 2288 254465 20018 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1353 136663 46797 0 0
srv_find_qd_qs_mism 1053255 1053255 0 0.00 0 20062 5 0 0
srv_make_invnt_saldo 556 556 0 0.00 26 137411 1584 0 0
srv_make_money_saldo 772 772 0 0.00 0 28854 63 0 0
srv_recalc_idx_stat 4 4 0 0.00 90559 453588 314692 0 0
t$perf-abend:sp_add_invoice_to_stock 136 136 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4645 4645 0 0.00 0 18597 189 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 0 3964 122 0 0
t$perf-norm:sp_cancel_client_order 1603 1603 0 0.00 0 4703 19 0 0
t$perf-norm:sp_cancel_customer_reserve 658 658 0 0.00 0 8470 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 678 678 0 0.00 0 3222 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 10843 29 0 0
t$perf-norm:sp_cancel_supplier_invoice 355 355 0 0.00 0 1677 13 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 17938 1599 0 0
t$perf-norm:sp_cancel_write_off 981 981 0 0.00 0 2529 14 0 0
t$perf-norm:sp_client_order 19477 19477 0 0.00 0 22123 18 0 0
t$perf-norm:sp_customer_reserve 932 932 0 0.00 0 1011 4 0 0
t$perf-norm:sp_pay_from_customer 6777 6777 0 0.00 0 17513 14 0 0
t$perf-norm:sp_pay_to_supplier 5538 5538 0 0.00 0 6091 13 0 0
t$perf-norm:sp_reserve_write_off 9541 9541 0 0.00 0 11049 12 0 0
t$perf-norm:sp_supplier_invoice 5141 5141 0 0.00 0 4996 17 0 0
t$perf-norm:sp_supplier_order 5174 5174 0 0.00 0 17431 18 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 167 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 63 3 0 0
t$perf-norm:srv_make_invnt_saldo 556 556 0 0.00 0 918 16 0 0
t$perf-norm:srv_make_money_saldo 772 772 0 0.00 0 1868 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 9542 9270 272 2.85 0 7630 23 0 272
v_all_customers 35746 35746 0 0.00 0 15 1 0 0
v_all_suppliers 28876 28876 0 0.00 0 33 1 0 0
v_all_wares 38946 38946 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 396 6 0 0
v_cancel_client_order 3206 3206 0 0.00 0 17501 12 0 0
v_cancel_customer_prepayment 1356 1356 0 0.00 0 4114 10 0 0
v_cancel_customer_reserve 1316 1316 0 0.00 0 3689 10 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 17645 26 0 0
v_cancel_supplier_invoice 724 710 14 1.93 0 7627 37 0 14
v_cancel_supplier_order 846 846 0 0.00 0 3381 11 0 0
v_cancel_write_off 1962 1962 0 0.00 0 5544 20 0 0
v_max_id_clo_ord 5168 5168 0 0.00 0 289 1 0 0
v_max_id_clo_res 932 932 0 0.00 0 4080 23 0 0
v_max_id_ord_sup 5138 5138 0 0.00 0 5596 4 0 0
v_max_non_paid_invoice 1406 1406 0 0.00 0 975 3 0 0
v_max_non_paid_realizn 3500 3500 0 0.00 0 4741 5 0 0
v_min_id_clo_ord 5168 5168 0 0.00 0 3119 7 0 0
v_min_id_clo_res 932 932 0 0.00 0 457 3 0 0
v_min_id_ord_sup 5138 5138 0 0.00 0 6749 9 0 0
v_min_non_paid_invoice 5538 5538 0 0.00 0 10383 29 0 0
v_min_non_paid_realizn 6776 6776 0 0.00 1 7505 27 0 0
v_our_firm 9752 9752 0 0.00 0 21 1 0 0
v_reserve_write_off 19082 19082 0 0.00 0 21927 9 0 0
x_lock_depdocs_on_canc_invoice 12063 12063 0 0.00 0 16090 120 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 15409 378 0 0
x_make_qstorno_1000_1200 5174 5174 0 0.00 2 49330 1265 0 0
x_make_qstorno_1200_2000 5141 5141 0 0.00 2 83648 3231 0 0
x_make_qstorno_2100_3300 434147 434147 0 0.00 0 37681 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-12-2022 16:39:56 to 23-12-2022 16:40:00.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:08:38 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:40 24685 8192000000 9911837520 10225434624 997337408 10.853 100 87 174 0 1025918736 6216480 22364848
14:11:08 11893 8192000000 9951862576 10275176448 1036758192 11.233 100 90 180 0 1068101072 8296928 23045952
14:12:25 14766 8192000000 9976469824 10307825664 1061827104 11.474 100 84 168 0 1092653728 9420176 21406448
14:13:47 6455 8192000000 10028224992 10375561216 1117950064 12.008 100 79 157 0 1147149504 10478800 18720640
14:14:59 5929 8192000000 10077123440 10435473408 1163884192 12.440 100 73 146 0 1193371488 12513104 16974192
14:16:07 4685 8192000000 10112247584 10479403008 1203187136 12.806 100 73 145 0 1231450400 12461680 15801584
14:17:14 2092 8192000000 10157285696 10529857536 1245501280 13.197 100 81 160 0 1276478752 13160800 17816672
14:18:18 1404 8192000000 10178079632 10561474560 1269218832 13.414 100 89 177 2 1302308832 13018656 20071344
14:19:21 796 8192000000 10199372912 10583437312 1287727616 13.584 100 92 184 0 1320743552 12837552 20178384
14:20:23 1638 8192000000 10227575936 10615652352 1309095072 13.778 100 86 170 1 1341113456 13018672 18999712
14:21:26 11667 8192000000 10216690704 10618159104 1312741888 13.811 100 37 74 0 1328705136 8087984 7875264
14:22:39 8635 8192000000 10213082320 10626973696 1317944032 13.858 100 18 35 1 1327716768 5907344 3865392
14:23:54 11830 8192000000 10216604160 10636607488 1325635568 13.928 100 20 39 0 1340391008 10875664 3879776
14:25:08 1504 8192000000 10232315984 10644041728 1335276064 14.015 100 57 110 3 1358390848 8608672 14506112
14:26:20 11206 8192000000 10222896480 10649980928 1337240672 14.033 100 16 32 1 1345867792 4923888 3703232
14:27:32 5612 8192000000 10223027712 10646888448 1334203056 14.005 100 31 62 0 1347236208 6175344 6857808
14:28:38 7305 8192000000 10210050704 10627919872 1308784352 13.775 99 23 44 1 1319590800 5509680 5296768
14:29:48 10797 8192000000 10160766320 10561642496 1237976448 13.128 95 21 42 1 1247996336 4732544 5287344
14:31:00 20193 8192000000 10156819360 10569252864 1203073216 12.805 93 17 32 0 1212211408 5391936 3746256
14:32:20 4514 8192000000 10140322064 10537771008 1199156560 12.768 95 43 87 0 1217277520 8410352 9710608
14:33:25 6583 8192000000 10133139920 10531586048 1229058080 13.045 98 25 48 1 1239882048 5254208 5569760
14:34:37 76624 8192000000 10173412976 10583859200 1269635184 13.418 99 35 70 1 1287472432 9574544 8262704
14:36:54 6039 8192000000 10199312480 10607792128 1299406544 13.690 100 37 74 1 1314397344 6668032 8322768
14:38:01 8346 8192000000 10203284800 10621280256 1316409728 13.844 100 25 50 0 1331682400 9850768 5421904
14:39:15 14468 8192000000 10249381360 10657218560 1340250704 14.060 100 52 101 0 1360190704 9027104 10912896
14:40:43 46948 8192000000 10256556608 10672832512 1353179408 14.176 100 43 84 0 1371564128 9419440 8965280
14:42:34 20196 8192000000 10274164272 10691190784 1367452576 14.304 100 50 100 1 1392257488 13729296 11075616
14:44:04 37602 8192000000 10288910784 10714763264 1365339616 14.285 99 33 66 0 1391017776 17815312 7862848
14:45:53 15824 8192000000 10315177792 10733916160 1390241584 14.508 100 40 79 0 1419065920 18985248 9839088
14:47:24 20803 8192000000 10319521968 10744864768 1401191296 14.606 100 50 98 1 1433192016 20537760 11462960
14:48:50 31758 8192000000 10324285296 10757214208 1406058256 14.649 100 41 81 0 1440565712 24234240 10273216
14:50:28 4951 8192000000 10344524720 10768027648 1412452832 14.706 100 55 102 1 1449714768 24472688 12789248
14:51:38 9894 8192000000 10350478144 10774720512 1400180080 14.597 99 63 123 1 1449535968 34126624 15229264
14:52:57 4473 8192000000 10323788032 10752061440 1376534672 14.386 98 46 91 1 1417171296 28700288 11936336
14:54:09 7609 8192000000 10335482480 10757169152 1393840928 14.540 100 58 114 0 1440658112 32341840 14475344
14:55:21 1619 8192000000 10355571984 10771349504 1399905376 14.594 99 77 153 1 1455127568 34810736 20411456
14:56:31 1416 8192000000 10362810032 10776010752 1408064880 14.667 100 83 163 3 1463504592 35798336 19641376
14:57:41 3287 8192000000 10372790544 10782199808 1409179184 14.677 100 85 169 3 1472326592 42622544 20524864
14:58:51 3353 8192000000 10381682560 10803552256 1423533504 14.804 100 52 104 1 1482984176 45442544 14008128
15:00:00 10812 8192000000 10367857264 10791063552 1409701440 14.681 100 60 119 0 1474056240 48504576 15850224
15:01:28 5481 8192000000 10371810160 10791636992 1404952608 14.639 99 54 109 2 1468466160 49092304 14421248
15:02:37 19632 8192000000 10353174704 10787545088 1381099744 14.426 98 38 76 0 1440737600 49104688 10533168
15:04:01 27126 8192000000 10335476800 10769690624 1359147648 14.230 98 33 66 0 1426095152 57301712 9645792
15:05:47 15462 8192000000 10321239264 10759032832 1368229504 14.311 100 25 50 0 1430478240 54165088 8083648
15:07:06 19210 8192000000 10294786704 10733678592 1320125840 13.878 99 21 42 0 1393470112 66514224 6830048
15:08:36 94580 8192000000 10293077296 10738454528 1363705600 14.271 99 16 30 0 1384794496 17767184 3321712
15:11:16 106173 8192000000 10269374144 10719768576 1356870352 14.209 100 20 40 0 1381481296 19000384 5610560
15:14:07 29963 8192000000 10274190672 10734059520 1360664000 14.243 99 7 14 0 1376389264 14135072 1590192
15:15:45 116441 8192000000 10287838624 10733314048 1353982224 14.183 98 23 45 0 1367161888 7569392 5610272
15:18:50 37060 8192000000 10297119264 10744467456 1391271552 14.517 100 30 61 1 1407458272 8607904 7578816
15:20:27 91458 8192000000 10295506784 10746826752 1377485456 14.394 99 12 21 2 1390711408 10410880 2815072
15:23:06 65301 8192000000 10306279408 10754547712 1391820544 14.522 100 43 81 0 1411577376 9653088 10103744
15:25:16 136662 8192000000 10278664256 10734997504 1337462336 14.035 96 19 38 0 1363656560 21152240 5041984
15:28:42 21025 8192000000 10299978160 10744729600 1387513248 14.484 100 28 56 0 1406826336 12668880 6644208
15:30:06 43423 8192000000 10311458320 10763313152 1388981232 14.497 99 24 48 0 1413313072 18552880 5778960
15:32:07 45708 8192000000 10303720240 10775793664 1394741232 14.548 100 12 24 0 1419592400 21645888 3205280
15:34:14 46203 8192000000 10283312624 10736070656 1375760768 14.379 99 15 31 0 1385735264 6921296 3053200
15:36:04 98671 8192000000 10291948928 10750156800 1372168336 14.346 98 20 40 0 1385676400 9411168 4096896
15:38:48 54623 8192000000 10294411824 10753318912 1388456016 14.492 99 13 26 0 1401089872 10200080 2433776
15:40:43 50319 8192000000 10300702112 10768367616 1405903200 14.648 100 17 32 0 1420973152 11497072 3572880
15:42:34 68418 8192000000 10301439696 10769190912 1371145408 14.337 97 16 32 0 1386879472 12363088 3370976
15:44:43 49477 8192000000 10305755904 10762383360 1377152720 14.391 98 14 29 0 1391971200 11943840 2874640
15:46:56 49162 8192000000 10301541856 10757984256 1386376880 14.474 99 22 44 0 1399031984 8292544 4362560
15:48:48 75535 8192000000 10287004464 10756038656 1382248000 14.437 99 14 28 1 1397610048 12272400 3089648
15:51:05 72083 8192000000 10290184416 10731577344 1325337072 13.925 95 23 43 1 1339999904 9842176 4820656
15:53:19 67167 8192000000 10271581440 10730090496 1314213696 13.824 96 14 28 0 1329618352 12410720 2993936
15:55:28 46135 8192000000 10259838208 10683084800 1315892112 13.839 96 46 93 1 1336985504 11166048 9927344
15:57:14 85165 8192000000 10243879136 10687422464 1300095744 13.696 96 39 78 0 1317890128 8753280 9041104
15:59:43 1353 8192000000 10257977472 10689744896 1338142784 14.041 100 54 108 1 1359715952 9598176 11974992
16:00:46 70368 8192000000 10227144528 10679119872 1303863840 13.730 97 15 30 0 1316465152 9611744 2989568
16:02:56 67252 8192000000 10253247728 10702716928 1330213344 13.969 98 10 19 0 1342669776 10348368 2108064
16:06:25 37424 8192000000 10260321888 10705334272 1327358384 13.943 98 17 34 1 1343231808 12241520 3631904
16:08:08 45590 8192000000 10251906800 10697654272 1317855520 13.857 97 12 24 0 1334010736 13342528 2812688
16:10:17 133796 8192000000 10280098928 10725281792 1364785712 14.280 99 11 22 0 1382228624 15163344 2279568
16:13:32 62903 8192000000 10310997808 10757971968 1380054704 14.417 99 23 46 0 1396936304 12260000 4621600
16:15:47 38155 8192000000 10319912336 10764087296 1401131808 14.605 100 31 62 1 1423057104 15294352 6630944
16:17:26 85501 8192000000 10292940976 10756657152 1346403728 14.115 96 12 24 1 1368540304 20055360 2081216
16:20:13 58327 8192000000 10283662288 10730401792 1342957312 14.084 97 18 36 0 1359507072 12373440 4176320
16:22:34 49343 8192000000 10285209008 10753798144 1390960992 14.514 100 16 34 0 1409769072 15113120 3694960
16:24:24 44149 8192000000 10286578768 10748006400 1389199904 14.499 100 13 26 0 1402818192 10495648 3122640
16:26:10 42117 8192000000 10294306096 10743513088 1368684640 14.315 98 28 56 0 1382909504 6711648 7513216
16:27:53 79174 8192000000 10288953824 10749177856 1364376400 14.277 98 12 24 0 1375387792 8256080 2755312
16:30:14 54795 8192000000 10289473680 10743144448 1371873200 14.344 99 16 32 0 1388930256 13353008 3704048
16:32:11 56378 8192000000 10268646416 10738782208 1362759456 14.262 99 8 16 0 1375185952 10573952 1852544
16:34:08 60703 8192000000 10290872544 10754162688 1359144336 14.230 98 8 16 0 1372322544 11516032 1662176
16:36:11 30878 8192000000 10282075136 10731208704 1342647504 14.081 97 39 74 2 1358150368 6906160 8596704
16:37:45 57270 8192000000 9555916544 9899626496 680210672 7.666 49 15 30 0 700108448 16668032 3229744

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 23-12-2022 16:40:02 to 23-12-2022 16:40:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 136 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-12-2022 16:40:19 to 23-12-2022 16:40:21.

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          232920
MON$OLDEST_ACTIVE               232921
MON$OLDEST_SNAPSHOT             232921
MON$NEXT_TRANSACTION            232921
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                       2028064
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.33648 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33648 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33648 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33648 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 ---
:1112: 
:1113: mscs-test-161.budzdorov.ru    Fri Dec 23 16:38:50 2022
:1114:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1115: 
:1116: 
:1117: mscs-test-161.budzdorov.ru    Fri Dec 23 16:38:59 2022
:1118:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1119: 
:1120: 
:1121: mscs-test-161.budzdorov.ru    Fri Dec 23 16:39:04 2022
:1122:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1123: 
--- end of diff output ---

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

23.12.22 16:40:22. 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-12-23 16:40:24 No severe PSQL-related problems occured
23.12.22 16:40:26. End of report.