Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.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 15176664 kB
MemAvailable 30464348 kB
Buffers 0 kB
Cached 16557616 kB
SwapCached 4576 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-29 13:37:23
DTS_END                         2023-01-29 13:37:23.7130
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/60542
TRN_ID                          218271
ATT_ID                          611
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: 9120.48 22318 1094458
customer order: creation 170.17 513 20420
customer order: refuse 14.78 471 1773
order to supplier: creation 45.48 1448 5457
order to supplier: removal 3.21 178256 385
invoice (draft): creation 45.08 3152 5410
invoice (draft): removal 51.68 986 6202
invoice accept: apply 40.17 28410 4821
invoice accept: cancel 46.73 1187 5607
customer reserve: creation 3785.66 268 454279
customer reserve: removal 4621.68 96 554601
realization accept: apply 82.10 308 9852
realization accept: cancel 85.22 176 10226
payment from customer: creation 59.13 182 7096
payment from customer: removal 5.40 126 648
payment to supplier: creation 47.68 216 5721
payment to supplier: removal 3.47 112 416
service: total inventory turnovers 3.95 4094 474
service: total monetary turnovers 8.03 377 963
service: aggregate perf. data 0.84 15267 101
service: refresh index statistics 0.05 210718 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-01-2023 13:39:22 to 29-01-2023 13:39:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7527
TEST_TIME 2 7668
TEST_TIME 3 6297
TEST_TIME 4 6296
TEST_TIME 5 6405
TEST_TIME 6 6620
TEST_TIME 7 6353
TEST_TIME 8 6239
TEST_TIME 9 6466
TEST_TIME 10 6487
TEST_TIME 11 6471
TEST_TIME 12 6720
TEST_TIME 13 6847
TEST_TIME 14 6905
TEST_TIME 15 6975
TEST_TIME 16 6978
TEST_TIME 17 7167
TEST_TIME 18 7225
TEST_TIME 19 7303
TEST_TIME 20 7391
TEST_TIME 21 7588
TEST_TIME 22 7732
TEST_TIME 23 7821
TEST_TIME 24 7936
TEST_TIME 25 8193
TEST_TIME 26 8303
TEST_TIME 27 8361
TEST_TIME 28 8405
TEST_TIME 29 8289
TEST_TIME 30 8445
TEST_TIME 31 8988
TEST_TIME 32 9494
TEST_TIME 33 9833
TEST_TIME 34 10727
TEST_TIME 35 10895
TEST_TIME 36 10766
TEST_TIME 37 10633
TEST_TIME 38 10468
TEST_TIME 39 10312
TEST_TIME 40 10205
TEST_TIME 41 10233
TEST_TIME 42 10127
TEST_TIME 43 10049
TEST_TIME 44 9995
TEST_TIME 45 10033
TEST_TIME 46 9934
TEST_TIME 47 10100
TEST_TIME 48 10031
TEST_TIME 49 9996
TEST_TIME 50 9917
TEST_TIME 51 9844
TEST_TIME 52 9732
TEST_TIME 53 9708
TEST_TIME 54 9736
TEST_TIME 55 9687
TEST_TIME 56 9558
TEST_TIME 57 9535
TEST_TIME 58 9541
TEST_TIME 59 9533
TEST_TIME 60 9536
TEST_TIME 61 9465
TEST_TIME 62 9477
TEST_TIME 63 9659
TEST_TIME 64 9695
TEST_TIME 65 9677
TEST_TIME 66 9662
TEST_TIME 67 9707
TEST_TIME 68 9750
TEST_TIME 69 9710
TEST_TIME 70 9722
TEST_TIME 71 9805
TEST_TIME 72 9730
TEST_TIME 73 9699
TEST_TIME 74 9669
TEST_TIME 75 9578
TEST_TIME 76 9535
TEST_TIME 77 9493
TEST_TIME 78 9476
TEST_TIME 79 9484
TEST_TIME 80 9402
TEST_TIME 81 9418
TEST_TIME 82 9437
TEST_TIME 83 9437
TEST_TIME 84 9413
TEST_TIME 85 9447
TEST_TIME 86 9426
TEST_TIME 87 9495
TEST_TIME 88 9461
TEST_TIME 89 9480
TEST_TIME 90 9489
TEST_TIME 91 9479
TEST_TIME 92 9475
TEST_TIME 93 9600
TEST_TIME 94 9516
TEST_TIME 95 9440
TEST_TIME 96 9402
TEST_TIME 97 9417
TEST_TIME 98 9351
TEST_TIME 99 9291
TEST_TIME 100 9372
TEST_TIME 101 9339
TEST_TIME 102 9325
TEST_TIME 103 9298
TEST_TIME 104 9328
TEST_TIME 105 9333
TEST_TIME 106 9290
TEST_TIME 107 9358
TEST_TIME 108 9331
TEST_TIME 109 9331
TEST_TIME 110 9338
TEST_TIME 111 9389
TEST_TIME 112 9363
TEST_TIME 113 9389
TEST_TIME 114 9321
TEST_TIME 115 9321
TEST_TIME 116 9292
TEST_TIME 117 9253
TEST_TIME 118 9210
TEST_TIME 119 9193
TEST_TIME 120 9139

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-01-2023 13:39:26 to 29-01-2023 13:39:29.

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 1514655 1514655 0 0.00 0 44119 14 0 0
doc_list_biud 1514646 1514646 0 0.00 0 48815 41 0 0
sp_add_invoice_to_stock 4955 4821 134 2.70 35 267459 28410 0 134
sp_cancel_adding_invoice 5607 5607 0 0.00 5 111637 1187 0 0
sp_cancel_client_order 1773 1773 0 0.00 6 32753 471 0 0
sp_cancel_customer_reserve 554601 554601 0 0.00 1 48819 97 0 0
sp_cancel_pay_from_customer 648 648 0 0.00 4 9111 126 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 4 3266 113 0 0
sp_cancel_supplier_invoice 6214 6202 12 0.19 2 35729 986 0 12
sp_cancel_supplier_order 385 385 0 0.00 10 1540514 178256 0 0
sp_cancel_write_off 10226 10226 0 0.00 2 12563 176 0 0
sp_client_order 20420 20420 0 0.00 6 38517 513 0 0
sp_customer_reserve 454279 454279 0 0.00 1 58930 269 0 0
sp_fill_shopping_cart 31278 31278 0 0.00 1 11755 84 0 0
sp_fill_shopping_cart_clo_res 454261 454261 0 0.00 0 14092 19 0 0
sp_get_clo_for_invoice 4795 4795 0 0.00 0 21159 305 0 0
sp_kill_cost_storno 16897 16897 0 0.00 0 9103 74 0 0
sp_kill_qstorno_ret_qs2qd 1126015 1126015 0 0.00 0 48813 42 0 0
sp_kill_qty_storno 593440 593440 0 0.00 0 48815 97 0 0
sp_lock_selected_doc 20324 20324 0 0.00 0 12537 8 0 0
sp_make_cost_storno 27463 27463 0 0.00 0 19317 94 0 0
sp_multiply_rows_for_pdistr 27461 27461 0 0.00 0 13747 42 0 0
sp_multiply_rows_for_qdistr 20420 20420 0 0.00 0 17032 178 0 0
sp_pay_from_customer 7096 7096 0 0.00 6 32366 182 0 0
sp_pay_to_supplier 5721 5721 0 0.00 6 18903 216 0 0
sp_payment_common 12815 12815 0 0.00 2 7791 46 0 0
sp_qd_handle_on_cancel_clo 1773 1773 0 0.00 1 32742 377 0 0
sp_qd_handle_on_invoice_upd_sts 10402 10402 0 0.00 0 32929 522 0 0
sp_qd_handle_on_reserve_upd_sts 9851 9851 0 0.00 0 28169 77 0 0
sp_reserve_write_off 9852 9852 0 0.00 8 28304 308 0 0
sp_supplier_invoice 5410 5410 0 0.00 10 96067 3153 0 0
sp_supplier_order 5457 5457 0 0.00 8 48773 1448 0 0
srv_aggregate_perf_data 101 101 0 0.00 2677 148478 15267 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 790 134546 34848 0 0
srv_find_qd_qs_mism 1047114 1047114 0 0.00 0 44118 11 0 0
srv_make_invnt_saldo 474 474 0 0.00 60 110490 4094 0 0
srv_make_money_saldo 963 963 0 0.00 1 11697 378 0 0
srv_recalc_idx_stat 6 6 0 0.00 60089 317298 210719 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4823 4823 0 0.00 1 11395 931 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 1 8339 510 0 0
t$perf-norm:sp_cancel_client_order 1773 1773 0 0.00 0 1630 31 0 0
t$perf-norm:sp_cancel_customer_reserve 674 674 0 0.00 0 2239 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 648 648 0 0.00 0 305 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 525 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 1287 50 0 0
t$perf-norm:sp_cancel_supplier_order 385 385 0 0.00 0 90051 7219 0 0
t$perf-norm:sp_cancel_write_off 981 981 0 0.00 0 1985 20 0 0
t$perf-norm:sp_client_order 20423 20423 0 0.00 0 9654 38 0 0
t$perf-norm:sp_customer_reserve 949 949 0 0.00 0 525 12 0 0
t$perf-norm:sp_pay_from_customer 7096 7096 0 0.00 0 9446 23 0 0
t$perf-norm:sp_pay_to_supplier 5721 5721 0 0.00 0 5214 20 0 0
t$perf-norm:sp_reserve_write_off 9852 9852 0 0.00 0 10021 27 0 0
t$perf-norm:sp_supplier_invoice 5410 5410 0 0.00 0 4286 31 0 0
t$perf-norm:sp_supplier_order 5457 5457 0 0.00 0 9622 37 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 4310 55 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 113 5 0 0
t$perf-norm:srv_make_invnt_saldo 474 474 0 0.00 0 886 15 0 0
t$perf-norm:srv_make_money_saldo 963 963 0 0.00 0 16142 22 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 4 205 69 0 0
v_add_invoice_to_stock 9852 9584 268 2.72 0 3716 24 0 268
v_all_customers 37388 37388 0 0.00 0 70 4 0 0
v_all_suppliers 29912 29912 0 0.00 0 64 5 0 0
v_all_wares 40834 40834 0 0.00 0 287 4 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 547 15 0 0
v_cancel_client_order 3546 3546 0 0.00 0 30803 20 0 0
v_cancel_customer_prepayment 1296 1296 0 0.00 0 1643 11 0 0
v_cancel_customer_reserve 1348 1348 0 0.00 0 1482 14 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 3170 15 0 0
v_cancel_supplier_invoice 844 820 24 2.84 0 2971 29 0 24
v_cancel_supplier_order 770 770 0 0.00 0 1454 11 0 0
v_cancel_write_off 1962 1962 0 0.00 0 1600 26 0 0
v_max_id_clo_ord 5453 5453 0 0.00 0 245 5 0 0
v_max_id_clo_res 949 949 0 0.00 0 961 10 0 0
v_max_id_ord_sup 5407 5407 0 0.00 0 8581 10 0 0
v_max_non_paid_invoice 1624 1624 0 0.00 0 536 9 0 0
v_max_non_paid_realizn 3654 3654 0 0.00 0 2151 8 0 0
v_min_id_clo_ord 5453 5453 0 0.00 0 2573 8 0 0
v_min_id_clo_res 949 949 0 0.00 0 5636 22 0 0
v_min_id_ord_sup 5407 5407 0 0.00 0 8551 12 0 0
v_min_non_paid_invoice 5720 5720 0 0.00 0 7169 16 0 0
v_min_non_paid_realizn 7095 7095 0 0.00 0 7786 18 0 0
v_our_firm 10326 10326 0 0.00 0 67 5 0 0
v_reserve_write_off 19698 19698 0 0.00 0 3036 10 0 0
x_lock_depdocs_on_canc_invoice 11399 11399 0 0.00 0 26090 120 0 0
x_lock_depdocs_on_canc_sup_ord 385 385 0 0.00 0 2653 357 0 0
x_make_qstorno_1000_1200 5457 5457 0 0.00 2 48759 1271 0 0
x_make_qstorno_1200_2000 5410 5410 0 0.00 3 96052 2930 0 0
x_make_qstorno_2100_3300 454279 454279 0 0.00 0 58928 238 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-01-2023 13:39:30 to 29-01-2023 13:39:34.

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 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:28 35588 8192000000 9720720016 10024722432 799954928 8.896 100 91 182 1 830795104 8235312 22604864
11:10:16 12471 8192000000 9764474720 10075971584 842497296 9.325 100 91 182 0 878094400 13930576 21666528
11:11:39 14191 8192000000 9809708448 10129485824 872885024 9.629 100 86 172 1 920328048 27830064 19612960
11:12:57 6130 8192000000 9872592672 10210308096 921897744 10.115 100 88 172 0 990982000 50543184 18541072
11:14:08 1730 8192000000 9948622288 10288762880 958183344 10.471 100 96 192 1 1054805072 76751040 19870688
11:15:11 1806 8192000000 9970441456 10321752064 988556112 10.767 100 91 181 1 1083366224 76439888 18370224
11:16:14 2248 8192000000 10005193088 10366701568 1016456672 11.038 100 92 183 2 1115476224 80836576 18182976
11:17:19 1123 8192000000 10018520288 10389155840 1042880528 11.292 100 95 186 2 1137403280 75863744 18659008
11:18:21 954 8192000000 10020083648 10397937664 1058049184 11.438 100 94 171 2 1145570208 68863120 18657904
11:19:23 7612 8192000000 10010220144 10389012480 1065168400 11.506 100 76 143 4 1143275088 63940880 14165808
11:20:31 946 8192000000 10043179824 10422931456 1083121472 11.677 100 91 172 1 1162298736 61224112 17953152
11:21:33 3054 8192000000 10050600352 10440671232 1083043296 11.676 100 62 122 0 1177691360 81734928 12913136
11:22:37 999 8192000000 10032904864 10417561600 1091494512 11.757 100 91 176 4 1153812800 44788928 17529360
11:23:38 1078 8192000000 10041507872 10431942656 1095125712 11.791 100 81 154 0 1169330000 58700720 15503568
11:24:41 1072 8192000000 10072571776 10463305728 1100958672 11.847 100 79 156 0 1192498624 75876176 15663776
11:25:43 1194 8192000000 10068262848 10459648000 1103128224 11.867 100 89 173 0 1187397616 67593616 16675776
11:26:46 1239 8192000000 10072362624 10460975104 1104749312 11.883 100 97 187 1 1190641424 66199184 19692928
11:27:49 1052 8192000000 10066650992 10458677248 1104808032 11.883 100 98 181 3 1187705344 64824576 18072736
11:28:50 975 8192000000 10042710080 10428125184 1086854544 11.713 100 96 176 3 1163027056 58085776 18086736
11:29:52 1267 8192000000 10045087392 10436603904 1091951920 11.761 100 80 158 0 1166164480 57531760 16680800
11:30:54 7059 8192000000 10044134864 10436718592 1080336864 11.651 100 66 128 0 1161725728 68482576 12906288
11:32:06 7636 8192000000 10021333920 10415366144 1061112080 11.467 100 76 149 0 1136421136 60479520 14829536
11:33:14 3221 8192000000 9987843296 10368454656 1040761376 11.272 99 75 143 3 1105581600 49982352 14837872
11:34:18 1163 8192000000 9982832368 10361270272 1040964496 11.274 100 84 164 0 1107230928 49960016 16306416
11:35:20 2147 8192000000 10004766928 10389180416 1038759072 11.253 100 80 158 1 1118076048 63828960 15488016
11:36:27 2726 8192000000 9998590928 10375229440 1041621504 11.280 100 66 128 0 1113268352 58922272 12724576
11:37:32 1042 8192000000 9995477728 10378194944 1052555056 11.385 100 94 174 2 1116824064 46315200 17953808
11:38:38 2172 8192000000 10032987632 10409799680 1063858992 11.493 100 84 159 2 1152605200 74208208 14538000
11:39:45 15254 8192000000 10037776816 10434138112 1079473120 11.642 100 63 120 1 1160632544 69060480 12098944
11:41:07 4115 8192000000 10062219344 10448326656 1092089728 11.763 100 80 154 2 1179940672 72262752 15588192
11:42:14 3124 8192000000 10084165904 10475888640 1100437824 11.842 100 72 137 3 1208593648 94693408 13462416
11:43:22 2919 8192000000 10095573696 10491998208 1118452576 12.012 100 65 128 2 1211997872 79376688 14168608
11:44:34 5297 8192000000 10136039664 10540404736 1128911728 12.111 100 72 138 1 1246368816 102399712 15057376
11:45:42 2908 8192000000 10155350368 10556293120 1140477568 12.220 100 78 148 1 1268259776 111271408 16510800
11:46:57 932 8192000000 10186432304 10592735232 1151237168 12.321 100 95 186 1 1304742352 130792880 22712304
11:48:02 1190 8192000000 10242201472 10643456000 1159551648 12.399 100 85 159 1 1345689344 168954080 17183616
11:49:06 1183 8192000000 10275521888 10683740160 1167843504 12.477 100 94 176 0 1390727856 202349584 20534768
11:50:11 3787 8192000000 10285109696 10695786496 1167062848 12.469 100 72 142 2 1386732176 204084464 15584864
11:51:20 1094 8192000000 10346407744 10748559360 1172997008 12.525 100 97 185 2 1457870896 263726080 21147808
11:52:23 1499 8192000000 10338374048 10750935040 1170099088 12.498 100 94 180 2 1447932640 256130512 21703040
11:53:32 3069 8192000000 10338739776 10766913536 1175918256 12.552 100 77 150 0 1450999872 256814544 18267072
11:54:39 1507 8192000000 10383830640 10819178496 1196250800 12.741 100 93 181 0 1490654208 273950160 20453248
11:55:44 1705 8192000000 10420546048 10843885568 1207914672 12.850 100 93 182 2 1527056800 297590880 21551248
11:56:50 1260 8192000000 10469099280 10893910016 1208912240 12.859 100 93 180 2 1574858544 344613248 21333056
11:57:53 801 8192000000 10480708304 10896785408 1177511744 12.567 99 94 188 1 1587144496 386442912 23189840
11:58:57 2003 8192000000 10517071856 10956103680 1196638848 12.745 100 70 139 0 1626903680 412099712 18165120
12:00:06 1165 8192000000 10545599232 10962698240 1181597008 12.605 100 99 191 1 1649657824 445057488 23003328
12:01:08 1045 8192000000 10584917344 11010154496 1198433744 12.762 100 97 190 2 1692165552 470084976 23646832
12:02:13 925 8192000000 10597490304 11042250752 1195553008 12.735 100 85 166 0 1702399968 487016432 19830528
12:03:17 780 8192000000 10610101680 11034259456 1196691888 12.746 100 89 176 1 1713644320 495625616 21326816
12:04:20 6925 8192000000 10606978256 11074220032 1196161056 12.741 100 56 110 0 1715906416 505641392 14103968
12:05:29 8081 8192000000 10615008944 11087495168 1168592544 12.484 99 45 90 0 1709232640 528897984 11742112
12:07:02 16586 8192000000 10656145120 11117019136 1172173168 12.517 100 50 100 0 1753826240 568419408 13233664
12:08:57 19035 8192000000 10550244656 11124404224 1156874224 12.374 100 27 54 0 1665024992 500646912 7503856
12:10:37 39672 8192000000 10164082592 10691842048 1127627280 12.099 98 32 62 0 1399398016 263332480 8438256
12:12:26 40542 8192000000 10055405232 10501361664 1103136800 11.867 97 31 53 0 1139253456 30459232 5657424
12:14:19 41559 8192000000 10130523344 10570502144 1148259696 12.293 100 32 64 0 1217393200 62654272 6479232
12:16:01 133244 8192000000 10099830496 10548957184 1096249392 11.802 96 40 70 3 1172078688 68230560 7598736
12:19:18 40772 8192000000 10064335872 10504847360 1128154768 12.104 100 28 52 0 1167507264 33730912 5621584
12:21:00 30692 8192000000 10144223344 10577571840 1105395872 11.889 97 32 62 1 1210936448 98769968 6770608
12:22:36 22072 8192000000 10137830832 10598899712 1124318928 12.068 100 72 135 0 1240713664 100338560 16056176
12:24:02 32436 8192000000 10120386816 10602196992 1125653376 12.080 100 41 83 0 1262916128 128519360 8743392
12:25:45 33453 8192000000 10111202368 10563608576 1119257760 12.020 99 18 36 1 1198882144 75416992 4207392
12:27:23 134522 8192000000 10098652768 10561482752 1107101696 11.905 98 22 45 0 1176619264 64695136 4822432
12:30:46 32907 8192000000 10103645792 10553921536 1113314416 11.964 99 19 33 1 1193080752 75857664 3908672
12:32:19 56267 8192000000 10088272944 10559217664 1128572864 12.108 100 14 28 0 1185084224 53632832 2878528
12:34:29 78253 8192000000 10071844256 10542592000 1100397248 11.841 97 16 30 1 1156069984 52333552 3339184
12:36:47 41123 8192000000 10100194736 10559737856 1131560368 12.136 100 17 34 0 1199373744 64369744 3443632
12:38:29 56982 8192000000 10150247616 10597376000 1113292816 11.964 97 39 76 1 1229916992 108625056 7999120
12:40:29 90093 8192000000 10087303184 10566766592 1124426176 12.069 99 16 32 0 1166343968 39009760 2908032
12:43:04 32265 8192000000 10079770480 10511101952 1095273952 11.793 96 33 66 0 1142619904 40436608 6909344
12:44:41 4942 8192000000 10131676688 10567434240 1141191264 12.227 100 50 100 0 1228307728 76975216 10141248
12:45:49 20052 8192000000 10090443440 10521165824 1121340656 12.040 98 56 105 0 1169967616 37554176 11072784
12:47:45 94810 8192000000 10144729072 10646323200 1152194448 12.330 100 25 49 0 1242889424 85477088 5217888
12:50:20 99359 8192000000 10075547840 10540072960 1095821664 11.798 95 20 40 0 1139102048 38690160 4590224
12:53:08 81048 8192000000 10173712304 10633568256 1154194016 12.349 100 23 44 0 1274050400 114844400 5011984
12:55:30 58128 8192000000 10138183216 10674913280 1120086320 12.028 97 12 24 1 1241645728 118615264 2944144
12:57:54 79768 8192000000 10095418304 10633506816 1128610192 12.108 99 17 34 0 1231745184 99338640 3796352
13:00:15 45006 8192000000 10086017328 10533638144 1089930416 11.742 96 14 29 0 1119941600 26501664 3509520
13:02:16 60253 8192000000 10108198400 10541346816 1110109920 11.933 98 31 59 0 1187340768 70709024 6521824
13:04:17 61443 8192000000 10088396720 10539405312 1099552464 11.833 97 23 46 0 1173923584 69496080 4875040
13:06:18 29127 8192000000 10108557568 10576523264 1127662560 12.099 100 42 84 0 1211364640 75219824 8482256
13:07:49 944 8192000000 10085961632 10498281472 1115623568 11.986 99 92 178 2 1173663488 38834720 19205200
13:08:55 83271 8192000000 10133934288 10600976384 1114108848 11.971 99 23 46 0 1244977664 126505232 4363584
13:11:18 95614 8192000000 10075699808 10541322240 1097818592 11.817 97 33 66 0 1157919184 53580928 6519664
13:13:57 68266 8192000000 10077275168 10580541440 1116096320 11.990 99 12 25 0 1179273840 60693648 2483872
13:16:16 68449 8192000000 10081453408 10590285824 1135546048 12.174 100 8 16 0 1196597344 59732128 1319168
13:18:27 20616 8192000000 10080806640 10528129024 1117943984 12.008 98 25 49 0 1142940336 20265344 4731008
13:20:01 74699 8192000000 10117528640 10564993024 1106642432 11.901 97 35 68 0 1176592608 61968464 7981712
13:22:56 43257 8192000000 10120057280 10560024576 1129364736 12.115 99 40 80 0 1184048384 46382064 8301584
13:24:48 118195 8192000000 10078834304 10528227328 1018932576 11.062 89 21 42 1 1067866512 44791440 4142496
13:27:47 19107 8192000000 10119079568 10550525952 1121774304 12.044 100 49 98 0 1215651568 84206352 9670912
13:29:06 18139 8192000000 10090757680 10522144768 1112016464 11.952 99 45 90 0 1163602896 42502112 9084320
13:30:26 41280 8192000000 10137362544 10610704384 1131566384 12.136 100 56 112 0 1237880032 94875280 11438368
13:32:30 69164 8192000000 10056713408 10535948288 1119849392 12.026 99 16 31 0 1161662272 38715568 3097312
13:34:50 51355 8192000000 10065579280 10538291200 1139181200 12.208 100 6 12 0 1176671792 36436640 1053952
13:36:42 19268 8192000000 10066434688 10537005056 1137525984 12.192 100 24 44 1 1167282128 24421712 5334432

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 29-01-2023 13:39:37 to 29-01-2023 13:39:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 134 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-01-2023 13:39:54 to 29-01-2023 13:39:56.

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          218225
MON$OLDEST_ACTIVE               218636
MON$OLDEST_SNAPSHOT             218636
MON$NEXT_TRANSACTION            218636
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                       2051680
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             771
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2894 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2894 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2894 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2894 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 ---
:144: 
:145: mscs-test-161.budzdorov.ru    Sun Jan 29 13:37:25 2023
:146:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:147: 
:148: 
:149: mscs-test-161.budzdorov.ru    Sun Jan 29 13:37:30 2023
:150:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:151: 
:152: 
:153: mscs-test-161.budzdorov.ru    Sun Jan 29 13:37:33 2023
:154:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:155: 
:156: 
:157: mscs-test-161.budzdorov.ru    Sun Jan 29 13:37:34 2023
:158:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:159: 
--- end of diff output ---

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

29.01.23 13:39:57. 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-29 13:40:00 No severe PSQL-related problems occured
29.01.23 13:40:01. End of report.