Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.01.2023 10:37
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 15033240 kB
MemAvailable 30387928 kB
Buffers 0 kB
Cached 16646376 kB
SwapCached 4388 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 4.0.3
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-01-10 10:37:25
DTS_END                         2023-01-10 10:37:25.2780
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/50542
TRN_ID                          216797
ATT_ID                          585
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: 9068.24 21404 1088189
customer order: creation 169.08 466 20290
customer order: refuse 14.67 486 1760
order to supplier: creation 45.93 1456 5512
order to supplier: removal 3.49 162385 419
invoice (draft): creation 45.99 3270 5519
invoice (draft): removal 52.22 1007 6266
invoice accept: apply 40.97 27229 4916
invoice accept: cancel 46.90 1293 5628
customer reserve: creation 3784.34 260 454121
customer reserve: removal 4566.93 97 548031
realization accept: apply 82.83 308 9940
realization accept: cancel 87.45 164 10494
payment from customer: creation 57.89 187 6947
payment from customer: removal 5.60 161 672
payment to supplier: creation 47.87 201 5744
payment to supplier: removal 3.44 128 413
service: total inventory turnovers 3.73 4963 448
service: total monetary turnovers 7.89 464 947
service: aggregate perf. data 0.98 14331 118
service: refresh index statistics 0.03 209218 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-01-2023 10:38:16 to 10-01-2023 10:38:18.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5607
TEST_TIME 2 5065
TEST_TIME 3 5340
TEST_TIME 4 5035
TEST_TIME 5 5440
TEST_TIME 6 5336
TEST_TIME 7 5297
TEST_TIME 8 5377
TEST_TIME 9 5547
TEST_TIME 10 5419
TEST_TIME 11 5631
TEST_TIME 12 5742
TEST_TIME 13 5895
TEST_TIME 14 6053
TEST_TIME 15 6414
TEST_TIME 16 6441
TEST_TIME 17 6506
TEST_TIME 18 6875
TEST_TIME 19 6837
TEST_TIME 20 7126
TEST_TIME 21 7329
TEST_TIME 22 7524
TEST_TIME 23 7763
TEST_TIME 24 7889
TEST_TIME 25 7999
TEST_TIME 26 8333
TEST_TIME 27 8515
TEST_TIME 28 8720
TEST_TIME 29 9015
TEST_TIME 30 9072
TEST_TIME 31 9208
TEST_TIME 32 9531
TEST_TIME 33 9778
TEST_TIME 34 9958
TEST_TIME 35 10218
TEST_TIME 36 10333
TEST_TIME 37 10454
TEST_TIME 38 10388
TEST_TIME 39 10281
TEST_TIME 40 10161
TEST_TIME 41 10300
TEST_TIME 42 10163
TEST_TIME 43 10153
TEST_TIME 44 10068
TEST_TIME 45 10001
TEST_TIME 46 9845
TEST_TIME 47 9761
TEST_TIME 48 9688
TEST_TIME 49 9649
TEST_TIME 50 9634
TEST_TIME 51 9569
TEST_TIME 52 9530
TEST_TIME 53 9494
TEST_TIME 54 9419
TEST_TIME 55 9389
TEST_TIME 56 9371
TEST_TIME 57 9463
TEST_TIME 58 9562
TEST_TIME 59 9444
TEST_TIME 60 9533
TEST_TIME 61 9442
TEST_TIME 62 9427
TEST_TIME 63 9350
TEST_TIME 64 9381
TEST_TIME 65 9454
TEST_TIME 66 9531
TEST_TIME 67 9608
TEST_TIME 68 9617
TEST_TIME 69 9632
TEST_TIME 70 9650
TEST_TIME 71 9640
TEST_TIME 72 9629
TEST_TIME 73 9606
TEST_TIME 74 9540
TEST_TIME 75 9517
TEST_TIME 76 9465
TEST_TIME 77 9399
TEST_TIME 78 9413
TEST_TIME 79 9403
TEST_TIME 80 9368
TEST_TIME 81 9339
TEST_TIME 82 9288
TEST_TIME 83 9230
TEST_TIME 84 9150
TEST_TIME 85 9159
TEST_TIME 86 9182
TEST_TIME 87 9160
TEST_TIME 88 9133
TEST_TIME 89 9142
TEST_TIME 90 9181
TEST_TIME 91 9184
TEST_TIME 92 9140
TEST_TIME 93 9122
TEST_TIME 94 9167
TEST_TIME 95 9201
TEST_TIME 96 9251
TEST_TIME 97 9231
TEST_TIME 98 9293
TEST_TIME 99 9298
TEST_TIME 100 9279
TEST_TIME 101 9219
TEST_TIME 102 9193
TEST_TIME 103 9164
TEST_TIME 104 9151
TEST_TIME 105 9088
TEST_TIME 106 9112
TEST_TIME 107 9079
TEST_TIME 108 9034
TEST_TIME 109 9003
TEST_TIME 110 8999
TEST_TIME 111 8973
TEST_TIME 112 8995
TEST_TIME 113 8997
TEST_TIME 114 8999
TEST_TIME 115 9028
TEST_TIME 116 9044
TEST_TIME 117 9017
TEST_TIME 118 9037
TEST_TIME 119 9134
TEST_TIME 120 9091

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-01-2023 10:38:19 to 10-01-2023 10:38:23.

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 1507139 1507139 0 0.00 0 19077 14 0 0
doc_list_biud 1507131 1507131 0 0.00 0 71648 42 0 0
sp_add_invoice_to_stock 5016 4916 100 1.99 48 199840 27229 0 100
sp_cancel_adding_invoice 5628 5628 0 0.00 5 94095 1294 0 0
sp_cancel_client_order 1760 1760 0 0.00 6 14721 487 0 0
sp_cancel_customer_reserve 548031 548031 0 0.00 1 61954 98 0 0
sp_cancel_pay_from_customer 672 672 0 0.00 4 9811 162 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 4 3038 128 0 0
sp_cancel_supplier_invoice 6273 6266 7 0.11 3 71656 1007 0 7
sp_cancel_supplier_order 419 419 0 0.00 10 1564206 162385 0 0
sp_cancel_write_off 10494 10494 0 0.00 2 10791 165 0 0
sp_client_order 20290 20290 0 0.00 5 28273 466 0 0
sp_customer_reserve 454121 454121 0 0.00 0 107216 261 0 0
sp_fill_shopping_cart 31313 31313 0 0.00 1 33185 85 0 0
sp_fill_shopping_cart_clo_res 454092 454092 0 0.00 0 15882 19 0 0
sp_get_clo_for_invoice 4881 4881 0 0.00 0 27958 293 0 0
sp_kill_cost_storno 17207 17207 0 0.00 0 15799 76 0 0
sp_kill_qstorno_ret_qs2qd 1113241 1113241 0 0.00 0 71647 44 0 0
sp_kill_qty_storno 587419 587419 0 0.00 0 71647 100 0 0
sp_lock_selected_doc 20534 20534 0 0.00 0 10104 7 0 0
sp_make_cost_storno 27512 27512 0 0.00 0 19038 86 0 0
sp_multiply_rows_for_pdistr 27512 27512 0 0.00 0 8628 42 0 0
sp_multiply_rows_for_qdistr 20290 20290 0 0.00 0 9470 187 0 0
sp_pay_from_customer 6947 6947 0 0.00 5 14161 187 0 0
sp_pay_to_supplier 5744 5744 0 0.00 6 19418 201 0 0
sp_payment_common 12690 12690 0 0.00 2 9766 47 0 0
sp_qd_handle_on_cancel_clo 1760 1760 0 0.00 1 14705 421 0 0
sp_qd_handle_on_invoice_upd_sts 10509 10509 0 0.00 0 12015 536 0 0
sp_qd_handle_on_reserve_upd_sts 9940 9940 0 0.00 0 24113 79 0 0
sp_reserve_write_off 9940 9940 0 0.00 8 24168 309 0 0
sp_supplier_invoice 5519 5519 0 0.00 12 85359 3270 0 0
sp_supplier_order 5512 5512 0 0.00 7 33412 1457 0 0
srv_aggregate_perf_data 118 118 0 0.00 2427 209863 14332 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 752 157956 32225 0 0
srv_find_qd_qs_mism 1040395 1040395 0 0.00 0 10039 11 0 0
srv_make_invnt_saldo 448 448 0 0.00 67 244670 4964 0 0
srv_make_money_saldo 947 947 0 0.00 1 27191 465 0 0
srv_recalc_idx_stat 4 4 0 0.00 43747 414168 209219 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 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 4918 4918 0 0.00 1 11151 974 0 0
t$perf-norm:sp_cancel_adding_invoice 434 434 0 0.00 1 6329 480 0 0
t$perf-norm:sp_cancel_client_order 1760 1760 0 0.00 0 4496 33 0 0
t$perf-norm:sp_cancel_customer_reserve 651 651 0 0.00 0 1242 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 672 672 0 0.00 0 3281 24 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 1161 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 1497 49 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 110741 7113 0 0
t$perf-norm:sp_cancel_write_off 983 983 0 0.00 0 2962 20 0 0
t$perf-norm:sp_client_order 20290 20290 0 0.00 0 3437 37 0 0
t$perf-norm:sp_customer_reserve 983 983 0 0.00 0 3216 23 0 0
t$perf-norm:sp_pay_from_customer 6947 6947 0 0.00 0 11995 22 0 0
t$perf-norm:sp_pay_to_supplier 5744 5744 0 0.00 0 4587 20 0 0
t$perf-norm:sp_reserve_write_off 9940 9940 0 0.00 0 8321 24 0 0
t$perf-norm:sp_supplier_invoice 5519 5519 0 0.00 0 7801 33 0 0
t$perf-norm:sp_supplier_order 5512 5512 0 0.00 0 2584 33 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 498 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 197 9 0 0
t$perf-norm:srv_make_invnt_saldo 448 448 0 0.00 0 3680 28 0 0
t$perf-norm:srv_make_money_saldo 947 947 0 0.00 0 261 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 37 153 86 0 0
v_add_invoice_to_stock 9960 9760 200 2.00 0 5058 24 0 200
v_all_customers 37090 37090 0 0.00 0 98 4 0 0
v_all_suppliers 30748 30748 0 0.00 0 252 4 0 0
v_all_wares 40580 40580 0 0.00 0 93 4 0 0
v_cancel_adding_invoice 868 868 0 0.00 0 917 15 0 0
v_cancel_client_order 3520 3520 0 0.00 0 1678 9 0 0
v_cancel_customer_prepayment 1344 1344 0 0.00 0 988 12 0 0
v_cancel_customer_reserve 1302 1302 0 0.00 0 2118 12 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 1376 12 0 0
v_cancel_supplier_invoice 780 766 14 1.79 0 799 24 0 14
v_cancel_supplier_order 838 838 0 0.00 0 5218 18 0 0
v_cancel_write_off 1966 1966 0 0.00 0 1620 26 0 0
v_max_id_clo_ord 5509 5509 0 0.00 0 1149 5 0 0
v_max_id_clo_res 983 983 0 0.00 0 6855 15 0 0
v_max_id_ord_sup 5512 5512 0 0.00 0 8227 9 0 0
v_max_non_paid_invoice 1390 1390 0 0.00 0 1279 9 0 0
v_max_non_paid_realizn 3641 3641 0 0.00 0 948 7 0 0
v_min_id_clo_ord 5509 5509 0 0.00 0 3933 9 0 0
v_min_id_clo_res 983 983 0 0.00 0 8475 21 0 0
v_min_id_ord_sup 5512 5512 0 0.00 0 7143 15 0 0
v_min_non_paid_invoice 5743 5743 0 0.00 0 9520 17 0 0
v_min_non_paid_realizn 6947 6947 0 0.00 0 9759 19 0 0
v_our_firm 10102 10102 0 0.00 0 66 5 0 0
v_reserve_write_off 19874 19874 0 0.00 0 5077 12 0 0
x_lock_depdocs_on_canc_invoice 11511 11511 0 0.00 0 23133 125 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 22293 421 0 0
x_make_qstorno_1000_1200 5512 5512 0 0.00 3 33392 1285 0 0
x_make_qstorno_1200_2000 5519 5519 0 0.00 6 85344 3031 0 0
x_make_qstorno_2100_3300 454120 454120 0 0.00 0 107202 228 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-01-2023 10:38:24 to 10-01-2023 10:38:28.

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
08:07:29 19 8192000000 8808669680 8823496704 10286304 0.125 6 1 2 0 10392864 70784 35776
08:08:30 25177 8192000000 9719785184 10019459072 789318352 8.788 100 84 166 0 817150256 7240272 20591632
08:09:59 32436 8192000000 9768986960 10077978624 841821456 9.318 100 89 177 0 875675376 12550128 21303792
08:11:38 17737 8192000000 9814527424 10136317952 881795136 9.718 100 82 164 0 920386368 19937616 18653616
08:12:58 12080 8192000000 9868355712 10205364224 931257552 10.207 100 82 164 0 979961696 30471280 18232864
08:14:16 5065 8192000000 9908307568 10252853248 967488512 10.562 100 89 178 0 1025801136 39880528 18432096
08:15:25 1579 8192000000 9975365616 10325954560 996793696 10.847 100 90 174 0 1091482432 77083264 17605472
08:16:30 1652 8192000000 9997468032 10363289600 1015975760 11.033 100 87 166 0 1111721120 79179600 16565760
08:17:34 1852 8192000000 10017461440 10386870272 1038163376 11.247 100 93 180 1 1141217952 84740112 18314464
08:18:39 774 8192000000 10035223120 10410385408 1059889184 11.455 100 97 190 0 1160899312 82115760 18894368
08:19:41 1186 8192000000 10047201120 10425348096 1069988320 11.552 100 97 188 1 1166026832 77749952 18288560
08:20:44 1249 8192000000 10040598880 10420981760 1076665120 11.616 100 96 184 1 1162377744 67531344 18181280
08:21:46 2081 8192000000 10039038496 10422837248 1082263168 11.669 100 76 143 2 1162901008 65697888 14939952
08:22:50 2849 8192000000 10048273184 10428137472 1078716640 11.635 100 90 171 2 1171205136 75992624 16495872
08:23:53 1098 8192000000 10054833776 10443612160 1083510800 11.681 100 94 182 1 1179411056 77152688 18747568
08:24:56 1126 8192000000 10065841376 10451140608 1087776368 11.722 100 96 184 1 1187748400 81944352 18027680
08:25:59 1070 8192000000 10044894544 10433236992 1088482560 11.728 100 87 167 1 1166974272 61924960 16566752
08:27:01 2193 8192000000 10057732000 10453999616 1091143264 11.754 100 72 141 1 1183886912 78571456 14172192
08:28:04 1360 8192000000 10057273984 10448175104 1092186256 11.763 100 78 151 1 1179670400 71859808 15624336
08:29:06 1123 8192000000 10058612080 10449895424 1093113776 11.772 100 98 190 0 1177730032 66176848 18439408
08:30:08 1093 8192000000 10021074048 10411868160 1081268688 11.660 100 79 147 2 1143329056 45991248 16069120
08:31:11 942 8192000000 10033612512 10419359744 1080715632 11.654 100 87 158 3 1156508352 58932064 16860656
08:32:13 1186 8192000000 10020985824 10397843456 1058449696 11.442 100 95 182 0 1147241920 69414096 19378128
08:33:15 1017 8192000000 10016357936 10393280512 1054767056 11.406 100 98 186 1 1141812736 67365248 19680432
08:34:17 1482 8192000000 9992417376 10370957312 1037308528 11.239 99 83 160 2 1118999216 65855472 15835216
08:35:19 1294 8192000000 9973295616 10340257792 1016950576 11.043 100 95 177 1 1098011648 64225296 16835776
08:36:22 3720 8192000000 9969349424 10356195328 1025058752 11.121 99 50 98 0 1094242960 58955792 10228416
08:37:29 3701 8192000000 9995790816 10373496832 1043323744 11.297 100 80 142 1 1116637616 60061952 13251920
08:38:44 7290 8192000000 10021408032 10402529280 1065415424 11.508 100 71 134 2 1143943200 65572144 12955632
08:39:58 1745 8192000000 10066021184 10451795968 1083904384 11.685 100 89 158 1 1178578896 77995776 16678736
08:41:06 1445 8192000000 10086162928 10466852864 1092297616 11.765 100 92 174 2 1201788768 91598480 17892672
08:42:16 4625 8192000000 10106922048 10496536576 1104289008 11.878 100 74 138 0 1230001184 111019632 14692544
08:43:25 5877 8192000000 10121172608 10514079744 1113838064 11.969 100 70 133 1 1242077872 113881136 14358672
08:44:34 11751 8192000000 10213025680 10611863552 1125583376 12.080 100 70 138 1 1315608688 174935536 15089776
08:45:51 905 8192000000 10205297376 10603610112 1134676048 12.165 100 93 176 3 1322811856 169529584 18606224
08:46:55 1484 8192000000 10231957360 10627915776 1145627216 12.268 100 79 142 4 1339232384 177583264 16021904
08:48:02 1218 8192000000 10294162800 10689323008 1150908608 12.318 100 93 186 4 1403771024 231482752 21379664
08:49:09 2973 8192000000 10316694736 10722787328 1161811872 12.420 100 83 165 1 1432350480 251758768 18779840
08:50:15 1157 8192000000 10344979904 10749026304 1173803360 12.532 100 92 173 2 1455588976 262428864 19356752
08:51:19 1676 8192000000 10362876112 10775388160 1176168656 12.554 100 90 175 0 1476828336 280490560 20169120
08:52:24 5431 8192000000 10375237536 10807123968 1174830976 12.542 100 76 144 0 1489168928 297357344 16980608
08:53:35 1643 8192000000 10411104240 10821341184 1182894320 12.617 100 95 187 0 1527937504 323216000 21827184
08:54:41 893 8192000000 10415238608 10836713472 1185509248 12.642 100 96 186 1 1524034880 316374912 22150720
08:55:44 1103 8192000000 10422991568 10846838784 1178664288 12.578 100 98 194 2 1534588784 332815488 23109008
08:56:49 869 8192000000 10465389744 10894520320 1185691632 12.643 100 98 186 0 1574105408 366112000 22301776
08:57:54 1031 8192000000 10447340976 10900316160 1191726912 12.699 100 90 178 1 1555098640 341032544 22339184
08:58:58 1381 8192000000 10446577536 10900865024 1180871392 12.598 100 90 177 0 1553699008 351310896 21516720
09:00:03 845 8192000000 10486160176 10920050688 1192465568 12.706 100 99 196 0 1593880752 377780464 23634720
09:01:07 1318 8192000000 10461738560 10911834112 1188448848 12.669 100 95 186 1 1569680448 358592224 22639376
09:02:13 746 8192000000 10479137776 10914881536 1183947520 12.627 100 97 189 0 1585146800 379025376 22173904
09:03:16 3222 8192000000 10452813648 10904166400 1178876160 12.580 100 70 135 0 1558148976 362780064 16492752
09:04:27 7535 8192000000 10435390720 10930524160 1167529856 12.474 99 59 118 0 1536509536 354384304 14595376
09:05:39 7897 8192000000 10377163104 10852806656 1149147888 12.301 99 48 93 1 1492225312 330843152 12234272
09:07:13 16930 8192000000 10383882272 10826399744 1165879008 12.458 100 34 62 0 1488214736 314997344 7338384
09:08:30 41579 8192000000 10289624080 10837696512 1152767280 12.335 100 22 44 0 1460888352 302927264 5193808
09:10:13 38198 8192000000 10218427136 10684436480 1125558176 12.079 98 18 34 0 1312665680 183264656 3842848
09:11:56 19952 8192000000 10191419120 10657169408 1142284544 12.237 100 28 56 1 1288765696 139955040 6526112
09:13:34 72524 8192000000 10071790272 10529566720 1088307840 11.727 96 24 48 0 1181961648 87959056 5694752
09:15:48 61113 8192000000 10079332784 10518323200 1113655072 11.967 98 25 49 0 1148143920 29712544 4776304
09:17:51 91021 8192000000 10102605424 10613858304 1136337680 12.181 99 17 32 0 1233213376 93810112 3065584
09:21:02 69154 8192000000 10057731312 10473803776 1071836384 11.570 95 25 48 0 1126309408 48680864 5792160
09:23:13 97841 8192000000 10096388032 10532995072 1103042000 11.866 98 39 74 0 1185330784 74637456 7651328
09:25:58 53492 8192000000 10105807968 10538176512 1129050544 12.112 99 41 76 1 1189573472 51944720 8578208
09:27:53 31974 8192000000 10095209296 10539393024 1091781760 11.760 97 27 54 0 1182987664 84567488 6638416
09:29:27 31370 8192000000 10133260352 10581684224 1120063456 12.028 100 59 116 2 1245016336 110888128 14064752
09:31:12 55771 8192000000 10167475328 10617438208 1111877984 11.950 98 18 36 0 1230741856 114196784 4667088
09:33:08 54331 8192000000 10151924384 10604994560 1121005648 12.036 99 15 30 0 1246016768 121703072 3308048
09:35:03 41583 8192000000 10082695312 10586411008 1118373088 12.012 100 12 24 0 1193537376 72536080 2628208
09:36:48 79319 8192000000 10055780960 10556268544 1102888672 11.865 98 26 50 0 1182527984 74117440 5521872
09:39:07 40054 8192000000 10099048032 10550308864 1126582416 12.089 99 22 40 0 1185085984 53826768 4676800
09:40:49 14414 8192000000 10158187200 10601283584 1144898800 12.262 100 40 78 0 1253852048 100484880 8468368
09:42:11 51839 8192000000 10140886768 10600570880 1129004304 12.112 99 24 46 1 1245338336 110932864 5401168
09:44:23 57627 8192000000 10102334560 10565120000 1146978656 12.281 100 41 76 2 1223432160 68713840 7739664
09:48:22 37552 8192000000 10100923744 10530746368 1129538864 12.117 99 38 75 1 1165621200 28855808 7226528
09:50:08 85690 8192000000 10107013600 10553397248 1126553712 12.089 98 28 56 1 1197646656 65382160 5710784
09:53:28 22101 8192000000 10083893712 10521366528 1129224976 12.114 99 41 81 0 1174035536 37389424 7421136
09:54:53 897 8192000000 10106742336 10536153088 1143434176 12.248 100 72 128 1 1214360912 55954528 14972208
09:55:55 26818 8192000000 10101865264 10554789888 1133149744 12.151 100 38 76 0 1216593136 75432096 8011296
09:57:22 40704 8192000000 10074634944 10533888000 1116419120 11.993 98 29 58 0 1174861600 52279680 6162800
09:59:20 57585 8192000000 10079799680 10530766848 1128437808 12.107 100 30 52 1 1179352144 45416816 5497520
10:01:29 117530 8192000000 10107400400 10579136512 1091855568 11.760 96 19 38 0 1179839040 83910544 4072928
10:04:28 21236 8192000000 10070088448 10514161664 1137696752 12.194 100 20 38 0 1171927408 30361328 3869328
10:05:49 13881 8192000000 10092788320 10520358912 1117965600 12.008 100 50 100 0 1177117536 49232736 9919200
10:07:07 25844 8192000000 10093534032 10540261376 1110360192 11.936 99 44 88 0 1190384288 70864944 9159152
10:08:33 45765 8192000000 10105182448 10536710144 1129332864 12.115 99 43 81 1 1182029616 44512048 8184704
10:10:19 29504 8192000000 10169585856 10622443520 1146579904 12.277 100 36 72 0 1280086960 125711456 7795600
10:11:52 41693 8192000000 10128142768 10578264064 1110094336 11.933 98 18 36 0 1201489920 87681728 3713856
10:13:38 22663 8192000000 10080138288 10526191616 1108358864 11.917 98 49 96 0 1161139440 42453936 10326640
10:15:06 33316 8192000000 10085773696 10574348288 1131155040 12.132 100 36 72 1 1235110976 96747568 7208368
10:16:41 157956 8192000000 10071791728 10536525824 1091682672 11.759 96 22 44 0 1161808048 65050800 5074576
10:20:20 36840 8192000000 10061574032 10509406208 1100379520 11.841 98 33 67 0 1146269952 39281232 6609200
10:21:57 56251 8192000000 10082376640 10548387840 1124301408 12.068 100 10 20 0 1201261008 74703696 2255904
10:23:54 76492 8192000000 10087242640 10564005888 1121758480 12.044 99 20 38 0 1177527568 51467968 4301120
10:26:41 53272 8192000000 10129870160 10600284160 1114122208 11.971 98 16 32 0 1209630048 91771536 3736304
10:29:19 31577 8192000000 10114858960 10562494464 1120622672 12.033 99 35 69 0 1236122416 108280976 7218768
10:31:06 31880 8192000000 10121942784 10578087936 1125807728 12.082 100 49 97 1 1228736400 92467728 10460944
10:32:41 52952 8192000000 10129233664 10571051008 1124331728 12.068 99 28 55 0 1215197648 84678864 6187056
10:34:37 51000 8192000000 10125497072 10600894464 1132672384 12.147 100 19 38 0 1224751664 87747728 4331552
10:36:30 49150 8192000000 10079216912 10553729024 1111379104 11.945 98 17 34 0 1166457840 51721328 3357408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 10-01-2023 10:38:30 to 10-01-2023 10:38:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 100 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:03, from 10-01-2023 10:38:47 to 10-01-2023 10:38:50.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          216136
MON$OLDEST_ACTIVE               217262
MON$OLDEST_SNAPSHOT             217262
MON$NEXT_TRANSACTION            217262
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-30 07:30:08.6030 GMT
MON$PAGES                       2063136
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             768
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2887 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2887 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2887 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2887 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:139: 
:140: mscs-test-161.budzdorov.ru    Tue Jan 10 10:37:33 2023
:141:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:142: 
:143: 
:144: mscs-test-161.budzdorov.ru    Tue Jan 10 10:37:40 2023
:145:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:146: 
--- end of diff output ---

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

10.01.23 10:38:51. There are 3 logs in /var/tmp/logs-oltp40 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-01-10 10:38:53 No severe PSQL-related problems occured
10.01.23 10:38:55. End of report.