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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15159272 kB
MemAvailable 30388284 kB
Buffers 0 kB
Cached 16524856 kB
SwapCached 2028 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-15 13:37:24
DTS_END                         2023-01-15 13:37:24.1190
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/44008
TRN_ID                          214715
ATT_ID                          617
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: 8860.90 22519 1063308
customer order: creation 161.98 458 19438
customer order: refuse 13.63 368 1636
order to supplier: creation 42.46 1310 5095
order to supplier: removal 3.74 153998 449
invoice (draft): creation 42.88 3176 5145
invoice (draft): removal 51.28 954 6154
invoice accept: apply 39.23 25640 4707
invoice accept: cancel 46.81 1086 5617
customer reserve: creation 3657.93 247 438951
customer reserve: removal 4509.06 102 541087
realization accept: apply 78.83 236 9460
realization accept: cancel 90.50 152 10860
payment from customer: creation 56.54 159 6785
payment from customer: removal 5.37 135 644
payment to supplier: creation 46.25 176 5550
payment to supplier: removal 3.23 94 388
service: total inventory turnovers 3.60 4127 432
service: total monetary turnovers 6.48 350 778
service: aggregate perf. data 1.05 14319 126
service: refresh index statistics 0.05 243283 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-01-2023 13:38:28 to 15-01-2023 13:38:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4511
TEST_TIME 2 4530
TEST_TIME 3 5204
TEST_TIME 4 4798
TEST_TIME 5 4679
TEST_TIME 6 5426
TEST_TIME 7 5333
TEST_TIME 8 5535
TEST_TIME 9 5567
TEST_TIME 10 5626
TEST_TIME 11 6088
TEST_TIME 12 6358
TEST_TIME 13 6569
TEST_TIME 14 6583
TEST_TIME 15 6590
TEST_TIME 16 6658
TEST_TIME 17 6879
TEST_TIME 18 6973
TEST_TIME 19 6844
TEST_TIME 20 7017
TEST_TIME 21 7345
TEST_TIME 22 7896
TEST_TIME 23 8127
TEST_TIME 24 8349
TEST_TIME 25 8814
TEST_TIME 26 9536
TEST_TIME 27 9645
TEST_TIME 28 10121
TEST_TIME 29 10503
TEST_TIME 30 10584
TEST_TIME 31 10768
TEST_TIME 32 10863
TEST_TIME 33 10756
TEST_TIME 34 10518
TEST_TIME 35 10352
TEST_TIME 36 10147
TEST_TIME 37 10267
TEST_TIME 38 10118
TEST_TIME 39 10167
TEST_TIME 40 10000
TEST_TIME 41 9887
TEST_TIME 42 9943
TEST_TIME 43 9860
TEST_TIME 44 9741
TEST_TIME 45 9596
TEST_TIME 46 9619
TEST_TIME 47 9514
TEST_TIME 48 9408
TEST_TIME 49 9376
TEST_TIME 50 9280
TEST_TIME 51 9224
TEST_TIME 52 9179
TEST_TIME 53 9109
TEST_TIME 54 9093
TEST_TIME 55 9023
TEST_TIME 56 9196
TEST_TIME 57 9134
TEST_TIME 58 9132
TEST_TIME 59 9123
TEST_TIME 60 9097
TEST_TIME 61 9020
TEST_TIME 62 9061
TEST_TIME 63 9161
TEST_TIME 64 9249
TEST_TIME 65 9244
TEST_TIME 66 9226
TEST_TIME 67 9227
TEST_TIME 68 9378
TEST_TIME 69 9321
TEST_TIME 70 9276
TEST_TIME 71 9162
TEST_TIME 72 9250
TEST_TIME 73 9204
TEST_TIME 74 9151
TEST_TIME 75 9137
TEST_TIME 76 9070
TEST_TIME 77 9016
TEST_TIME 78 8948
TEST_TIME 79 8927
TEST_TIME 80 8889
TEST_TIME 81 8860
TEST_TIME 82 8873
TEST_TIME 83 8928
TEST_TIME 84 8896
TEST_TIME 85 8853
TEST_TIME 86 8836
TEST_TIME 87 8797
TEST_TIME 88 8838
TEST_TIME 89 8892
TEST_TIME 90 8875
TEST_TIME 91 8893
TEST_TIME 92 8884
TEST_TIME 93 9030
TEST_TIME 94 9157
TEST_TIME 95 9147
TEST_TIME 96 9143
TEST_TIME 97 9207
TEST_TIME 98 9206
TEST_TIME 99 9191
TEST_TIME 100 9175
TEST_TIME 101 9212
TEST_TIME 102 9182
TEST_TIME 103 9176
TEST_TIME 104 9127
TEST_TIME 105 9090
TEST_TIME 106 9046
TEST_TIME 107 9024
TEST_TIME 108 8976
TEST_TIME 109 8950
TEST_TIME 110 8951
TEST_TIME 111 8915
TEST_TIME 112 8926
TEST_TIME 113 8919
TEST_TIME 114 8933
TEST_TIME 115 8902
TEST_TIME 116 8911
TEST_TIME 117 8900
TEST_TIME 118 8935
TEST_TIME 119 8929
TEST_TIME 120 8883

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-01-2023 13:38:32 to 15-01-2023 13:38:35.

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 1467615 1467615 0 0.00 0 26067 13 0 0
doc_list_biud 1467610 1467610 0 0.00 0 31884 43 0 0
sp_add_invoice_to_stock 4871 4707 164 3.36 34 198453 25641 0 164
sp_cancel_adding_invoice 5617 5617 0 0.00 6 95007 1087 0 0
sp_cancel_client_order 1636 1636 0 0.00 5 9779 368 0 0
sp_cancel_customer_reserve 541087 541087 0 0.00 1 31489 102 0 0
sp_cancel_pay_from_customer 644 644 0 0.00 4 5381 136 0 0
sp_cancel_pay_to_supplier 388 388 0 0.00 3 1607 95 0 0
sp_cancel_supplier_invoice 6164 6154 10 0.16 3 31897 955 0 10
sp_cancel_supplier_order 449 449 0 0.00 12 1282150 153999 0 0
sp_cancel_write_off 10860 10860 0 0.00 2 18503 152 0 0
sp_client_order 19438 19438 0 0.00 5 26297 458 0 0
sp_customer_reserve 438951 438951 0 0.00 0 100875 247 0 0
sp_fill_shopping_cart 29666 29666 0 0.00 1 20990 75 0 0
sp_fill_shopping_cart_clo_res 438934 438934 0 0.00 0 23279 18 0 0
sp_get_clo_for_invoice 4684 4684 0 0.00 0 26390 325 0 0
sp_kill_cost_storno 17509 17509 0 0.00 0 23331 70 0 0
sp_kill_qstorno_ret_qs2qd 1099637 1099637 0 0.00 0 31882 46 0 0
sp_kill_qty_storno 579945 579945 0 0.00 0 31884 102 0 0
sp_lock_selected_doc 19648 19648 0 0.00 0 4664 6 0 0
sp_make_cost_storno 26479 26479 0 0.00 0 18220 69 0 0
sp_multiply_rows_for_pdistr 26478 26478 0 0.00 0 18058 37 0 0
sp_multiply_rows_for_qdistr 19437 19437 0 0.00 0 20692 191 0 0
sp_pay_from_customer 6785 6785 0 0.00 5 18242 159 0 0
sp_pay_to_supplier 5550 5550 0 0.00 7 18250 177 0 0
sp_payment_common 12334 12334 0 0.00 2 14618 44 0 0
sp_qd_handle_on_cancel_clo 1636 1636 0 0.00 0 9534 312 0 0
sp_qd_handle_on_invoice_upd_sts 10299 10299 0 0.00 0 29287 451 0 0
sp_qd_handle_on_reserve_upd_sts 9460 9460 0 0.00 0 12670 56 0 0
sp_reserve_write_off 9460 9460 0 0.00 7 20093 237 0 0
sp_supplier_invoice 5145 5145 0 0.00 7 88507 3176 0 0
sp_supplier_order 5095 5095 0 0.00 9 30195 1310 0 0
srv_aggregate_perf_data 126 126 0 0.00 2647 206570 14319 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 697 156304 33819 0 0
srv_find_qd_qs_mism 1017103 1017103 0 0.00 0 26063 10 0 0
srv_make_invnt_saldo 432 432 0 0.00 66 84432 4128 0 0
srv_make_money_saldo 778 778 0 0.00 1 9020 351 0 0
srv_recalc_idx_stat 6 6 0 0.00 113329 502336 243283 0 0
t$perf-abend:sp_add_invoice_to_stock 164 164 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 4715 4715 0 0.00 1 10962 689 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 1 7127 366 0 0
t$perf-norm:sp_cancel_client_order 1636 1636 0 0.00 0 5465 29 0 0
t$perf-norm:sp_cancel_customer_reserve 638 638 0 0.00 0 1636 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 644 644 0 0.00 0 183 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 388 388 0 0.00 0 831 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 368 368 0 0.00 0 420 18 0 0
t$perf-norm:sp_cancel_supplier_order 449 449 0 0.00 0 74965 4480 0 0
t$perf-norm:sp_cancel_write_off 997 997 0 0.00 0 974 15 0 0
t$perf-norm:sp_client_order 19438 19438 0 0.00 0 8634 28 0 0
t$perf-norm:sp_customer_reserve 947 947 0 0.00 0 619 11 0 0
t$perf-norm:sp_pay_from_customer 6785 6785 0 0.00 0 8916 15 0 0
t$perf-norm:sp_pay_to_supplier 5550 5550 0 0.00 0 4563 18 0 0
t$perf-norm:sp_reserve_write_off 9461 9461 0 0.00 0 4961 19 0 0
t$perf-norm:sp_supplier_invoice 5145 5145 0 0.00 0 6178 23 0 0
t$perf-norm:sp_supplier_order 5095 5095 0 0.00 0 2020 24 0 0
t$perf-norm:srv_aggregate_perf_data 126 126 0 0.00 0 232 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 12 2 0 0
t$perf-norm:srv_make_invnt_saldo 432 432 0 0.00 0 1396 17 0 0
t$perf-norm:srv_make_money_saldo 778 778 0 0.00 0 1187 5 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 9 155 65 0 0
v_add_invoice_to_stock 9670 9342 328 3.39 0 4470 22 0 328
v_all_customers 35766 35766 0 0.00 0 132 3 0 0
v_all_suppliers 28820 28820 0 0.00 0 88 3 0 0
v_all_wares 38871 38871 0 0.00 0 90 3 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 834 12 0 0
v_cancel_client_order 3272 3272 0 0.00 0 1970 8 0 0
v_cancel_customer_prepayment 1288 1288 0 0.00 0 5288 22 0 0
v_cancel_customer_reserve 1276 1276 0 0.00 0 4176 24 0 0
v_cancel_payment_to_supplier 776 776 0 0.00 0 1122 10 0 0
v_cancel_supplier_invoice 756 736 20 2.64 0 3464 23 0 20
v_cancel_supplier_order 898 898 0 0.00 0 2141 12 0 0
v_cancel_write_off 1994 1994 0 0.00 0 1626 20 0 0
v_max_id_clo_ord 5093 5093 0 0.00 0 6405 8 0 0
v_max_id_clo_res 946 946 0 0.00 0 3777 21 0 0
v_max_id_ord_sup 5134 5134 0 0.00 0 5172 5 0 0
v_max_non_paid_invoice 1364 1364 0 0.00 0 108 7 0 0
v_max_non_paid_realizn 3408 3408 0 0.00 0 1567 6 0 0
v_min_id_clo_ord 5093 5093 0 0.00 0 9764 18 0 0
v_min_id_clo_res 946 946 0 0.00 0 4341 15 0 0
v_min_id_ord_sup 5134 5134 0 0.00 0 3280 7 0 0
v_min_non_paid_invoice 5550 5550 0 0.00 0 6883 17 0 0
v_min_non_paid_realizn 6784 6784 0 0.00 0 6966 21 0 0
v_our_firm 9856 9856 0 0.00 0 100 4 0 0
v_reserve_write_off 18918 18918 0 0.00 0 7457 9 0 0
x_lock_depdocs_on_canc_invoice 11403 11403 0 0.00 0 13483 117 0 0
x_lock_depdocs_on_canc_sup_ord 449 449 0 0.00 0 20267 361 0 0
x_make_qstorno_1000_1200 5095 5095 0 0.00 2 30186 1154 0 0
x_make_qstorno_1200_2000 5145 5145 0 0.00 3 88482 2963 0 0
x_make_qstorno_2100_3300 438951 438951 0 0.00 0 100873 219 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-01-2023 13:38:36 to 15-01-2023 13:38:40.

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
11:07:27 921 8192000000 8924570304 8980729856 16711360 0.203 4 3 5 0 17154016 145024 297632
11:08:29 31065 8192000000 9734281712 10037837824 812755360 9.025 100 81 162 0 840095840 8031696 19308784
11:10:03 14844 8192000000 9772508912 10084507648 844416624 9.344 100 82 164 0 877365936 13427648 19521664
11:11:25 15790 8192000000 9801572448 10121457664 867246944 9.573 100 83 166 0 908767760 22097888 19422928
11:12:44 7641 8192000000 9860105296 10194329600 915148224 10.048 100 76 152 0 972922592 41359616 16414752
11:13:53 3225 8192000000 9928525856 10269462528 953517664 10.426 100 92 183 2 1042084192 68367760 20198768
11:14:58 1580 8192000000 9959753120 10312761344 980836384 10.692 100 90 180 0 1079173088 79011616 19325088
11:16:02 1410 8192000000 9992427280 10351771648 1017819040 11.051 100 88 171 1 1107141808 72199504 17123264
11:17:05 1200 8192000000 10017415376 10386399232 1039683360 11.262 100 92 179 1 1135456784 78599840 17173584
11:18:13 950 8192000000 10017655408 10391269376 1055010592 11.409 100 99 191 0 1139762672 65166640 19585440
11:19:14 1165 8192000000 10037234000 10415841280 1066713568 11.521 100 94 187 2 1157263296 72038992 18510736
11:20:17 1121 8192000000 10042342928 10427707392 1074357200 11.594 100 94 166 1 1164702960 72159632 18186128
11:21:19 1067 8192000000 10060472240 10443845632 1075565104 11.605 100 94 183 1 1184626880 91126304 17935472
11:22:22 1064 8192000000 10048294720 10432573440 1083188240 11.678 100 90 173 1 1167477568 67158976 17130352
11:23:24 1135 8192000000 10065081168 10449846272 1088445840 11.728 100 96 185 2 1187378464 80816096 18116528
11:24:26 990 8192000000 10039316176 10426859520 1087854016 11.722 100 80 139 1 1160297168 59501168 12941984
11:25:28 1024 8192000000 10035974272 10422861824 1093294624 11.774 100 94 177 1 1157072688 45903136 17874928
11:26:31 1116 8192000000 10047927184 10433847296 1096093136 11.801 100 89 167 1 1169204016 56086304 17024576
11:27:33 1248 8192000000 10061010192 10451099648 1097587376 11.815 100 90 174 1 1190235792 75344384 17304032
11:28:35 1072 8192000000 10072834224 10461466624 1088143936 11.725 100 90 172 2 1195265216 89425456 17695824
11:29:37 983 8192000000 10027556400 10418212864 1090766816 11.750 100 94 172 0 1150381008 41767600 17846592
11:30:39 1111 8192000000 10070980080 10456231936 1079051776 11.638 100 99 192 0 1192509824 94221904 19236144
11:31:41 1101 8192000000 10062659184 10445910016 1072962384 11.580 100 96 184 1 1183050096 90696368 19391344
11:32:43 1152 8192000000 9962445888 10330447872 1016657856 11.040 98 92 178 1 1086925248 51450224 18817168
11:33:45 1082 8192000000 9997632960 10367774720 1025298496 11.123 100 92 167 0 1121376624 78837248 17240880
11:34:47 3701 8192000000 9973651424 10353856512 1028551136 11.154 99 71 127 2 1096882976 55706752 12625088
11:35:52 1072 8192000000 9997439680 10377940992 1047222832 11.334 100 77 151 0 1119716432 57726656 14766944
11:36:54 1421 8192000000 9980988080 10363637760 1042094912 11.285 100 82 143 3 1102300720 46262032 13943776
11:38:06 13386 8192000000 9998431808 10383187968 1062693520 11.482 100 69 125 1 1115386592 40136736 12556336
11:39:25 20189 8192000000 10069177728 10460332032 1076414928 11.613 100 69 134 1 1186613568 96611552 13587088
11:40:49 814 8192000000 10049870112 10434072576 1085656816 11.701 100 85 151 1 1171941040 67239296 19044928
11:41:54 1666 8192000000 10102776832 10492211200 1101247040 11.849 100 82 156 2 1223432544 105668144 16517360
11:43:02 9667 8192000000 10179792992 10584379392 1120567968 12.032 100 74 148 0 1290481680 153880592 16033120
11:44:23 8787 8192000000 10177927248 10575900672 1129578288 12.117 100 65 124 1 1296203200 153438608 13186304
11:45:43 1703 8192000000 10219646112 10615300096 1140564768 12.221 100 92 170 2 1335885808 176862768 18458272
11:46:50 1765 8192000000 10287790896 10686963712 1153900672 12.346 100 90 175 2 1402004016 228986832 19116512
11:47:55 1344 8192000000 10308782272 10712903680 1145500144 12.267 100 89 168 0 1422895632 257829632 19565856
11:48:58 846 8192000000 10296147168 10723852288 1161854016 12.421 100 93 179 3 1418433968 234293216 22286736
11:50:02 916 8192000000 10362015296 10763702272 1173131264 12.526 100 91 174 0 1471502416 277757504 20613648
11:51:09 1144 8192000000 10369418272 10786066432 1178795408 12.579 100 79 156 0 1478604896 281524512 18284976
11:52:17 1324 8192000000 10414227360 10817527808 1199929680 12.776 100 94 182 0 1519431840 298654192 20847968
11:53:21 1035 8192000000 10450342064 10866892800 1200544816 12.781 100 96 185 2 1558853024 336061344 22246864
11:54:26 1220 8192000000 10505364736 10909908992 1213039760 12.897 100 95 182 2 1611429520 377844976 20544784
11:55:29 3231 8192000000 10566771584 10989076480 1214282368 12.909 100 72 142 3 1680451952 448364912 17804672
11:56:36 1168 8192000000 10581839488 11002531840 1190597024 12.689 99 95 186 0 1685964432 472409472 22957936
11:57:40 2890 8192000000 10606944720 11063472128 1188512592 12.670 100 68 136 1 1715933904 510110128 17311184
11:58:50 16363 8192000000 10577638976 11068235776 1185832864 12.645 100 46 90 1 1704790624 506469184 12488576
12:00:10 7697 8192000000 10536193712 10996039680 1182731536 12.616 100 39 78 0 1667520320 474074048 10714736
12:01:36 14422 8192000000 10513269968 11003932672 1180270528 12.593 100 38 76 0 1621210208 430817888 10121792
12:03:12 29757 8192000000 10345865392 10866081792 1159695008 12.400 99 29 58 1 1475477008 308730672 7051328
12:04:50 19143 8192000000 10261475536 10757558272 1124193904 12.067 98 37 72 0 1353028928 220663120 8171904
12:06:13 29149 8192000000 10179369808 10660114432 1148273248 12.293 100 22 40 0 1288556992 134990576 5293168
12:07:43 51946 8192000000 10221544144 10701099008 1128693056 12.109 98 16 32 0 1307790592 175234416 3863120
12:09:38 81901 8192000000 10108415920 10617950208 1125878288 12.082 98 38 67 2 1192037808 58383552 7775968
12:12:04 1835 8192000000 10115255632 10544558080 1149356544 12.303 100 72 131 2 1212734096 50703744 12673808
12:13:06 15256 8192000000 10152539712 10578722816 1138083936 12.198 99 72 138 1 1242509904 89304032 15121936
12:14:23 17863 8192000000 10153290720 10618716160 1143273696 12.246 100 24 48 0 1265809408 116752000 5783712
12:17:12 114383 8192000000 10116574496 10573684736 1103306976 11.869 96 19 38 0 1165713920 58473808 3933136
12:21:22 83400 8192000000 10109117472 10562215936 1115167920 11.981 97 19 36 0 1188324320 68749040 4407360
12:24:14 68697 8192000000 10042430144 10483367936 1115064384 11.980 98 38 72 0 1142739968 19978608 7696976
12:26:27 56851 8192000000 10072810528 10521260032 1077231856 11.621 95 26 49 1 1131593744 49375296 4986592
12:28:25 21898 8192000000 10081901136 10527260672 1107754944 11.911 98 26 52 2 1175551488 62476816 5319728
12:29:50 62401 8192000000 10116197104 10594201600 1119406656 12.021 99 15 30 0 1234650064 111767168 3476240
12:31:53 55409 8192000000 10115108064 10581417984 1133136448 12.151 100 20 40 0 1258770368 120912352 4721568
12:33:49 70559 8192000000 10065250896 10520322048 1116231056 11.991 99 9 18 0 1145646704 27794336 1621312
12:36:00 693 8192000000 10056285312 10480144384 1119711280 12.024 99 84 148 2 1154676560 19083072 15882208
12:37:01 32393 8192000000 10064546384 10518482944 1102410832 11.861 98 43 85 0 1168289360 57528496 8350032
12:38:36 13783 8192000000 10125428736 10551930880 1112055616 11.952 99 46 92 1 1207909408 86913168 8940624
12:39:59 34883 8192000000 10131888736 10563031040 1106013248 11.895 98 56 108 1 1236254592 118864192 11377152
12:41:36 58181 8192000000 10103399760 10530902016 1113914016 11.969 99 28 51 1 1169259120 50445280 4899824
12:43:38 48291 8192000000 10127540160 10585554944 1132521520 12.145 100 21 42 0 1203369568 66635184 4212864
12:45:29 67683 8192000000 10087645616 10534481920 1099306864 11.831 96 16 30 0 1190790304 88551872 2931568
12:47:41 100792 8192000000 10131179872 10570440704 1129803872 12.120 100 38 76 0 1233954976 95928288 8222816
12:50:24 36953 8192000000 10062895360 10534490112 1132565056 12.146 100 14 28 0 1167072384 31565136 2942192
12:53:47 51351 8192000000 10044190304 10500059136 1108080608 11.914 98 17 33 0 1134536016 23177648 3277760
12:55:43 61782 8192000000 10053546640 10521755648 1115178064 11.981 99 16 30 1 1179904960 60810848 3916048
12:57:46 21024 8192000000 10074179536 10527899648 1133788272 12.157 100 34 61 0 1182113280 40552288 7772720
12:59:08 30660 8192000000 10124871632 10570567680 1133839104 12.158 99 17 32 0 1215236848 77508448 3889296
13:00:40 23439 8192000000 10110256608 10551566336 1143377568 12.247 99 29 56 0 1205660384 55938752 6344064
13:02:13 11074 8192000000 10175321568 10610167808 1165058368 12.451 100 45 89 0 1267708560 92891600 9758592
13:03:25 13624 8192000000 10224960256 10658652160 1162505232 12.427 100 46 92 0 1327844096 155004944 10333920
13:04:50 12791 8192000000 10292099744 10722082816 1170043056 12.497 100 57 111 0 1383846864 200903760 12900048
13:06:09 30076 8192000000 10227179760 10681180160 1154593360 12.353 99 25 48 0 1316139632 155833760 5712512
13:07:51 40439 8192000000 10257769152 10689769472 1155252992 12.359 98 50 100 0 1357369168 191205632 10910544
13:09:36 34946 8192000000 10299524256 10748444672 1158658176 12.391 100 46 92 0 1382426208 213895424 9872608
13:11:20 156304 8192000000 10089430064 10547904512 1071750976 11.569 93 17 34 0 1150125632 75302960 3071696
13:15:00 47369 8192000000 10106560416 10534248448 1102528320 11.862 96 23 41 0 1149924400 43002368 4393712
13:16:52 29533 8192000000 10091537584 10507210752 1129963968 12.121 100 64 108 2 1184856784 45671248 9221568
13:18:22 55362 8192000000 10087991168 10527801344 1100197488 11.840 97 34 68 0 1150307296 43564704 6545104
13:20:26 125667 8192000000 10067828576 10509676544 1100762848 11.845 97 26 49 1 1150719968 44649584 5307536
13:23:35 34456 8192000000 10094646944 10560004096 1139587904 12.212 100 24 48 0 1191334592 46560416 5186272
13:25:11 41215 8192000000 10105528448 10549903360 1129280512 12.115 100 15 30 0 1198179872 65477456 3421904
13:27:48 52044 8192000000 10085577568 10530897920 1111208720 11.944 98 14 26 0 1191370544 77115264 3046560
13:29:40 74707 8192000000 10084618880 10574446592 1116745232 11.996 99 13 27 1 1185143344 65025424 3372688
13:32:00 19734 8192000000 10069464208 10509115392 1132059120 12.141 100 27 41 2 1171019040 33527744 5432176
13:33:20 41507 8192000000 10113661200 10582097920 1131763824 12.138 99 26 50 0 1206397376 68899232 5734320
13:35:19 49240 8192000000 10103074992 10564927488 1090261056 11.745 95 29 51 1 1158296432 61937856 6097520
13:37:11 23038 8192000000 9208084272 9531547648 344819456 4.039 31 6 12 0 419904496 73830064 1254976

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 15-01-2023 13:38:42 to 15-01-2023 13:38:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 164 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 15-01-2023 13:39:00 to 15-01-2023 13:39:02.

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          214807
MON$OLDEST_ACTIVE               215129
MON$OLDEST_SNAPSHOT             215129
MON$NEXT_TRANSACTION            215129
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                       2049056
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             766
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2890 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2890 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2890 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2890 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 ---
:698: 
:699: mscs-test-161.budzdorov.ru    Sun Jan 15 13:37:35 2023
:700:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:701: 
:702: 
:703: mscs-test-161.budzdorov.ru    Sun Jan 15 13:37:36 2023
:704:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:705: 
:706: 
:707: mscs-test-161.budzdorov.ru    Sun Jan 15 13:37:41 2023
:708:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:709: 
--- end of diff output ---

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

15.01.23 13:39:03. 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-15 13:39:06 No severe PSQL-related problems occured
15.01.23 13:39:07. End of report.