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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15210068 kB
MemAvailable 30430336 kB
Buffers 0 kB
Cached 16530048 kB
SwapCached 2392 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-16 10:37:26
DTS_END                         2023-01-16 10:37:26.1450
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/57562
TRN_ID                          217810
ATT_ID                          657
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: 8520.23 21077 1022427
customer order: creation 168.03 485 20163
customer order: refuse 14.38 440 1726
order to supplier: creation 44.68 1349 5361
order to supplier: removal 3.23 172833 387
invoice (draft): creation 44.98 3291 5398
invoice (draft): removal 45.97 1069 5516
invoice accept: apply 41.43 26865 4971
invoice accept: cancel 41.59 1226 4991
customer reserve: creation 3759.28 263 451113
customer reserve: removal 4081.47 107 489776
realization accept: apply 81.51 286 9781
realization accept: cancel 66.03 166 7923
payment from customer: creation 58.32 163 6998
payment from customer: removal 5.69 139 683
payment to supplier: creation 48.42 185 5810
payment to supplier: removal 3.13 127 376
service: total inventory turnovers 3.77 4223 452
service: total monetary turnovers 7.37 357 884
service: aggregate perf. data 0.95 15285 114
service: refresh index statistics 0.03 192675 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-01-2023 10:39:23 to 16-01-2023 10:39:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7199
TEST_TIME 2 5091
TEST_TIME 3 5873
TEST_TIME 4 5509
TEST_TIME 5 5606
TEST_TIME 6 5303
TEST_TIME 7 5175
TEST_TIME 8 5045
TEST_TIME 9 5059
TEST_TIME 10 5265
TEST_TIME 11 5732
TEST_TIME 12 5804
TEST_TIME 13 5941
TEST_TIME 14 6005
TEST_TIME 15 6271
TEST_TIME 16 6663
TEST_TIME 17 6821
TEST_TIME 18 6868
TEST_TIME 19 6918
TEST_TIME 20 6972
TEST_TIME 21 7015
TEST_TIME 22 7289
TEST_TIME 23 7699
TEST_TIME 24 7646
TEST_TIME 25 7691
TEST_TIME 26 8028
TEST_TIME 27 8259
TEST_TIME 28 8475
TEST_TIME 29 8562
TEST_TIME 30 8885
TEST_TIME 31 9049
TEST_TIME 32 9208
TEST_TIME 33 9735
TEST_TIME 34 9982
TEST_TIME 35 10513
TEST_TIME 36 10397
TEST_TIME 37 10432
TEST_TIME 38 10364
TEST_TIME 39 10173
TEST_TIME 40 10012
TEST_TIME 41 9927
TEST_TIME 42 9785
TEST_TIME 43 9829
TEST_TIME 44 9777
TEST_TIME 45 9699
TEST_TIME 46 9723
TEST_TIME 47 9682
TEST_TIME 48 9541
TEST_TIME 49 9514
TEST_TIME 50 9414
TEST_TIME 51 9281
TEST_TIME 52 9258
TEST_TIME 53 9177
TEST_TIME 54 9147
TEST_TIME 55 9114
TEST_TIME 56 8996
TEST_TIME 57 8925
TEST_TIME 58 8942
TEST_TIME 59 8920
TEST_TIME 60 8858
TEST_TIME 61 8845
TEST_TIME 62 8883
TEST_TIME 63 8877
TEST_TIME 64 8859
TEST_TIME 65 8883
TEST_TIME 66 8971
TEST_TIME 67 9001
TEST_TIME 68 9012
TEST_TIME 69 9066
TEST_TIME 70 9034
TEST_TIME 71 9114
TEST_TIME 72 9061
TEST_TIME 73 9068
TEST_TIME 74 9037
TEST_TIME 75 9047
TEST_TIME 76 8951
TEST_TIME 77 8947
TEST_TIME 78 8899
TEST_TIME 79 8891
TEST_TIME 80 8815
TEST_TIME 81 8898
TEST_TIME 82 8861
TEST_TIME 83 8827
TEST_TIME 84 8841
TEST_TIME 85 8821
TEST_TIME 86 8804
TEST_TIME 87 8824
TEST_TIME 88 8979
TEST_TIME 89 8922
TEST_TIME 90 8966
TEST_TIME 91 8912
TEST_TIME 92 9008
TEST_TIME 93 9054
TEST_TIME 94 8980
TEST_TIME 95 8941
TEST_TIME 96 8881
TEST_TIME 97 8872
TEST_TIME 98 8821
TEST_TIME 99 8778
TEST_TIME 100 8747
TEST_TIME 101 8747
TEST_TIME 102 8715
TEST_TIME 103 8680
TEST_TIME 104 8717
TEST_TIME 105 8736
TEST_TIME 106 8736
TEST_TIME 107 8713
TEST_TIME 108 8747
TEST_TIME 109 8789
TEST_TIME 110 8803
TEST_TIME 111 8765
TEST_TIME 112 8801
TEST_TIME 113 8784
TEST_TIME 114 8756
TEST_TIME 115 8722
TEST_TIME 116 8713
TEST_TIME 117 8664
TEST_TIME 118 8640
TEST_TIME 119 8603
TEST_TIME 120 8548

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-01-2023 10:39:26 to 16-01-2023 10:39:30.

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 1438529 1438529 0 0.00 0 24723 14 0 0
doc_list_biud 1438512 1438512 0 0.00 0 40888 42 0 0
sp_add_invoice_to_stock 5108 4971 137 2.68 35 271551 26865 0 137
sp_cancel_adding_invoice 4991 4991 0 0.00 8 82446 1226 0 0
sp_cancel_client_order 1726 1726 0 0.00 6 12445 441 0 0
sp_cancel_customer_reserve 489776 489776 0 0.00 1 38320 107 0 0
sp_cancel_pay_from_customer 683 683 0 0.00 3 3989 140 0 0
sp_cancel_pay_to_supplier 376 376 0 0.00 4 5954 127 0 0
sp_cancel_supplier_invoice 5522 5516 6 0.10 3 40985 1070 0 6
sp_cancel_supplier_order 387 387 0 0.00 13 1401466 172834 0 0
sp_cancel_write_off 7923 7923 0 0.00 2 19595 167 0 0
sp_client_order 20163 20163 0 0.00 5 27327 486 0 0
sp_customer_reserve 451113 451113 0 0.00 0 70556 264 0 0
sp_fill_shopping_cart 30909 30909 0 0.00 1 23092 82 0 0
sp_fill_shopping_cart_clo_res 451082 451082 0 0.00 0 14061 18 0 0
sp_get_clo_for_invoice 4932 4932 0 0.00 0 46487 284 0 0
sp_kill_cost_storno 13973 13973 0 0.00 0 18900 77 0 0
sp_kill_qstorno_ret_qs2qd 993378 993378 0 0.00 0 40867 47 0 0
sp_kill_qty_storno 525029 525029 0 0.00 0 40882 107 0 0
sp_lock_selected_doc 20253 20253 0 0.00 0 7180 9 0 0
sp_make_cost_storno 27518 27518 0 0.00 0 24717 71 0 0
sp_multiply_rows_for_pdistr 27517 27517 0 0.00 0 8067 41 0 0
sp_multiply_rows_for_qdistr 20162 20162 0 0.00 0 16379 187 0 0
sp_pay_from_customer 6998 6998 0 0.00 5 7868 164 0 0
sp_pay_to_supplier 5810 5810 0 0.00 5 13859 185 0 0
sp_payment_common 12806 12806 0 0.00 2 6409 41 0 0
sp_qd_handle_on_cancel_clo 1726 1726 0 0.00 0 12416 372 0 0
sp_qd_handle_on_invoice_upd_sts 9922 9922 0 0.00 0 31336 509 0 0
sp_qd_handle_on_reserve_upd_sts 9779 9779 0 0.00 0 14931 70 0 0
sp_reserve_write_off 9781 9781 0 0.00 8 24753 286 0 0
sp_supplier_invoice 5398 5398 0 0.00 9 94601 3292 0 0
sp_supplier_order 5361 5361 0 0.00 7 31316 1349 0 0
srv_aggregate_perf_data 114 114 0 0.00 1910 172780 15286 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 873 110995 33546 0 0
srv_find_qd_qs_mism 974858 974858 0 0.00 0 12057 11 0 0
srv_make_invnt_saldo 452 452 0 0.00 48 111022 4224 0 0
srv_make_money_saldo 884 884 0 0.00 1 13818 358 0 0
srv_recalc_idx_stat 4 4 0 0.00 42154 445232 192676 0 0
t$perf-abend:sp_add_invoice_to_stock 137 137 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4980 4980 0 0.00 0 25687 912 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 1 6347 406 0 0
t$perf-norm:sp_cancel_client_order 1726 1726 0 0.00 0 2643 30 0 0
t$perf-norm:sp_cancel_customer_reserve 652 652 0 0.00 0 4434 44 0 0
t$perf-norm:sp_cancel_pay_from_customer 683 683 0 0.00 0 1197 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 376 376 0 0.00 0 276 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 1100 38 0 0
t$perf-norm:sp_cancel_supplier_order 387 387 0 0.00 0 120382 7239 0 0
t$perf-norm:sp_cancel_write_off 934 934 0 0.00 0 1962 18 0 0
t$perf-norm:sp_client_order 20164 20164 0 0.00 0 6156 39 0 0
t$perf-norm:sp_customer_reserve 949 949 0 0.00 0 2247 19 0 0
t$perf-norm:sp_pay_from_customer 6999 6999 0 0.00 0 14365 23 0 0
t$perf-norm:sp_pay_to_supplier 5810 5810 0 0.00 0 11619 21 0 0
t$perf-norm:sp_reserve_write_off 9781 9781 0 0.00 0 18195 27 0 0
t$perf-norm:sp_supplier_invoice 5398 5398 0 0.00 0 12206 33 0 0
t$perf-norm:sp_supplier_order 5361 5361 0 0.00 0 12296 35 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 232 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 117 5 0 0
t$perf-norm:srv_make_invnt_saldo 452 452 0 0.00 0 1790 14 0 0
t$perf-norm:srv_make_money_saldo 884 884 0 0.00 0 2944 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 192 87 0 0
v_add_invoice_to_stock 10130 9856 274 2.70 0 6607 27 0 274
v_all_customers 36634 36634 0 0.00 0 209 4 0 0
v_all_suppliers 30322 30322 0 0.00 0 210 4 0 0
v_all_wares 40316 40316 0 0.00 0 58 4 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 3358 22 0 0
v_cancel_client_order 3452 3452 0 0.00 0 2091 10 0 0
v_cancel_customer_prepayment 1366 1366 0 0.00 0 2228 13 0 0
v_cancel_customer_reserve 1304 1304 0 0.00 0 3318 15 0 0
v_cancel_payment_to_supplier 752 752 0 0.00 0 730 11 0 0
v_cancel_supplier_invoice 780 768 12 1.53 0 1227 26 0 12
v_cancel_supplier_order 774 774 0 0.00 0 732 11 0 0
v_cancel_write_off 1868 1868 0 0.00 0 2786 25 0 0
v_max_id_clo_ord 5356 5356 0 0.00 0 4766 5 0 0
v_max_id_clo_res 949 949 0 0.00 0 1470 13 0 0
v_max_id_ord_sup 5392 5392 0 0.00 0 7124 8 0 0
v_max_non_paid_invoice 1395 1395 0 0.00 0 443 7 0 0
v_max_non_paid_realizn 3752 3752 0 0.00 0 137 7 0 0
v_min_id_clo_ord 5356 5356 0 0.00 0 2109 7 0 0
v_min_id_clo_res 949 949 0 0.00 0 7793 22 0 0
v_min_id_ord_sup 5391 5391 0 0.00 0 5119 10 0 0
v_min_non_paid_invoice 5810 5810 0 0.00 0 5725 13 0 0
v_min_non_paid_realizn 6996 6996 0 0.00 0 6388 15 0 0
v_our_firm 10170 10170 0 0.00 0 52 4 0 0
v_reserve_write_off 19557 19557 0 0.00 0 4438 11 0 0
x_lock_depdocs_on_canc_invoice 10123 10123 0 0.00 0 17245 130 0 0
x_lock_depdocs_on_canc_sup_ord 387 387 0 0.00 0 5174 387 0 0
x_make_qstorno_1000_1200 5361 5361 0 0.00 2 31288 1190 0 0
x_make_qstorno_1200_2000 5398 5398 0 0.00 4 83341 3057 0 0
x_make_qstorno_2100_3300 451113 451113 0 0.00 0 70555 233 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-01-2023 10:39:31 to 16-01-2023 10:39:35.

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:30 922 8192000000 8934847728 8991490048 18562176 0.226 5 3 4 0 19033088 155440 315472
08:08:31 26536 8192000000 9726432704 10027376640 807816272 8.975 100 83 166 0 835729680 7428896 20484512
08:10:01 9700 8192000000 9762781168 10068156416 829928864 9.199 100 91 182 0 863694208 12257552 21507792
08:11:18 15868 8192000000 9785230112 10099105792 856180592 9.462 100 91 182 0 896514976 19541584 20792800
08:12:48 7559 8192000000 9853380512 10181980160 896240752 9.861 100 86 170 0 964242288 49518288 18483248
08:14:03 8782 8192000000 9882160688 10224791552 937160368 10.265 100 84 162 1 1005757776 51485520 17111888
08:15:16 1792 8192000000 9945802240 10296311808 982775520 10.711 100 93 179 1 1065264224 64358528 18130176
08:16:22 1634 8192000000 9986866176 10343739392 1005376704 10.931 100 93 178 3 1101921664 78352592 18192368
08:17:26 755 8192000000 9998941648 10364256256 1024304320 11.114 100 92 177 0 1124927584 83280864 17342400
08:18:29 1096 8192000000 10016339680 10388848640 1051310384 11.373 100 95 180 4 1134354112 65863856 17179872
08:19:32 925 8192000000 10021004608 10400432128 1056098528 11.419 100 97 191 3 1143989952 68442672 19448752
08:20:34 1162 8192000000 10037014880 10417512448 1062365840 11.479 100 94 181 1 1158431440 78110272 17955328
08:21:37 2724 8192000000 10048232816 10432061440 1071546352 11.567 100 77 151 0 1168500432 82088576 14865504
08:22:41 5205 8192000000 10036639952 10425540608 1081243184 11.659 100 69 124 3 1161382240 67316528 12822528
08:23:48 1096 8192000000 10054235504 10443669504 1086137568 11.706 100 92 169 0 1177708528 74054688 17516272
08:24:50 949 8192000000 10052836000 10438082560 1091301248 11.755 100 96 182 0 1176054144 65418416 19334480
08:25:52 1013 8192000000 10063640160 10451087360 1090560016 11.748 100 92 173 0 1186051472 78772624 16718832
08:26:54 1313 8192000000 10072687008 10459504640 1094733456 11.788 100 98 186 4 1192509856 77947216 19829184
08:27:56 5441 8192000000 10047633808 10439438336 1094630592 11.787 100 67 123 1 1179361984 72947792 11783600
08:29:03 890 8192000000 10053237376 10444042240 1092341040 11.765 100 92 178 4 1176077584 63858336 19878208
08:30:05 1163 8192000000 10017845136 10402353152 1064992576 11.504 99 93 172 5 1139868688 56760064 18116048
08:31:08 1548 8192000000 10062032448 10454339584 1077919312 11.628 100 87 165 1 1184907024 90759936 16227776
08:32:10 1306 8192000000 10009625696 10387267584 1054944976 11.408 100 94 174 3 1133030608 59823200 18262432
08:33:13 1068 8192000000 10029301248 10408763392 1060595136 11.462 99 95 178 1 1151798464 72912240 18291088
08:34:15 1024 8192000000 10006327552 10379636736 1040741264 11.272 100 98 191 2 1128183504 68728384 18713856
08:35:17 1182 8192000000 9985875888 10356416512 1023436432 11.105 98 95 191 4 1110395616 67380880 19578304
08:36:20 1076 8192000000 9997106288 10370195456 1029124144 11.160 100 94 185 2 1121900512 73245792 19530576
08:37:21 1006 8192000000 10014129776 10390228992 1048166208 11.343 100 91 171 0 1128984480 63417712 17400560
08:38:32 11085 8192000000 10010637040 10396901376 1064028224 11.495 100 63 122 2 1135228992 59161360 12039408
08:39:52 3118 8192000000 10053729616 10434301952 1074522480 11.595 100 77 133 1 1165162896 77848512 12791904
08:41:03 2397 8192000000 10066693536 10454999040 1102355952 11.860 100 76 145 0 1187177392 68936960 15884480
08:42:10 1242 8192000000 10115580992 10499350528 1112217632 11.953 100 83 148 1 1231698656 104772832 14708192
08:43:16 8026 8192000000 10138814272 10538078208 1117820880 12.006 100 67 130 1 1254600224 122284688 14494656
08:44:36 1710 8192000000 10171245248 10568683520 1133232192 12.152 100 77 138 2 1287182352 139076320 14873840
08:45:47 2667 8192000000 10214050352 10612654080 1138114432 12.198 100 74 146 0 1326484096 171948896 16420768
08:46:54 3984 8192000000 10230981216 10642706432 1155375344 12.360 100 77 149 1 1344725568 173186608 16163616
08:48:02 981 8192000000 10272449904 10679554048 1166179120 12.461 100 96 186 0 1389940560 202095248 21666192
08:49:06 2248 8192000000 10296592752 10701066240 1174979408 12.543 100 82 148 0 1403086240 211969808 16137024
08:50:14 3843 8192000000 10315912000 10735054848 1182373088 12.612 100 75 150 0 1428880288 228325104 18182096
08:51:25 1130 8192000000 10395271552 10798198784 1199131280 12.768 100 96 185 0 1509281744 288062224 22088240
08:52:29 1389 8192000000 10403628304 10831998976 1203759328 12.811 100 95 182 1 1519034048 294745728 20528992
08:53:35 1610 8192000000 10422882928 10843811840 1214100880 12.907 100 90 175 1 1530303552 295395408 20807264
08:54:39 1094 8192000000 10423709856 10846277632 1202038384 12.795 100 90 176 2 1538628048 316270208 20319456
08:55:44 872 8192000000 10487237120 10902982656 1209548464 12.865 100 97 190 0 1596205888 364516880 22140544
08:56:48 961 8192000000 10502929360 10924208128 1210888832 12.877 100 97 192 0 1610723040 376453152 23381056
08:57:54 865 8192000000 10556228976 10993266688 1220003184 12.962 100 99 193 1 1665281584 421329600 23948800
08:59:00 966 8192000000 10542651776 11005100032 1204933296 12.822 100 99 195 1 1647147728 418536464 23677968
09:00:06 1208 8192000000 10569706800 11031666688 1215332000 12.918 100 92 184 0 1676616784 439177968 22106816
09:01:10 3630 8192000000 10547789792 10992726016 1209102432 12.861 100 68 131 2 1657688608 432180368 16405808
09:02:41 8409 8192000000 10588969120 11024867328 1192656736 12.708 99 56 111 0 1686261872 479267488 14337648
09:03:53 6365 8192000000 10517062256 10993438720 1192187552 12.704 100 45 89 0 1626953328 422922592 11843184
09:05:06 11709 8192000000 10524512256 10969841664 1186633712 12.652 100 39 78 1 1631232944 434295936 10303296
09:06:20 20779 8192000000 10463746544 10955481088 1176468560 12.557 100 38 76 0 1582677168 396314544 9894064
09:07:43 18896 8192000000 10421037008 10895249408 1171958976 12.515 100 28 55 0 1537296112 358310048 7027088
09:09:06 28053 8192000000 10356485568 10879234048 1140851152 12.224 98 39 75 1 1476252496 325251584 10149760
09:10:40 13282 8192000000 10258171712 10722947072 1149700176 12.307 100 29 58 0 1383732848 227340080 6692592
09:11:55 45752 8192000000 10116945840 10561941504 1083165408 11.678 94 20 38 0 1226585328 138644752 4775168
09:14:00 40321 8192000000 10094713408 10552545280 1118112880 12.009 99 25 49 0 1215666544 92536576 5017088
09:15:43 67309 8192000000 10117033264 10545537024 1101499360 11.852 98 53 94 0 1201607056 89199184 10908512
09:18:26 48634 8192000000 10087199904 10528972800 1108230800 11.916 99 36 67 1 1176143728 60994080 6918848
09:20:17 62242 8192000000 10113566480 10591956992 1135408640 12.172 100 24 48 0 1234047664 93273328 5365696
09:22:29 57792 8192000000 10075681520 10551558144 1106923776 11.903 98 33 57 2 1191547104 78186016 6437312
09:24:28 51913 8192000000 10057571472 10521956352 1109140608 11.924 98 16 32 0 1153319504 40482704 3696192
09:26:20 62023 8192000000 10076728768 10512760832 1111013216 11.942 98 10 20 0 1155662400 42454480 2194704
09:28:23 59507 8192000000 10086866176 10550657024 1130866416 12.130 100 23 39 0 1199504688 64604848 4033424
09:30:32 62376 8192000000 10070933264 10554720256 1133521184 12.155 100 15 28 0 1194916944 58356656 3039104
09:32:34 42568 8192000000 10065541392 10510761984 1106793888 11.902 97 24 46 1 1153034208 41143856 5096464
09:34:20 63519 8192000000 10061208864 10553565184 1129265488 12.114 100 19 38 0 1209720528 76852384 3602656
09:36:24 49684 8192000000 10039737376 10487422976 1074841824 11.598 95 19 31 3 1096657888 18689792 3126272
09:38:15 22606 8192000000 10094028400 10525757440 1118875792 12.016 98 43 85 0 1176853744 50266896 7711056
09:39:38 44156 8192000000 10082785936 10538016768 1117806736 12.006 98 25 50 0 1163321328 40703328 4811264
09:41:29 8657 8192000000 10117903504 10551640064 1135956160 12.177 99 44 88 0 1205942560 61539440 8446960
09:42:39 50361 8192000000 10096919744 10555932672 1126780720 12.091 99 19 37 0 1206665360 76030112 3854528
09:44:30 88217 8192000000 10116099680 10596712448 1130388304 12.125 99 14 28 0 1175102512 41934864 2779344
09:47:02 20588 8192000000 10114193424 10543280128 1145788176 12.270 100 46 90 0 1219283680 63327600 10167904
09:48:25 30072 8192000000 10088246656 10547036160 1131988992 12.140 99 36 72 0 1217084816 78114944 6980880
09:49:59 46966 8192000000 10153543456 10629550080 1145942112 12.271 100 32 64 0 1250245040 98170448 6132480
09:51:55 91922 8192000000 10082520944 10529095680 1139250848 12.208 100 29 56 0 1185904944 41122176 5531920
09:54:28 35331 8192000000 10115717680 10569297920 1151249472 12.321 100 22 44 0 1230045024 74369504 4426048
09:56:04 75525 8192000000 10113047040 10603843584 1133393792 12.153 99 10 20 0 1227104864 91272592 2438480
09:58:21 45847 8192000000 10076884384 10559025152 1112718672 11.958 98 16 32 0 1187568272 71261520 3588080
10:00:30 49535 8192000000 10127046384 10563260416 1113820992 11.969 98 24 47 0 1194418208 75459584 5137632
10:02:20 35071 8192000000 10128104288 10560483328 1108441392 11.918 98 39 75 0 1202536880 86358752 7736736
10:03:59 37207 8192000000 10136766512 10592587776 1107445328 11.908 98 30 60 0 1210407984 96535520 6427136
10:06:06 31830 8192000000 10068268384 10499252224 1120293088 12.030 100 43 84 0 1172897024 44104736 8499200
10:07:39 65937 8192000000 10134240832 10573180928 1110586080 11.938 99 31 62 0 1208122656 91370432 6166144
10:10:10 110993 8192000000 10037849584 10476068864 1097087200 11.810 99 37 74 0 1151427936 46893296 7447440
10:13:05 23175 8192000000 10037367680 10486407168 1116649520 11.995 100 26 51 1 1141456912 19793920 5013472
10:14:29 37423 8192000000 10043227392 10519035904 1103944800 11.875 99 7 14 0 1146689392 41407760 1336832
10:16:07 61478 8192000000 10065721472 10522849280 1076073920 11.610 96 11 23 1 1122773440 43853840 2845680
10:18:35 89898 8192000000 10069101888 10533109760 1083634368 11.682 97 19 38 0 1122918592 35414176 3870048
10:21:07 54102 8192000000 10066606400 10547113984 1100070656 11.838 98 27 54 0 1197495104 91480768 5943680
10:23:01 26465 8192000000 10090371968 10529800192 1116496896 11.994 99 28 56 0 1181063936 58553344 6013696
10:24:28 65071 8192000000 10132295408 10612699136 1125448288 12.078 99 32 65 0 1236249520 104134992 6666240
10:26:53 59864 8192000000 10072748288 10538909696 1127116240 12.094 99 23 46 3 1164570464 32375120 5079104
10:28:55 38514 8192000000 10109178944 10561376256 1131146192 12.132 99 38 74 1 1193770224 54948096 7675936
10:30:37 10175 8192000000 10125162016 10552430592 1137050880 12.188 100 60 118 0 1211532016 63089296 11391840
10:31:50 71302 8192000000 10094323808 10541195264 1116457040 11.994 98 51 102 0 1212804720 85904976 10442704
10:34:10 44447 8192000000 10064555952 10529370112 1126831680 12.091 99 10 18 0 1146894400 18242272 1820448
10:36:01 66114 8192000000 10071552752 10507689984 1074665456 11.597 95 20 34 1 1127611344 49822192 3123696

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 137 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:03, from 16-01-2023 10:39:54 to 16-01-2023 10:39:57.

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          214983
MON$OLDEST_ACTIVE               218105
MON$OLDEST_SNAPSHOT             218105
MON$NEXT_TRANSACTION            218105
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                       2052704
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             773
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 ---
:745: 
:746: mscs-test-161.budzdorov.ru    Mon Jan 16 10:37:28 2023
:747:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:748: 
--- end of diff output ---

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

16.01.23 10:39:58. 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-16 10:40:00 No severe PSQL-related problems occured
16.01.23 10:40:02. End of report.