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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15369076 kB
MemAvailable 30427152 kB
Buffers 0 kB
Cached 16327636 kB
SwapCached 3080 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 2023-02-28 19:38:42
DTS_END                         2023-02-28 19:38:42.2640
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/59570
TRN_ID                          253785
ATT_ID                          720
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: 9350.43 22565 1122052
customer order: creation 187.15 335 22458
customer order: refuse 15.80 299 1896
order to supplier: creation 49.13 1241 5896
order to supplier: removal 3.51 96067 421
invoice (draft): creation 48.79 3245 5855
invoice (draft): removal 52.18 740 6262
invoice accept: apply 44.40 16432 5328
invoice accept: cancel 46.54 794 5585
customer reserve: creation 4068.47 165 488216
customer reserve: removal 4524.52 59 542943
realization accept: apply 89.16 152 10699
realization accept: cancel 82.56 116 9907
payment from customer: creation 63.45 122 7614
payment from customer: removal 6.13 62 735
payment to supplier: creation 52.29 115 6275
payment to supplier: removal 3.70 62 444
service: total inventory turnovers 5.39 911 647
service: total monetary turnovers 6.31 45 757
service: aggregate perf. data 0.93 13907 111
service: refresh index statistics 0.03 316421 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-02-2023 19:39:59 to 28-02-2023 19:40:01.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5045
TEST_TIME 2 3784
TEST_TIME 3 4804
TEST_TIME 4 4904
TEST_TIME 5 5596
TEST_TIME 6 5887
TEST_TIME 7 5567
TEST_TIME 8 5593
TEST_TIME 9 5381
TEST_TIME 10 5389
TEST_TIME 11 5630
TEST_TIME 12 5545
TEST_TIME 13 5795
TEST_TIME 14 5942
TEST_TIME 15 6128
TEST_TIME 16 6261
TEST_TIME 17 6454
TEST_TIME 18 6565
TEST_TIME 19 7076
TEST_TIME 20 7196
TEST_TIME 21 7600
TEST_TIME 22 7609
TEST_TIME 23 8110
TEST_TIME 24 8686
TEST_TIME 25 9065
TEST_TIME 26 8977
TEST_TIME 27 9636
TEST_TIME 28 10319
TEST_TIME 29 10247
TEST_TIME 30 10274
TEST_TIME 31 10227
TEST_TIME 32 10264
TEST_TIME 33 10103
TEST_TIME 34 9980
TEST_TIME 35 9795
TEST_TIME 36 9790
TEST_TIME 37 9681
TEST_TIME 38 9877
TEST_TIME 39 9880
TEST_TIME 40 9771
TEST_TIME 41 9758
TEST_TIME 42 9828
TEST_TIME 43 9853
TEST_TIME 44 9741
TEST_TIME 45 9565
TEST_TIME 46 9579
TEST_TIME 47 9589
TEST_TIME 48 9500
TEST_TIME 49 9627
TEST_TIME 50 9582
TEST_TIME 51 9614
TEST_TIME 52 9592
TEST_TIME 53 9621
TEST_TIME 54 9630
TEST_TIME 55 9663
TEST_TIME 56 9620
TEST_TIME 57 9661
TEST_TIME 58 9840
TEST_TIME 59 9854
TEST_TIME 60 9948
TEST_TIME 61 9802
TEST_TIME 62 9686
TEST_TIME 63 9660
TEST_TIME 64 9640
TEST_TIME 65 9598
TEST_TIME 66 9541
TEST_TIME 67 9485
TEST_TIME 68 9402
TEST_TIME 69 9364
TEST_TIME 70 9334
TEST_TIME 71 9302
TEST_TIME 72 9264
TEST_TIME 73 9267
TEST_TIME 74 9239
TEST_TIME 75 9389
TEST_TIME 76 9362
TEST_TIME 77 9372
TEST_TIME 78 9409
TEST_TIME 79 9427
TEST_TIME 80 9476
TEST_TIME 81 9502
TEST_TIME 82 9576
TEST_TIME 83 9598
TEST_TIME 84 9521
TEST_TIME 85 9469
TEST_TIME 86 9452
TEST_TIME 87 9385
TEST_TIME 88 9330
TEST_TIME 89 9323
TEST_TIME 90 9310
TEST_TIME 91 9361
TEST_TIME 92 9352
TEST_TIME 93 9356
TEST_TIME 94 9375
TEST_TIME 95 9416
TEST_TIME 96 9475
TEST_TIME 97 9460
TEST_TIME 98 9454
TEST_TIME 99 9466
TEST_TIME 100 9506
TEST_TIME 101 9526
TEST_TIME 102 9543
TEST_TIME 103 9499
TEST_TIME 104 9590
TEST_TIME 105 9503
TEST_TIME 106 9496
TEST_TIME 107 9442
TEST_TIME 108 9446
TEST_TIME 109 9425
TEST_TIME 110 9398
TEST_TIME 111 9387
TEST_TIME 112 9369
TEST_TIME 113 9323
TEST_TIME 114 9335
TEST_TIME 115 9342
TEST_TIME 116 9325
TEST_TIME 117 9349
TEST_TIME 118 9369
TEST_TIME 119 9391
TEST_TIME 120 9353

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-02-2023 19:40:02 to 28-02-2023 19:40:06.

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 1574515 1574515 0 0.00 0 17718 6 0 0
doc_list_biud 1574515 1574515 0 0.00 0 33605 26 0 0
sp_add_invoice_to_stock 5412 5328 84 1.55 38 180416 16433 0 84
sp_cancel_adding_invoice 5585 5585 0 0.00 4 63605 794 0 0
sp_cancel_client_order 1896 1896 0 0.00 4 12363 299 0 0
sp_cancel_customer_reserve 542943 542943 0 0.00 0 24794 60 0 0
sp_cancel_pay_from_customer 735 735 0 0.00 3 2283 62 0 0
sp_cancel_pay_to_supplier 444 444 0 0.00 3 3229 62 0 0
sp_cancel_supplier_invoice 6272 6262 10 0.15 2 33610 740 0 10
sp_cancel_supplier_order 421 421 0 0.00 6 1060594 96067 0 0
sp_cancel_write_off 9907 9907 0 0.00 1 18336 116 0 0
sp_client_order 22458 22458 0 0.00 3 47878 335 0 0
sp_customer_reserve 488216 488216 0 0.00 0 146282 165 0 0
sp_fill_shopping_cart 34207 34207 0 0.00 1 12215 37 0 0
sp_fill_shopping_cart_clo_res 488213 488213 0 0.00 0 17251 13 0 0
sp_get_clo_for_invoice 5324 5324 0 0.00 0 31329 282 0 0
sp_kill_cost_storno 16671 16671 0 0.00 0 6999 51 0 0
sp_kill_qstorno_ret_qs2qd 1102476 1102476 0 0.00 0 33602 32 0 0
sp_kill_qty_storno 583037 583037 0 0.00 0 33604 69 0 0
sp_lock_selected_doc 22146 22146 0 0.00 0 7545 4 0 0
sp_make_cost_storno 29912 29912 0 0.00 0 13914 48 0 0
sp_multiply_rows_for_pdistr 29912 29912 0 0.00 0 12175 30 0 0
sp_multiply_rows_for_qdistr 22458 22458 0 0.00 0 47865 152 0 0
sp_pay_from_customer 7614 7614 0 0.00 4 22094 122 0 0
sp_pay_to_supplier 6275 6275 0 0.00 4 12188 115 0 0
sp_payment_common 13889 13889 0 0.00 2 22090 35 0 0
sp_qd_handle_on_cancel_clo 1896 1896 0 0.00 0 12354 280 0 0
sp_qd_handle_on_invoice_upd_sts 10909 10909 0 0.00 0 24260 307 0 0
sp_qd_handle_on_reserve_upd_sts 10699 10699 0 0.00 0 15308 48 0 0
sp_reserve_write_off 10699 10699 0 0.00 6 15327 153 0 0
sp_supplier_invoice 5855 5855 0 0.00 5 121021 3245 0 0
sp_supplier_order 5896 5896 0 0.00 5 37630 1242 0 0
srv_aggregate_perf_data 111 111 0 0.00 2474 92395 13907 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1503 137961 47814 0 0
srv_find_qd_qs_mism 1071244 1071244 0 0.00 0 17712 5 0 0
srv_make_invnt_saldo 647 647 0 0.00 33 64068 911 0 0
srv_make_money_saldo 757 757 0 0.00 1 6138 46 0 0
srv_recalc_idx_stat 3 3 0 0.00 280560 353329 316421 0 0
t$perf-abend:sp_add_invoice_to_stock 84 84 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5328 5328 0 0.00 0 14612 158 0 0
t$perf-norm:sp_cancel_adding_invoice 452 452 0 0.00 0 1618 58 0 0
t$perf-norm:sp_cancel_client_order 1896 1896 0 0.00 0 1881 9 0 0
t$perf-norm:sp_cancel_customer_reserve 652 652 0 0.00 0 2061 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 735 735 0 0.00 0 1095 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 444 444 0 0.00 0 15181 38 0 0
t$perf-norm:sp_cancel_supplier_invoice 444 444 0 0.00 0 2346 15 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 24577 1544 0 0
t$perf-norm:sp_cancel_write_off 1079 1079 0 0.00 0 15626 23 0 0
t$perf-norm:sp_client_order 22458 22458 0 0.00 0 11408 19 0 0
t$perf-norm:sp_customer_reserve 1105 1105 0 0.00 0 375 2 0 0
t$perf-norm:sp_pay_from_customer 7614 7614 0 0.00 0 4664 7 0 0
t$perf-norm:sp_pay_to_supplier 6275 6275 0 0.00 0 10236 11 0 0
t$perf-norm:sp_reserve_write_off 10699 10699 0 0.00 0 15647 15 0 0
t$perf-norm:sp_supplier_invoice 5855 5855 0 0.00 0 14318 25 0 0
t$perf-norm:sp_supplier_order 5896 5896 0 0.00 0 4611 14 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 1131 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 2 0 0 0
t$perf-norm:srv_make_invnt_saldo 647 647 0 0.00 0 1442 19 0 0
t$perf-norm:srv_make_money_saldo 757 757 0 0.00 0 2256 25 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 1 5 2 0 0
v_add_invoice_to_stock 10816 10648 168 1.55 0 3974 14 0 168
v_all_customers 41034 41034 0 0.00 0 34 0 0 0
v_all_suppliers 32852 32852 0 0.00 0 81 1 0 0
v_all_wares 44916 44916 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 904 904 0 0.00 0 2398 10 0 0
v_cancel_client_order 3792 3792 0 0.00 0 1090 3 0 0
v_cancel_customer_prepayment 1470 1470 0 0.00 0 1813 5 0 0
v_cancel_customer_reserve 1304 1304 0 0.00 0 170 3 0 0
v_cancel_payment_to_supplier 888 888 0 0.00 0 2321 10 0 0
v_cancel_supplier_invoice 908 888 20 2.20 0 3331 14 0 20
v_cancel_supplier_order 842 842 0 0.00 0 1557 5 0 0
v_cancel_write_off 2158 2158 0 0.00 0 4126 15 0 0
v_max_id_clo_ord 5896 5896 0 0.00 0 776 1 0 0
v_max_id_clo_res 1105 1105 0 0.00 0 3908 17 0 0
v_max_id_ord_sup 5853 5853 0 0.00 0 5571 6 0 0
v_max_non_paid_invoice 1598 1598 0 0.00 0 872 3 0 0
v_max_non_paid_realizn 3946 3946 0 0.00 0 4821 5 0 0
v_min_id_clo_ord 5896 5896 0 0.00 0 5528 8 0 0
v_min_id_clo_res 1105 1105 0 0.00 0 2062 3 0 0
v_min_id_ord_sup 5853 5853 0 0.00 0 10408 9 0 0
v_min_non_paid_invoice 6275 6275 0 0.00 0 11008 22 0 0
v_min_non_paid_realizn 7614 7614 0 0.00 0 22087 30 0 0
v_our_firm 11218 11218 0 0.00 0 27 1 0 0
v_reserve_write_off 21396 21396 0 0.00 0 3087 4 0 0
x_lock_depdocs_on_canc_invoice 11403 11403 0 0.00 0 14741 101 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 7313 330 0 0
x_make_qstorno_1000_1200 5896 5896 0 0.00 1 37623 1166 0 0
x_make_qstorno_1200_2000 5855 5855 0 0.00 2 120480 3104 0 0
x_make_qstorno_2100_3300 488216 488216 0 0.00 0 146261 149 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-02-2023 19:40:07 to 28-02-2023 19:40:11.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:46 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:47 22396 8192000000 9916802688 10230296576 1000120016 10.880 100 84 168 0 1027201360 5342848 21738496
17:11:22 17048 8192000000 9963309424 10286051328 1043843664 11.302 100 89 178 0 1074305408 7598960 22862784
17:12:48 16232 8192000000 9992684864 10329108480 1076074208 11.610 100 82 164 0 1105788784 9367888 20346688
17:14:07 11215 8192000000 10040599152 10389471232 1127385904 12.097 100 83 166 0 1157517408 10905776 19225728
17:15:26 7027 8192000000 10091651936 10452889600 1181801056 12.607 100 79 158 1 1212779824 12397008 18581760
17:16:35 2917 8192000000 10136496112 10509033472 1222384400 12.984 100 73 143 1 1255140304 17324752 15431152
17:17:41 2326 8192000000 10166476496 10539425792 1250655648 13.244 100 83 165 0 1282734864 13855424 18223792
17:18:46 1393 8192000000 10187438720 10573692928 1280742032 13.520 100 81 161 1 1310456928 11680096 18034800
17:19:49 22067 8192000000 10204541552 10598543360 1295772192 13.657 100 57 110 0 1317023008 8650320 12600496
17:21:14 3376 8192000000 10203597712 10611068928 1307275728 13.761 100 37 72 0 1327287264 11986928 8024608
17:22:18 7117 8192000000 10204834720 10613686272 1315959344 13.840 100 31 61 1 1330753008 7577584 7216080
17:23:29 14881 8192000000 10217141248 10630324224 1324008240 13.913 100 20 40 0 1334627872 5925344 4694288
17:24:45 39409 8192000000 10210478144 10636382208 1330845360 13.975 100 34 68 0 1344353872 5294096 8214416
17:26:25 9254 8192000000 10226869504 10642919424 1331717232 13.983 100 26 50 0 1342635904 5797200 5121472
17:27:37 8683 8192000000 10213640432 10628825088 1310712144 13.793 98 16 32 0 1320319536 5428800 4178592
17:28:49 15195 8192000000 10209742352 10625867776 1298494704 13.682 98 20 40 0 1307993296 4862464 4636128
17:30:04 28604 8192000000 10143073808 10543484928 1174878464 12.542 91 28 54 0 1187475616 6759936 5837216
17:31:35 6658 8192000000 10117488240 10510061568 1167032464 12.469 93 13 27 0 1175043328 4526224 3484640
17:32:42 5904 8192000000 10142103296 10541240320 1247763888 13.218 99 30 61 1 1260361888 5641232 6956768
17:33:50 45542 8192000000 10173857504 10586275840 1275726112 13.474 99 24 49 0 1287643488 5363248 6554128
17:35:59 7764 8192000000 10191859200 10605494272 1299571664 13.691 100 20 39 0 1309221696 5502352 4147680
17:37:09 7307 8192000000 10200144832 10614362112 1305650480 13.747 100 19 36 0 1315364832 5571904 4142448
17:38:17 9149 8192000000 10227613536 10644963328 1325348816 13.925 100 19 38 0 1338636432 9445296 3842320
17:39:31 16027 8192000000 10245551072 10657611776 1339580032 14.054 100 44 89 0 1357259792 7497792 10181968
17:40:55 77339 8192000000 10251050000 10680217600 1353964432 14.183 100 40 80 2 1373193456 9827360 9401664
17:43:17 7903 8192000000 10281721552 10701254656 1355452928 14.197 99 45 90 0 1381893840 16245568 10195344
17:44:34 8512 8192000000 10290182432 10707349504 1361437168 14.250 99 55 110 1 1391415712 17156816 12821728
17:45:46 17070 8192000000 10305496592 10728476672 1383628848 14.449 100 49 92 0 1414907216 19761904 11516464
17:47:13 8642 8192000000 10323640592 10748772352 1397246672 14.570 100 47 94 1 1429675888 21065056 11364160
17:48:25 11382 8192000000 10303209472 10720522240 1376923744 14.389 99 50 98 1 1415176512 26257856 11994912
17:49:41 8899 8192000000 10331003376 10756227072 1406421776 14.652 100 47 94 0 1445432192 26926848 12083568
17:50:58 8066 8192000000 10332491600 10749562880 1396231456 14.561 100 58 116 0 1443642160 31888768 15521936
17:52:12 5604 8192000000 10318417360 10738307072 1390272336 14.508 100 49 98 1 1434658880 31899680 12486864
17:53:24 2649 8192000000 10335340848 10735534080 1377290432 14.392 100 88 174 1 1438071104 39627936 21152736
17:54:33 10258 8192000000 10314146592 10726256640 1360793072 14.244 99 56 109 1 1414737680 40223296 13721312
17:55:47 6738 8192000000 10324276736 10732855296 1355248192 14.195 98 66 127 0 1414921376 42293344 17379840
17:57:05 5206 8192000000 10302028128 10698764288 1332931568 13.994 99 72 143 0 1400751536 49280816 18539152
17:58:17 4111 8192000000 10334489088 10733703168 1366346320 14.294 100 80 160 0 1433625200 46825760 20453120
17:59:28 4104 8192000000 10316914528 10718720000 1355068480 14.193 100 71 140 1 1418353520 45390320 17894720
18:00:39 1502 8192000000 10318774432 10721857536 1341455168 14.071 99 82 164 0 1412740064 49290848 21994048
18:01:44 11209 8192000000 10312771328 10737127424 1358058672 14.220 100 39 78 1 1416737632 47484208 11194752
18:03:10 21858 8192000000 10286403072 10727333888 1347509376 14.125 99 20 40 0 1385822128 32492768 5819984
18:04:43 27595 8192000000 10283553088 10719133696 1343757504 14.091 100 23 46 0 1393160768 42744928 6658336
18:07:02 91087 8192000000 10276986192 10729717760 1351960032 14.165 99 20 38 0 1371893664 15167056 4766576
18:09:41 58603 8192000000 10286931216 10739941376 1365609712 14.288 99 27 53 1 1388249856 16583392 6056752
18:11:41 89155 8192000000 10296550640 10738163712 1375679168 14.378 99 33 64 0 1391156704 8094992 7382544
18:14:22 39597 8192000000 10298350560 10751455232 1390747760 14.513 100 15 31 0 1406736976 12113472 3875744
18:16:05 56769 8192000000 10305020272 10776502272 1396328464 14.562 100 13 24 1 1416950368 18074016 2547888
18:18:05 137929 8192000000 10308339488 10760384512 1371184368 14.338 97 16 32 0 1388294576 13162528 3947680
18:21:27 45915 8192000000 10290307264 10763206656 1385657008 14.467 99 13 27 1 1398839184 9881968 3300208
18:23:14 49715 8192000000 10314470992 10768089088 1402246992 14.615 100 24 48 0 1418339808 10688672 5404144
18:25:04 60242 8192000000 10305641760 10781102080 1402450992 14.617 100 8 16 0 1419548928 15059568 2038368
18:27:05 77502 8192000000 10321883984 10767085568 1395335664 14.553 99 26 53 1 1414084224 12166224 6582336
18:29:23 50163 8192000000 10292290000 10749489152 1347715792 14.127 96 13 26 0 1358286912 7725648 2845472
18:31:15 68367 8192000000 10287262688 10748194816 1357822800 14.218 98 38 76 0 1377783760 11812768 8148192
18:33:30 74989 8192000000 10307126048 10756542464 1351410432 14.160 97 16 32 0 1368674496 13951408 3312656
18:35:48 60817 8192000000 10277312208 10728132608 1316509584 13.845 96 11 22 0 1342714464 23420176 2784704
18:37:57 103891 8192000000 10267275872 10720313344 1308981200 13.777 96 11 21 0 1328996016 18018800 1996016
18:41:01 42742 8192000000 10270842752 10708910080 1328135232 13.950 97 40 80 2 1348951232 12200144 8615856
18:42:53 38513 8192000000 10239334944 10696019968 1331833376 13.984 98 12 24 0 1341454768 7184384 2437008
18:44:51 60620 8192000000 10244008208 10697244672 1300446576 13.699 96 8 16 0 1307654000 5485392 1722032
18:46:52 59154 8192000000 10232378288 10689384448 1298418208 13.681 96 20 40 1 1311787936 9163520 4206208
18:48:51 43021 8192000000 10245481648 10692624384 1287101664 13.578 95 15 30 0 1296588480 6460976 3025840
18:50:36 49912 8192000000 10255800944 10703241216 1328484272 13.953 98 23 46 0 1346842192 12715344 5642576
18:52:27 31573 8192000000 10256670592 10703024128 1331264048 13.979 98 50 100 0 1359573008 16328240 11980720
18:54:14 24322 8192000000 10256265488 10697682944 1332699632 13.992 99 27 54 0 1349513904 11053936 5760336
18:55:39 45225 8192000000 10263934048 10713591808 1347644960 14.126 99 20 37 0 1365093600 12746624 4702016
18:57:26 28600 8192000000 10287255696 10737852416 1374218112 14.365 100 36 72 0 1395252864 12777792 8256960
18:58:55 118041 8192000000 10280391088 10747310080 1348410240 14.133 98 18 37 0 1365548224 13075408 4062576
19:01:54 123350 8192000000 10284434960 10737340416 1358255344 14.222 98 25 48 0 1379953184 16005440 5692400
19:05:01 63526 8192000000 10261358240 10725347328 1364441376 14.277 99 6 12 0 1373755168 7907152 1406640
19:07:17 72871 8192000000 10283707392 10745839616 1360175200 14.239 98 13 26 0 1373731264 10604976 2951088
19:09:37 74735 8192000000 10286224288 10744238080 1367178528 14.302 98 13 26 0 1377221200 7513872 2528800
19:11:52 67840 8192000000 10299461392 10760790016 1345659024 14.108 96 15 30 0 1361772720 12762512 3351184
19:14:05 64765 8192000000 10281677968 10741440512 1324800736 13.920 95 5 10 0 1340332112 14437632 1093744
19:16:33 52947 8192000000 10301278032 10746372096 1356596048 14.207 98 33 67 0 1375361392 12082592 6682752
19:18:39 106830 8192000000 10291027328 10741301248 1317196384 13.851 95 12 24 0 1338181904 18288880 2696640
19:21:31 14405 8192000000 10296675184 10736152576 1362870144 14.263 99 43 84 1 1382665280 10500896 9294240
19:22:48 96019 8192000000 10274939952 10724663296 1354752624 14.190 99 13 26 0 1370544288 12648192 3143472
19:25:24 36658 8192000000 10249490080 10708357120 1347438096 14.124 99 16 33 0 1359242256 8517568 3286592
19:27:02 76788 8192000000 10246038320 10689810432 1301722800 13.711 96 16 32 0 1313068704 7918832 3427072
19:29:19 66405 8192000000 10231905216 10687930368 1294431568 13.645 96 10 20 0 1303463872 6819504 2212800
19:31:26 67751 8192000000 10251944688 10700861440 1304721968 13.738 96 14 27 0 1319538176 11666496 3149712
19:33:36 36190 8192000000 10245309216 10685116416 1281080656 13.523 95 17 34 0 1293855728 9300656 3474416
19:35:14 41216 8192000000 10250827120 10701307904 1334032992 14.004 99 17 34 1 1350705088 12836400 3835696
19:36:59 78704 8192000000 10256987024 10703794176 1302816032 13.721 97 20 40 0 1322328784 15145712 4367040

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-02-2023 19:40:13 to 28-02-2023 19:40:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 84 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-02-2023 19:40:29 to 28-02-2023 19:40:31.

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          251264
MON$OLDEST_ACTIVE               253967
MON$OLDEST_SNAPSHOT             253967
MON$NEXT_TRANSACTION            253967
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                       2019904
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.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
:763: 
:764: mscs-test-161.budzdorov.ru    Tue Feb 28 19:38:45 2023
:765:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:766: 
:767: 
:768: mscs-test-161.budzdorov.ru    Tue Feb 28 19:38:46 2023
:769:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:770: 
:771: 
:772: mscs-test-161.budzdorov.ru    Tue Feb 28 19:38:49 2023
:773:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:774: 
:775: 
:776: mscs-test-161.budzdorov.ru    Tue Feb 28 19:38:54 2023
:777:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:778: 
:779: 
:780: mscs-test-161.budzdorov.ru    Tue Feb 28 19:38:56 2023
:781:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:782: 
--- end of diff output ---

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

28.02.23 19:40:32. 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
2023-02-28 19:40:34 No severe PSQL-related problems occured
28.02.23 19:40:36. End of report.