Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.10.2022 04: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 15261180 kB
MemAvailable 30528700 kB
Buffers 20 kB
Cached 16416232 kB
SwapCached 2200 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 2022-10-09 04:37:23
DTS_END                         2022-10-09 04:37:23.7190
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55728
TRN_ID                          218237
ATT_ID                          576
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: 8755.53 22177 1050663
customer order: creation 169.51 500 20341
customer order: refuse 14.98 406 1797
order to supplier: creation 45.48 1402 5458
order to supplier: removal 3.27 162543 392
invoice (draft): creation 44.43 3334 5331
invoice (draft): removal 47.75 960 5730
invoice accept: apply 40.82 26672 4898
invoice accept: cancel 43.47 1195 5216
customer reserve: creation 3795.69 253 455483
customer reserve: removal 4264.27 98 511713
realization accept: apply 81.52 298 9782
realization accept: cancel 77.03 160 9243
payment from customer: creation 57.95 184 6954
payment from customer: removal 5.44 136 653
payment to supplier: creation 48.38 204 5805
payment to supplier: removal 3.25 149 390
service: total inventory turnovers 4.03 4003 483
service: total monetary turnovers 7.28 348 874
service: aggregate perf. data 0.97 15265 116
service: refresh index statistics 0.03 225423 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 09-10-2022 04:38:50 to 09-10-2022 04:38:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8996
TEST_TIME 2 6644
TEST_TIME 3 6469
TEST_TIME 4 6089
TEST_TIME 5 6320
TEST_TIME 6 6351
TEST_TIME 7 6239
TEST_TIME 8 6597
TEST_TIME 9 7009
TEST_TIME 10 6943
TEST_TIME 11 6882
TEST_TIME 12 7027
TEST_TIME 13 7128
TEST_TIME 14 7279
TEST_TIME 15 7468
TEST_TIME 16 7397
TEST_TIME 17 7433
TEST_TIME 18 7694
TEST_TIME 19 7659
TEST_TIME 20 7621
TEST_TIME 21 7715
TEST_TIME 22 7785
TEST_TIME 23 8026
TEST_TIME 24 8602
TEST_TIME 25 8735
TEST_TIME 26 8846
TEST_TIME 27 9187
TEST_TIME 28 9519
TEST_TIME 29 9701
TEST_TIME 30 9941
TEST_TIME 31 10305
TEST_TIME 32 10947
TEST_TIME 33 10948
TEST_TIME 34 10795
TEST_TIME 35 10689
TEST_TIME 36 10567
TEST_TIME 37 10745
TEST_TIME 38 10479
TEST_TIME 39 10314
TEST_TIME 40 10210
TEST_TIME 41 10065
TEST_TIME 42 9997
TEST_TIME 43 9975
TEST_TIME 44 9885
TEST_TIME 45 9886
TEST_TIME 46 9814
TEST_TIME 47 9734
TEST_TIME 48 9804
TEST_TIME 49 9791
TEST_TIME 50 9719
TEST_TIME 51 9768
TEST_TIME 52 9768
TEST_TIME 53 9682
TEST_TIME 54 9836
TEST_TIME 55 9800
TEST_TIME 56 9892
TEST_TIME 57 9858
TEST_TIME 58 9771
TEST_TIME 59 9834
TEST_TIME 60 9839
TEST_TIME 61 9880
TEST_TIME 62 9871
TEST_TIME 63 9860
TEST_TIME 64 9787
TEST_TIME 65 9663
TEST_TIME 66 9671
TEST_TIME 67 9598
TEST_TIME 68 9535
TEST_TIME 69 9507
TEST_TIME 70 9427
TEST_TIME 71 9345
TEST_TIME 72 9277
TEST_TIME 73 9197
TEST_TIME 74 9243
TEST_TIME 75 9249
TEST_TIME 76 9236
TEST_TIME 77 9189
TEST_TIME 78 9184
TEST_TIME 79 9190
TEST_TIME 80 9162
TEST_TIME 81 9181
TEST_TIME 82 9266
TEST_TIME 83 9301
TEST_TIME 84 9287
TEST_TIME 85 9364
TEST_TIME 86 9372
TEST_TIME 87 9328
TEST_TIME 88 9306
TEST_TIME 89 9230
TEST_TIME 90 9248
TEST_TIME 91 9211
TEST_TIME 92 9150
TEST_TIME 93 9099
TEST_TIME 94 9051
TEST_TIME 95 9016
TEST_TIME 96 8990
TEST_TIME 97 9049
TEST_TIME 98 9014
TEST_TIME 99 9009
TEST_TIME 100 9006
TEST_TIME 101 8972
TEST_TIME 102 9045
TEST_TIME 103 9028
TEST_TIME 104 9056
TEST_TIME 105 9153
TEST_TIME 106 9163
TEST_TIME 107 9169
TEST_TIME 108 9220
TEST_TIME 109 9165
TEST_TIME 110 9108
TEST_TIME 111 9097
TEST_TIME 112 9040
TEST_TIME 113 9010
TEST_TIME 114 8983
TEST_TIME 115 8957
TEST_TIME 116 8907
TEST_TIME 117 8877
TEST_TIME 118 8866
TEST_TIME 119 8837
TEST_TIME 120 8787

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-10-2022 04:38:54 to 09-10-2022 04:38:57.

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 1470015 1470015 0 0.00 0 29610 13 0 0
doc_list_biud 1470011 1470011 0 0.00 0 72204 40 0 0
sp_add_invoice_to_stock 5062 4898 164 3.23 54 183157 26672 0 164
sp_cancel_adding_invoice 5216 5216 0 0.00 6 90007 1196 0 0
sp_cancel_client_order 1797 1797 0 0.00 6 19711 407 0 0
sp_cancel_customer_reserve 511713 511713 0 0.00 1 31422 99 0 0
sp_cancel_pay_from_customer 653 653 0 0.00 4 9290 137 0 0
sp_cancel_pay_to_supplier 390 390 0 0.00 4 9090 150 0 0
sp_cancel_supplier_invoice 5746 5730 16 0.27 3 72343 961 0 16
sp_cancel_supplier_order 392 392 0 0.00 11 1384318 162544 0 0
sp_cancel_write_off 9243 9243 0 0.00 2 12634 161 0 0
sp_client_order 20341 20341 0 0.00 5 19554 501 0 0
sp_customer_reserve 455483 455483 0 0.00 0 40458 254 0 0
sp_fill_shopping_cart 31121 31121 0 0.00 1 26394 86 0 0
sp_fill_shopping_cart_clo_res 455460 455460 0 0.00 0 12614 18 0 0
sp_get_clo_for_invoice 4850 4850 0 0.00 0 20048 280 0 0
sp_kill_cost_storno 15502 15502 0 0.00 0 29589 66 0 0
sp_kill_qstorno_ret_qs2qd 1038791 1038791 0 0.00 0 72201 43 0 0
sp_kill_qty_storno 548719 548719 0 0.00 0 72203 99 0 0
sp_lock_selected_doc 20286 20286 0 0.00 0 9580 8 0 0
sp_make_cost_storno 27388 27388 0 0.00 0 13246 78 0 0
sp_multiply_rows_for_pdistr 27387 27387 0 0.00 0 8965 38 0 0
sp_multiply_rows_for_qdistr 20341 20341 0 0.00 0 15718 208 0 0
sp_pay_from_customer 6954 6954 0 0.00 5 21414 184 0 0
sp_pay_to_supplier 5805 5805 0 0.00 6 19534 204 0 0
sp_payment_common 12758 12758 0 0.00 2 8851 46 0 0
sp_qd_handle_on_cancel_clo 1797 1797 0 0.00 1 7441 308 0 0
sp_qd_handle_on_invoice_upd_sts 10062 10062 0 0.00 0 29764 509 0 0
sp_qd_handle_on_reserve_upd_sts 9782 9782 0 0.00 0 22290 73 0 0
sp_reserve_write_off 9782 9782 0 0.00 8 22339 299 0 0
sp_supplier_invoice 5331 5331 0 0.00 9 101408 3335 0 0
sp_supplier_order 5458 5458 0 0.00 8 56073 1402 0 0
srv_aggregate_perf_data 116 116 0 0.00 2298 154082 15266 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 747 144383 34047 0 0
srv_find_qd_qs_mism 1002473 1002473 0 0.00 0 12523 11 0 0
srv_make_invnt_saldo 483 483 0 0.00 78 74551 4003 0 0
srv_make_money_saldo 874 874 0 0.00 1 15897 348 0 0
srv_recalc_idx_stat 4 4 0 0.00 61888 320124 225424 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 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4904 4904 0 0.00 1 28215 990 0 0
t$perf-norm:sp_cancel_adding_invoice 369 369 0 0.00 1 7018 447 0 0
t$perf-norm:sp_cancel_client_order 1797 1797 0 0.00 0 9954 47 0 0
t$perf-norm:sp_cancel_customer_reserve 687 687 0 0.00 0 17214 58 0 0
t$perf-norm:sp_cancel_pay_from_customer 653 653 0 0.00 0 3321 24 0 0
t$perf-norm:sp_cancel_pay_to_supplier 390 390 0 0.00 0 448 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 1103 30 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 1 103268 7041 0 0
t$perf-norm:sp_cancel_write_off 982 982 0 0.00 0 1053 16 0 0
t$perf-norm:sp_client_order 20341 20341 0 0.00 0 9843 38 0 0
t$perf-norm:sp_customer_reserve 1003 1003 0 0.00 0 839 14 0 0
t$perf-norm:sp_pay_from_customer 6954 6954 0 0.00 0 5763 20 0 0
t$perf-norm:sp_pay_to_supplier 5805 5805 0 0.00 0 15519 23 0 0
t$perf-norm:sp_reserve_write_off 9782 9782 0 0.00 0 6456 24 0 0
t$perf-norm:sp_supplier_invoice 5331 5331 0 0.00 0 9571 33 0 0
t$perf-norm:sp_supplier_order 5458 5458 0 0.00 0 4610 32 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 267 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 5511 86 0 0
t$perf-norm:srv_make_invnt_saldo 483 483 0 0.00 0 1677 17 0 0
t$perf-norm:srv_make_money_saldo 874 874 0 0.00 0 222 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 162 46 0 0
v_add_invoice_to_stock 10016 9688 328 3.27 0 18707 28 0 328
v_all_customers 37082 37082 0 0.00 0 68 4 0 0
v_all_suppliers 30032 30032 0 0.00 0 157 4 0 0
v_all_wares 40678 40678 0 0.00 0 223 4 0 0
v_cancel_adding_invoice 738 738 0 0.00 0 6057 26 0 0
v_cancel_client_order 3594 3594 0 0.00 0 19454 17 0 0
v_cancel_customer_prepayment 1306 1306 0 0.00 0 2667 15 0 0
v_cancel_customer_reserve 1374 1374 0 0.00 1 10695 19 0 0
v_cancel_payment_to_supplier 780 780 0 0.00 0 3958 19 0 0
v_cancel_supplier_invoice 812 780 32 3.94 0 10782 44 0 32
v_cancel_supplier_order 784 784 0 0.00 0 1239 10 0 0
v_cancel_write_off 1964 1964 0 0.00 0 12034 32 0 0
v_max_id_clo_ord 5457 5457 0 0.00 0 615 5 0 0
v_max_id_clo_res 1003 1003 0 0.00 0 2465 21 0 0
v_max_id_ord_sup 5325 5325 0 0.00 0 5084 10 0 0
v_max_non_paid_invoice 1571 1571 0 0.00 0 4321 11 0 0
v_max_non_paid_realizn 3650 3650 0 0.00 0 4562 10 0 0
v_min_id_clo_ord 5457 5457 0 0.00 0 2829 8 0 0
v_min_id_clo_res 1003 1003 0 0.00 0 3724 16 0 0
v_min_id_ord_sup 5325 5325 0 0.00 0 3968 10 0 0
v_min_non_paid_invoice 5805 5805 0 0.00 0 5879 14 0 0
v_min_non_paid_realizn 6953 6953 0 0.00 0 6026 16 0 0
v_our_firm 10202 10202 0 0.00 0 53 5 0 0
v_reserve_write_off 19564 19564 0 0.00 0 21335 16 0 0
x_lock_depdocs_on_canc_invoice 10556 10556 0 0.00 0 20951 118 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 3039 330 0 0
x_make_qstorno_1000_1200 5458 5458 0 0.00 2 56007 1228 0 0
x_make_qstorno_1200_2000 5331 5331 0 0.00 5 101235 3094 0 0
x_make_qstorno_2100_3300 455483 455483 0 0.00 0 38097 223 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-10-2022 04:38:59 to 09-10-2022 04:39:03.

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
02:07:27 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:28 19122 8192000000 9713670240 10008965120 794676320 8.842 100 89 178 0 823983280 7467184 21839776
02:09:52 12290 8192000000 9756820736 10065367040 837561072 9.275 100 87 174 0 869615600 11060096 20994432
02:11:18 16812 8192000000 9807766848 10126061568 870467360 9.605 100 87 174 0 914819168 24288400 20063408
02:12:38 9495 8192000000 9846368048 10175184896 910138304 9.999 100 84 168 0 959584352 30971824 18474224
02:13:55 4348 8192000000 9923929616 10267381760 956647408 10.456 100 88 162 1 1033748720 60331216 16770096
02:15:01 2390 8192000000 9966629504 10319974400 994953376 10.830 100 91 175 0 1088195520 75771536 17470608
02:16:06 1957 8192000000 9975626160 10334994432 1016864032 11.042 100 97 186 0 1094339376 59290752 18184592
02:17:11 1354 8192000000 10044301456 10415730688 1034291344 11.210 100 95 186 1 1157728000 104785744 18650912
02:18:14 1675 8192000000 10015036320 10386944000 1045990608 11.322 100 84 164 1 1136296544 74040400 16265536
02:19:17 1438 8192000000 10030353168 10403381248 1060416048 11.460 100 97 179 2 1151413616 73432304 17565264
02:20:21 1087 8192000000 10052314512 10432024576 1070234784 11.554 100 99 192 0 1178295296 88347232 19713280
02:21:23 1644 8192000000 10048518464 10430128128 1082524880 11.672 100 91 176 1 1177399184 77135136 17739168
02:22:26 824 8192000000 10071203584 10453975040 1087524192 11.719 100 97 180 0 1189164256 85179392 16460672
02:23:29 1105 8192000000 10047377904 10429509632 1090322800 11.746 100 96 189 0 1168806768 59902304 18581664
02:24:30 1188 8192000000 10074248432 10460454912 1093360736 11.775 100 94 185 2 1196897280 85495776 18040768
02:25:32 947 8192000000 10081011984 10474405888 1103029696 11.866 100 79 156 0 1201756592 82734432 15992464
02:26:35 1038 8192000000 10046931632 10433036288 1095294576 11.793 100 95 189 0 1164528416 50477552 18756288
02:27:37 825 8192000000 10057984480 10451681280 1104378288 11.879 100 89 162 1 1181962560 60424256 17160016
02:28:39 1235 8192000000 10060151888 10442862592 1092888400 11.770 100 97 185 0 1182210608 70736432 18585776
02:29:41 1281 8192000000 10056629824 10444140544 1095874240 11.798 100 96 186 2 1181770000 67181392 18714368
02:30:44 1047 8192000000 10046083088 10430484480 1080382288 11.651 100 92 173 2 1168746576 71054912 17309376
02:31:47 1156 8192000000 10059361440 10437947392 1075212672 11.602 100 97 184 0 1179942528 85285344 19444512
02:32:50 1227 8192000000 9985588656 10354212864 1042260096 11.286 100 90 171 2 1106887456 48149424 16477936
02:33:52 1348 8192000000 10014535648 10388910080 1050552160 11.366 100 75 140 3 1130883936 66428992 13902784
02:34:54 1268 8192000000 9992702384 10369011712 1017791936 11.051 99 89 170 5 1114565568 80231296 16542336
02:35:57 989 8192000000 9989684688 10359767040 1022379120 11.095 100 90 174 1 1112560544 72364480 17816944
02:36:59 1103 8192000000 9970837312 10345365504 1033104496 11.198 100 91 167 2 1093180048 43708096 16367456
02:38:07 1205 8192000000 10013679072 10388955136 1055318640 11.412 100 97 188 1 1136569584 61435040 19815904
02:39:16 1541 8192000000 10030755408 10404384768 1069740176 11.550 100 94 171 1 1147728976 60969152 17019648
02:40:28 983 8192000000 10033354832 10414538752 1078106736 11.629 100 83 137 0 1151327152 59488688 13731728
02:41:32 8751 8192000000 10055396336 10442813440 1089568896 11.739 100 61 118 1 1172991296 71573184 11849216
02:42:45 1136 8192000000 10132040000 10521452544 1107226288 11.906 100 92 175 0 1248708896 121941552 19541056
02:43:50 1192 8192000000 10151447312 10547527680 1121875056 12.045 100 99 190 0 1265684080 123294256 20514768
02:44:54 3308 8192000000 10153855616 10561048576 1120694224 12.034 100 68 136 1 1271325088 135793424 14837440
02:46:02 2258 8192000000 10210089360 10618322944 1132820480 12.148 100 93 184 2 1327390272 175478272 19091520
02:47:09 5499 8192000000 10198530048 10595241984 1145567232 12.268 100 82 153 0 1310007216 149037584 15402400
02:48:20 1382 8192000000 10245580784 10637881344 1143272608 12.246 100 84 158 0 1352661200 193031200 16357392
02:49:25 1475 8192000000 10260560400 10659921920 1158810576 12.392 100 84 166 1 1372539856 195876288 17852992
02:50:29 10791 8192000000 10309149872 10719633408 1172823392 12.523 100 75 149 1 1415698896 226183920 16691584
02:51:44 1700 8192000000 10311018528 10740105216 1179625328 12.587 100 99 195 0 1422479136 220921536 21932272
02:52:50 2593 8192000000 10313396864 10729611264 1174227296 12.536 100 86 160 0 1421525280 229151280 18146704
02:53:58 1670 8192000000 10365586624 10791342080 1190148512 12.685 100 85 165 0 1472619376 263652784 18818080
02:55:04 743 8192000000 10404006640 10835197952 1193545648 12.716 100 96 186 0 1510552320 296768224 20238448
02:56:09 1151 8192000000 10431849504 10840346624 1196989504 12.748 100 90 172 0 1540932816 324495728 19447584
02:57:13 932 8192000000 10494558928 10899968000 1195601712 12.735 100 96 188 0 1602885920 384614176 22670032
02:58:17 1113 8192000000 10534079296 10943651840 1198782864 12.765 100 91 179 0 1640515584 420085920 21646800
02:59:20 798 8192000000 10545679296 10965241856 1184906720 12.636 100 96 188 1 1650703392 442735392 23061280
03:00:23 1231 8192000000 10520094128 11006455808 1186544208 12.651 100 96 185 2 1624591360 415452384 22594768
03:01:27 1077 8192000000 10472781904 10908442624 1170295872 12.500 100 96 188 1 1580244080 387959024 21989184
03:02:29 7670 8192000000 10488399856 10924285952 1170768976 12.504 100 62 119 1 1584917024 399220224 14927824
03:03:44 3941 8192000000 10438417088 10897829888 1158090352 12.385 100 63 120 0 1548984544 375224112 15670080
03:04:50 15023 8192000000 10412074736 10874011648 1150137552 12.311 99 45 87 1 1521828864 360277200 11414112
03:06:06 14288 8192000000 10350411520 10846629888 1141319008 12.228 100 31 60 1 1459171264 309900800 7951456
03:07:22 41426 8192000000 10302995936 10759581696 1127479920 12.098 99 24 49 0 1410170064 276765520 5924624
03:09:05 34904 8192000000 10150636256 10654703616 1121265872 12.039 99 21 42 0 1290345616 164697184 4382560
03:10:42 9879 8192000000 10059589616 10474811392 1125287920 12.077 100 62 114 2 1158953920 22743312 10922688
03:11:55 49571 8192000000 10141396896 10568159232 1139897840 12.215 100 28 56 0 1235626352 90013280 5715232
03:13:48 62558 8192000000 10048425904 10508451840 1073658864 11.587 95 15 29 1 1145701504 68978992 3063648
03:16:02 64950 8192000000 10072871952 10532585472 1116413136 11.993 99 42 84 0 1183830448 57327776 10089536
03:18:10 64759 8192000000 10093077600 10530869248 1122676656 12.052 99 33 66 0 1195497520 66146912 6673952
03:20:18 36747 8192000000 10143692208 10575740928 1148506368 12.295 100 45 85 1 1223249024 65924816 8817840
03:22:07 32255 8192000000 10161958400 10634555392 1145314624 12.265 100 34 67 0 1287044224 134499520 7230080
03:23:42 27623 8192000000 10177773152 10645827584 1144582624 12.259 100 17 35 0 1279562784 130793968 4186192
03:26:39 59015 8192000000 10102814032 10627887104 1124965712 12.074 99 13 26 0 1209767296 82032352 2769232
03:28:46 32076 8192000000 10120186656 10573529088 1126478048 12.088 99 13 25 0 1187708832 58785456 2445328
03:30:25 62412 8192000000 10129881296 10633199616 1142588368 12.240 100 15 30 0 1241899392 95741312 3569712
03:32:27 73877 8192000000 10104313776 10569797632 1132681424 12.147 99 29 56 0 1202806208 64653376 5471408
03:34:46 48203 8192000000 10106784272 10563837952 1138196320 12.199 100 26 52 0 1190124432 46334528 5593584
03:36:34 28145 8192000000 10126390880 10587709440 1134776192 12.166 99 33 64 0 1192745040 51773216 6195632
03:38:04 73227 8192000000 10091346064 10521284608 1107397520 11.908 96 18 36 1 1183212496 72482272 3332704
03:40:17 55197 8192000000 10136127408 10590617600 1140518272 12.220 99 33 67 1 1207029072 60017360 6493440
03:42:13 102246 8192000000 10111403360 10592399360 1134102640 12.160 98 45 88 1 1202214416 59365552 8746224
03:44:55 56948 8192000000 10094434896 10560675840 1143276592 12.246 99 15 31 0 1211332320 64847504 3208224
03:46:53 74232 8192000000 10086058496 10577870848 1104209760 11.878 96 7 14 0 1150781504 45139168 1432576
03:49:27 80135 8192000000 10129044224 10595352576 1127383584 12.097 99 14 24 2 1195633376 65529584 2720208
03:51:53 95806 8192000000 10098767520 10586939392 1130050992 12.122 99 14 28 0 1194899680 61966048 2882640
03:54:29 44551 8192000000 10115667472 10570665984 1106546784 11.900 97 25 50 1 1184077264 71862128 5668352
03:56:18 44651 8192000000 10120059072 10557743104 1132403040 12.144 99 36 72 0 1216865104 76893472 7568592
03:58:15 55437 8192000000 10138068528 10591059968 1102127152 11.858 98 24 48 0 1223401936 116157088 5117696
04:00:26 16339 8192000000 10062536176 10517557248 1096950960 11.809 99 52 96 1 1149989056 42822080 10216016
04:01:44 15737 8192000000 10131175664 10553274368 1115799360 11.987 99 53 105 0 1210996992 85011648 10185984
04:03:01 48016 8192000000 10094527792 10539524096 1102038304 11.857 99 26 52 0 1167908336 60789264 5080768
04:06:46 30633 8192000000 10088947888 10525458432 1125057136 12.075 100 34 68 2 1171828032 39804928 6965968
04:08:20 55326 8192000000 10044519536 10497019904 1102321216 11.860 97 25 50 1 1133885104 26912848 4651040
04:10:23 81954 8192000000 10072747072 10550435840 1108809216 11.921 98 8 16 0 1163505024 53146224 1549584
04:12:46 29057 8192000000 10069269824 10513264640 1097491264 11.814 97 27 50 1 1154715840 51727872 5496704
04:14:16 51368 8192000000 10090366384 10534809600 1120871024 12.035 99 33 64 0 1205116080 77244400 7000656
04:16:08 29183 8192000000 10149170864 10588545024 1145468912 12.267 100 33 66 0 1249522048 96805904 7247232
04:17:41 21058 8192000000 10167024432 10609524736 1138062160 12.197 99 38 74 0 1253179664 107352144 7765360
04:19:11 37637 8192000000 10154416048 10605887488 1116088032 11.990 98 43 86 0 1273979424 148445040 9446352
04:20:49 44061 8192000000 10168731392 10604347392 1117607136 12.004 98 39 76 1 1250937952 125314512 8016304
04:22:34 35622 8192000000 10136917504 10621128704 1135603360 12.174 100 36 72 0 1271148992 127408064 8137568
04:24:19 28015 8192000000 10141782560 10574319616 1134389456 12.163 99 46 85 0 1200354192 57465328 8499408
04:25:47 144383 8192000000 10098547632 10581176320 1124474704 12.069 99 27 54 0 1212050512 81627648 5948160
04:29:12 46451 8192000000 10060066800 10511826944 1109768064 11.930 98 6 12 0 1153593504 42507440 1318000
04:31:00 59584 8192000000 10049407728 10514219008 1105070496 11.886 98 6 12 0 1127510928 20832640 1607792
04:33:00 71094 8192000000 10063009760 10524327936 1123730048 12.062 99 15 31 0 1157516448 31168352 2618048
04:35:14 67248 8192000000 10067515888 10519298048 1111834064 11.950 98 15 30 0 1150252224 34985472 3432688

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 09-10-2022 04:39:05 to 09-10-2022 04:39:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 164 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-10-2022 04:39:22 to 09-10-2022 04:39:24.

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          215963
MON$OLDEST_ACTIVE               218371
MON$OLDEST_SNAPSHOT             218371
MON$NEXT_TRANSACTION            218371
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                       2055200
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             770
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2856 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2856 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2856 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2856 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 ---
:33: 
:34: mscs-test-161.budzdorov.ru    Sun Oct  9 04:37:29 2022
:35:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:36: 
--- end of diff output ---

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

09.10.22 04:39:25. 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
2022-10-09 04:39:28 No severe PSQL-related problems occured
09.10.22 04:39:29. End of report.