Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.05.2022 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 16401792 kB
MemAvailable 31375156 kB
Buffers 0 kB
Cached 15276980 kB
SwapCached 2588 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.2
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-05-22 13:37:17
DTS_END                         2022-05-22 13:37:17.6660
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/50326
TRN_ID                          213385
ATT_ID                          589
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: 8910.88 31568 1069305
customer order: creation 153.24 518 18389
customer order: refuse 13.64 392 1637
order to supplier: creation 40.50 1411 4860
order to supplier: removal 3.53 160430 424
invoice (draft): creation 43.07 3052 5168
invoice (draft): removal 51.85 977 6222
invoice accept: apply 39.03 27866 4684
invoice accept: cancel 46.13 1197 5535
customer reserve: creation 3691.02 262 442922
customer reserve: removal 4551.78 98 546214
realization accept: apply 75.62 285 9074
realization accept: cancel 83.65 166 10038
payment from customer: creation 54.18 174 6502
payment from customer: removal 4.97 130 596
payment to supplier: creation 43.94 196 5273
payment to supplier: removal 3.19 126 383
service: total inventory turnovers 3.75 3834 450
service: total monetary turnovers 6.90 364 828
service: aggregate perf. data 0.85 16807 102
service: refresh index statistics 0.03 413069 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 22-05-2022 13:38:24 to 22-05-2022 13:38:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8035
TEST_TIME 2 7095
TEST_TIME 3 6732
TEST_TIME 4 6652
TEST_TIME 5 6901
TEST_TIME 6 7357
TEST_TIME 7 7467
TEST_TIME 8 7177
TEST_TIME 9 6984
TEST_TIME 10 7054
TEST_TIME 11 6841
TEST_TIME 12 6738
TEST_TIME 13 6832
TEST_TIME 14 6935
TEST_TIME 15 7162
TEST_TIME 16 7221
TEST_TIME 17 7509
TEST_TIME 18 7835
TEST_TIME 19 7733
TEST_TIME 20 7718
TEST_TIME 21 8012
TEST_TIME 22 8409
TEST_TIME 23 8530
TEST_TIME 24 8658
TEST_TIME 25 8469
TEST_TIME 26 8842
TEST_TIME 27 9006
TEST_TIME 28 9482
TEST_TIME 29 9552
TEST_TIME 30 10017
TEST_TIME 31 10668
TEST_TIME 32 11137
TEST_TIME 33 10934
TEST_TIME 34 10861
TEST_TIME 35 10800
TEST_TIME 36 10532
TEST_TIME 37 10295
TEST_TIME 38 10231
TEST_TIME 39 10201
TEST_TIME 40 10144
TEST_TIME 41 10158
TEST_TIME 42 10084
TEST_TIME 43 10042
TEST_TIME 44 10036
TEST_TIME 45 9951
TEST_TIME 46 9949
TEST_TIME 47 9907
TEST_TIME 48 9846
TEST_TIME 49 9818
TEST_TIME 50 9867
TEST_TIME 51 10009
TEST_TIME 52 9893
TEST_TIME 53 9884
TEST_TIME 54 9906
TEST_TIME 55 9917
TEST_TIME 56 9882
TEST_TIME 57 9935
TEST_TIME 58 10060
TEST_TIME 59 9940
TEST_TIME 60 9832
TEST_TIME 61 9800
TEST_TIME 62 9708
TEST_TIME 63 9661
TEST_TIME 64 9584
TEST_TIME 65 9538
TEST_TIME 66 9544
TEST_TIME 67 9532
TEST_TIME 68 9473
TEST_TIME 69 9430
TEST_TIME 70 9428
TEST_TIME 71 9425
TEST_TIME 72 9402
TEST_TIME 73 9443
TEST_TIME 74 9406
TEST_TIME 75 9467
TEST_TIME 76 9417
TEST_TIME 77 9396
TEST_TIME 78 9548
TEST_TIME 79 9497
TEST_TIME 80 9520
TEST_TIME 81 9533
TEST_TIME 82 9528
TEST_TIME 83 9470
TEST_TIME 84 9416
TEST_TIME 85 9361
TEST_TIME 86 9313
TEST_TIME 87 9298
TEST_TIME 88 9219
TEST_TIME 89 9174
TEST_TIME 90 9159
TEST_TIME 91 9169
TEST_TIME 92 9188
TEST_TIME 93 9174
TEST_TIME 94 9132
TEST_TIME 95 9137
TEST_TIME 96 9127
TEST_TIME 97 9132
TEST_TIME 98 9174
TEST_TIME 99 9210
TEST_TIME 100 9217
TEST_TIME 101 9192
TEST_TIME 102 9223
TEST_TIME 103 9305
TEST_TIME 104 9279
TEST_TIME 105 9205
TEST_TIME 106 9146
TEST_TIME 107 9120
TEST_TIME 108 9091
TEST_TIME 109 9048
TEST_TIME 110 9053
TEST_TIME 111 9008
TEST_TIME 112 8977
TEST_TIME 113 8928
TEST_TIME 114 8952
TEST_TIME 115 8965
TEST_TIME 116 8956
TEST_TIME 117 8991
TEST_TIME 118 8960
TEST_TIME 119 8975
TEST_TIME 120 8931

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-05-2022 13:38:28 to 22-05-2022 13:38:31.

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 1474084 1474084 0 0.00 0 18681 13 0 0
doc_list_biud 1474084 1474084 0 0.00 0 43457 42 0 0
sp_add_invoice_to_stock 4801 4684 117 2.43 52 208916 27866 0 117
sp_cancel_adding_invoice 5535 5535 0 0.00 7 98706 1198 0 0
sp_cancel_client_order 1637 1637 0 0.00 5 9254 392 0 0
sp_cancel_customer_reserve 546214 546214 0 0.00 1 45640 98 0 0
sp_cancel_pay_from_customer 596 596 0 0.00 4 5868 130 0 0
sp_cancel_pay_to_supplier 383 383 0 0.00 4 4908 126 0 0
sp_cancel_supplier_invoice 6231 6222 9 0.14 4 42775 977 0 9
sp_cancel_supplier_order 424 424 0 0.00 12 1507875 160431 0 0
sp_cancel_write_off 10038 10038 0 0.00 2 16836 167 0 0
sp_client_order 18389 18389 0 0.00 6 36999 518 0 0
sp_customer_reserve 442922 442922 0 0.00 0 62593 263 0 0
sp_fill_shopping_cart 28416 28416 0 0.00 2 19350 77 0 0
sp_fill_shopping_cart_clo_res 442892 442892 0 0.00 0 19258 21 0 0
sp_get_clo_for_invoice 4637 4637 0 0.00 0 24666 334 0 0
sp_kill_cost_storno 16552 16552 0 0.00 0 7870 71 0 0
sp_kill_qstorno_ret_qs2qd 1109112 1109112 0 0.00 0 43428 44 0 0
sp_kill_qty_storno 583779 583779 0 0.00 0 43455 99 0 0
sp_lock_selected_doc 19069 19069 0 0.00 0 8490 8 0 0
sp_make_cost_storno 25484 25484 0 0.00 0 14457 78 0 0
sp_multiply_rows_for_pdistr 25484 25484 0 0.00 0 7882 40 0 0
sp_multiply_rows_for_qdistr 18389 18389 0 0.00 0 10342 206 0 0
sp_pay_from_customer 6502 6502 0 0.00 6 8794 174 0 0
sp_pay_to_supplier 5273 5273 0 0.00 6 12777 197 0 0
sp_payment_common 11775 11775 0 0.00 2 8117 48 0 0
sp_qd_handle_on_cancel_clo 1637 1637 0 0.00 0 9244 322 0 0
sp_qd_handle_on_invoice_upd_sts 10170 10170 0 0.00 0 29846 491 0 0
sp_qd_handle_on_reserve_upd_sts 9074 9074 0 0.00 0 36790 80 0 0
sp_reserve_write_off 9074 9074 0 0.00 8 36875 286 0 0
sp_supplier_invoice 5168 5168 0 0.00 9 88949 3053 0 0
sp_supplier_order 4860 4860 0 0.00 6 38885 1412 0 0
srv_aggregate_perf_data 102 102 0 0.00 3164 227262 16807 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 859 157458 39665 0 0
srv_find_qd_qs_mism 1022561 1022561 0 0.00 0 18680 10 0 0
srv_make_invnt_saldo 450 450 0 0.00 81 80081 3835 0 0
srv_make_money_saldo 828 828 0 0.00 1 12070 365 0 0
srv_recalc_idx_stat 4 4 0 0.00 289157 576464 413069 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4684 4684 0 0.00 1 16167 892 0 0
t$perf-norm:sp_cancel_adding_invoice 358 358 0 0.00 1 9219 468 0 0
t$perf-norm:sp_cancel_client_order 1637 1637 0 0.00 0 1390 26 0 0
t$perf-norm:sp_cancel_customer_reserve 617 617 0 0.00 0 1002 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 596 596 0 0.00 0 574 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 383 383 0 0.00 0 421 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 388 388 0 0.00 0 747 36 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 100986 6090 0 0
t$perf-norm:sp_cancel_write_off 958 958 0 0.00 0 1968 21 0 0
t$perf-norm:sp_client_order 18389 18389 0 0.00 0 5006 32 0 0
t$perf-norm:sp_customer_reserve 891 891 0 0.00 0 1304 12 0 0
t$perf-norm:sp_pay_from_customer 6502 6502 0 0.00 0 12438 22 0 0
t$perf-norm:sp_pay_to_supplier 5273 5273 0 0.00 0 5232 19 0 0
t$perf-norm:sp_reserve_write_off 9074 9074 0 0.00 0 8986 23 0 0
t$perf-norm:sp_supplier_invoice 5168 5168 0 0.00 0 3697 30 0 0
t$perf-norm:sp_supplier_order 4860 4860 0 0.00 0 4181 29 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 968 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 937 25 0 0
t$perf-norm:srv_make_invnt_saldo 450 450 0 0.00 0 879 10 0 0
t$perf-norm:srv_make_money_saldo 828 828 0 0.00 0 826 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 186 50 0 0
v_add_invoice_to_stock 9502 9268 234 2.46 0 8576 27 0 234
v_all_customers 33856 33856 0 0.00 0 69 3 0 0
v_all_suppliers 28050 28050 0 0.00 0 151 4 0 0
v_all_wares 36778 36778 0 0.00 0 150 3 0 0
v_cancel_adding_invoice 716 716 0 0.00 0 371 12 0 0
v_cancel_client_order 3274 3274 0 0.00 0 3919 11 0 0
v_cancel_customer_prepayment 1192 1192 0 0.00 0 2898 16 0 0
v_cancel_customer_reserve 1234 1234 0 0.00 0 2100 12 0 0
v_cancel_payment_to_supplier 766 766 0 0.00 0 301 10 0 0
v_cancel_supplier_invoice 794 776 18 2.26 0 1249 21 0 18
v_cancel_supplier_order 848 848 0 0.00 0 1558 11 0 0
v_cancel_write_off 1916 1916 0 0.00 0 1532 21 0 0
v_max_id_clo_ord 4860 4860 0 0.00 0 159 4 0 0
v_max_id_clo_res 891 891 0 0.00 0 5768 18 0 0
v_max_id_ord_sup 5167 5167 0 0.00 0 4604 8 0 0
v_max_non_paid_invoice 1275 1275 0 0.00 0 283 8 0 0
v_max_non_paid_realizn 3367 3367 0 0.00 0 2872 9 0 0
v_min_id_clo_ord 4860 4860 0 0.00 0 861 7 0 0
v_min_id_clo_res 891 891 0 0.00 0 1523 12 0 0
v_min_id_ord_sup 5167 5167 0 0.00 0 5939 9 0 0
v_min_non_paid_invoice 5273 5273 0 0.00 0 5518 21 0 0
v_min_non_paid_realizn 6502 6502 0 0.00 0 8108 20 0 0
v_our_firm 9192 9192 0 0.00 0 60 4 0 0
v_reserve_write_off 18148 18148 0 0.00 0 5355 10 0 0
x_lock_depdocs_on_canc_invoice 11369 11369 0 0.00 0 20372 138 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 14476 372 0 0
x_make_qstorno_1000_1200 4860 4860 0 0.00 2 38851 1252 0 0
x_make_qstorno_1200_2000 5168 5168 0 0.00 4 84822 2846 0 0
x_make_qstorno_2100_3300 442922 442922 0 0.00 0 62592 230 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-05-2022 13:38:32 to 22-05-2022 13:38:36.

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:21 1 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:21 46568 8192000000 9732547456 10033205248 803119296 8.928 100 92 184 0 834179904 8650368 22410240
11:10:13 27559 8192000000 9773011648 10084737024 846283776 9.363 100 92 184 0 883137920 14711616 22142528
11:11:44 18881 8192000000 9821484448 10144329728 879899472 9.699 100 81 162 0 928779600 30517920 18362208
11:13:06 5169 8192000000 9866697904 10202718208 919960272 10.096 100 74 146 0 989480928 53963088 15557568
11:14:17 8155 8192000000 9915490640 10265030656 957876176 10.468 100 74 146 0 1031356576 58370272 15110128
11:15:27 2296 8192000000 9998592000 10356023296 1008686688 10.963 100 93 184 1 1115256496 89151456 17418352
11:16:31 1061 8192000000 10016317072 10382688256 1035480320 11.221 100 92 177 2 1132840160 80337392 17022448
11:17:34 1216 8192000000 10007403344 10381393920 1053782832 11.397 100 96 188 1 1130489968 56936192 19770944
11:18:36 838 8192000000 10051342464 10426515456 1064584480 11.500 100 95 187 0 1172068880 89044224 18440176
11:19:38 1184 8192000000 10046656528 10430328832 1071914368 11.570 100 92 178 1 1171535376 80862032 18758976
11:20:41 3032 8192000000 10027463008 10408984576 1076442992 11.614 100 78 145 2 1153108768 62352960 14312816
11:21:45 1222 8192000000 10047277824 10436423680 1085328704 11.698 100 77 151 1 1166371616 65933888 15109024
11:22:48 1037 8192000000 10042700000 10429534208 1087633728 11.720 100 97 188 2 1163349424 57036352 18679344
11:23:50 954 8192000000 10050566816 10440953856 1092561664 11.767 100 85 168 2 1177190576 66999520 17629392
11:24:53 1111 8192000000 10090572320 10481176576 1096241584 11.802 100 93 182 1 1209826944 94541824 19043536
11:25:55 1348 8192000000 10062601440 10450022400 1096034720 11.800 100 93 174 2 1182350752 67416928 18899104
11:26:57 1386 8192000000 10037704544 10431627264 1099743984 11.835 100 78 153 0 1160094320 44327744 16022592
11:28:00 1390 8192000000 10041762272 10427047936 1091330432 11.755 100 96 173 4 1167464256 58260000 17873824
11:29:03 1241 8192000000 10040929840 10420838400 1073256016 11.583 99 94 190 1 1161037408 68137504 19643888
11:30:05 1132 8192000000 10018216880 10394644480 1064552624 11.500 100 93 176 3 1135461136 53213840 17694672
11:31:06 5427 8192000000 10031385664 10420080640 1066340672 11.517 100 78 143 4 1155528976 73970016 15218288
11:32:15 6681 8192000000 9999460160 10397417472 1054901760 11.408 99 58 104 0 1129300992 62951952 11447280
11:33:23 950 8192000000 9989577856 10365116416 1047157120 11.333 100 93 172 2 1112292704 45427008 19708576
11:34:25 874 8192000000 9997347280 10380742656 1042376688 11.288 100 83 156 0 1125748480 66101664 17270128
11:35:28 1348 8192000000 9948776464 10321088512 1019350592 11.066 100 79 149 2 1068441376 33910608 15180176
11:36:30 1174 8192000000 10002452304 10383306752 1040553376 11.270 100 72 135 0 1129509312 72959744 15996192
11:37:35 8229 8192000000 9999136576 10378907648 1053148800 11.391 100 74 139 1 1123328816 56586016 13594000
11:38:53 926 8192000000 10047405408 10426695680 1068631424 11.539 100 92 178 0 1164161296 76777056 18752816
11:40:01 14014 8192000000 10025824512 10420469760 1082719152 11.673 100 70 133 3 1149752032 52948496 14084384
11:41:20 1243 8192000000 10095288736 10485747712 1101449232 11.851 100 83 154 1 1216311632 99082016 15780384
11:42:26 866 8192000000 10146308432 10543636480 1111543680 11.947 100 87 162 1 1261745632 131352240 18849712
11:43:30 893 8192000000 10154093088 10548060160 1122777936 12.053 100 99 192 1 1269139520 124847488 21514096
11:44:33 4271 8192000000 10162327008 10566770688 1128032064 12.103 100 92 168 2 1282326848 135223920 19070864
11:45:44 1152 8192000000 10200912000 10595217408 1144381040 12.257 100 82 161 1 1315054496 152447728 18225728
11:46:59 3673 8192000000 10196626608 10603151360 1150489488 12.314 100 72 135 2 1302784720 137490672 14804560
11:48:14 1229 8192000000 10240859824 10645397504 1170350512 12.500 100 91 175 2 1349902864 159082240 20470112
11:49:19 1562 8192000000 10287975696 10692993024 1174178992 12.536 100 90 173 1 1400332208 206014240 20138976
11:50:25 1201 8192000000 10339805872 10749681664 1190898416 12.692 100 95 187 2 1446641232 234310272 21432544
11:51:29 875 8192000000 10376692288 10788241408 1198084576 12.759 100 97 192 1 1485549280 266355488 21109216
11:52:32 879 8192000000 10400255504 10805108736 1191768832 12.700 100 99 198 0 1508694880 294309088 22616960
11:53:36 942 8192000000 10432524752 10838540288 1209740240 12.867 100 96 191 1 1538136656 306196384 22200032
11:54:40 3376 8192000000 10427248192 10864521216 1196125888 12.740 100 81 163 0 1534286976 318768464 19392624
11:55:46 849 8192000000 10439995568 10872930304 1185634880 12.643 100 96 186 0 1548945344 341334656 21975808
11:56:53 1107 8192000000 10471512208 10883416064 1185953488 12.646 100 98 191 0 1574048144 364343392 23751264
11:57:57 1380 8192000000 10464876448 10914033664 1172066288 12.516 100 96 189 0 1568987136 372877168 24043680
11:59:01 926 8192000000 10452490768 10895040512 1176283216 12.556 100 96 187 0 1558388496 358905616 23199664
12:00:03 13783 8192000000 10465820848 10913394688 1178055088 12.572 100 51 102 1 1585566176 394289648 13221440
12:01:20 32675 8192000000 10492070032 10938806272 1186106368 12.647 100 40 80 0 1601533216 404815184 10611664
12:03:18 20381 8192000000 10510246272 11002081280 1177033440 12.563 100 46 91 0 1636030528 447252208 11744880
12:04:40 14515 8192000000 10487340608 10949111808 1175861632 12.552 100 36 72 1 1596721776 412048176 8811968
12:06:06 16915 8192000000 10401614352 10871369728 1158080128 12.385 100 32 63 0 1515801312 349114928 8606256
12:07:26 23981 8192000000 10333775600 10830512128 1152358080 12.332 99 28 57 1 1430725952 271767152 6600720
12:09:15 59499 8192000000 10050499056 10479063040 1094623248 11.787 96 16 32 0 1127507728 30145152 2739328
12:11:15 47207 8192000000 10107918288 10559098880 1134656192 12.165 100 22 44 1 1214097152 74199840 5241120
12:13:07 55943 8192000000 10116003552 10581979136 1139811328 12.214 100 35 63 4 1215518176 67893904 7812944
12:15:15 29330 8192000000 10113281184 10594672640 1126762368 12.091 98 29 57 0 1212089120 79699008 5627744
12:16:46 17784 8192000000 10160973584 10601902080 1152272832 12.331 100 63 123 1 1253625792 87366624 13986336
12:18:07 46173 8192000000 10169553872 10647085056 1154301824 12.350 100 28 56 2 1275427136 115053664 6071648
12:20:07 71734 8192000000 10124051344 10588327936 1095552720 11.795 95 18 36 0 1196578176 97130016 3895440
12:23:49 39982 8192000000 10086147712 10585358336 1115799440 11.987 98 9 18 0 1163932768 46023616 2109712
12:25:30 22583 8192000000 10103953136 10547552256 1139580672 12.212 100 41 82 1 1204009776 55030016 9399088
12:26:58 35144 8192000000 10133982480 10579988480 1105385312 11.889 96 30 60 0 1186397936 74858144 6154480
12:28:41 39243 8192000000 10089525616 10525626368 1124446960 12.069 99 37 69 0 1170997872 39592944 6957968
12:30:21 21103 8192000000 10113006352 10547294208 1123743552 12.062 100 47 92 1 1198963456 65810736 9409168
12:31:43 34305 8192000000 10119489776 10557480960 1138899120 12.205 100 43 79 1 1216032016 69072128 8060768
12:33:23 69534 8192000000 10143464240 10626551808 1115702352 11.986 98 22 44 0 1201177936 80817632 4657952
12:35:36 157458 8192000000 10079810704 10521714688 1124589520 12.070 99 38 76 3 1174393344 41997920 7805904
12:39:19 23264 8192000000 10098873328 10561548288 1125741920 12.081 99 40 80 1 1199009984 65343920 7924144
12:40:43 56483 8192000000 10085776736 10546872320 1090711296 11.749 97 17 34 1 1175470528 81127248 3631984
12:42:41 45048 8192000000 10080670512 10559393792 1113368448 11.964 99 17 33 1 1167558368 50591936 3597984
12:44:26 70406 8192000000 10060525328 10553888768 1121147056 12.038 99 8 16 0 1179160656 56713456 1300144
12:46:53 42837 8192000000 10060715088 10529648640 1114436432 11.974 98 18 34 0 1168841872 51225072 3180368
12:48:36 49102 8192000000 10090707872 10533015552 1127393248 12.097 99 24 48 1 1198467056 66008992 5064816
12:50:26 26072 8192000000 10068237744 10494353408 1114316352 11.973 99 52 101 3 1162246144 36779184 11150608
12:52:06 91105 8192000000 10091485824 10545037312 1109277536 11.926 98 21 42 1 1167343904 53871408 4194960
12:54:38 100317 8192000000 10085352880 10569281536 1129907920 12.120 99 29 58 1 1241431936 105457936 6066080
12:57:19 94028 8192000000 10111171216 10598563840 1100162048 11.839 97 25 50 1 1169582624 64569456 4851120
12:59:54 101602 8192000000 10069356800 10515734528 1076673760 11.616 95 42 84 0 1142238576 57301936 8262880
13:02:36 25435 8192000000 10050294272 10499497984 1113642976 11.967 100 31 60 2 1160574128 40473040 6458112
13:04:17 68037 8192000000 10064324304 10535313408 1128713712 12.109 100 13 26 0 1175376992 43824672 2838608
13:06:26 61208 8192000000 10098648000 10569351168 1129411296 12.116 99 15 30 0 1199764496 67041536 3311664
13:08:28 93027 8192000000 10062598880 10547064832 1103417312 11.870 97 13 26 1 1150153408 43386192 3349904
13:11:02 10952 8192000000 10065551376 10497949696 1120564448 12.032 99 48 96 0 1153915776 23223968 10127360
13:12:24 69549 8192000000 10104129008 10554953728 1101919328 11.856 97 28 56 1 1194683232 86197232 6566672
13:14:46 71544 8192000000 10083063408 10545524736 1051404864 11.374 94 23 46 0 1153519088 97154016 4960208
13:17:05 61658 8192000000 10103088288 10557427712 1119699472 12.024 99 27 54 0 1199932560 74293776 5939312
13:19:07 61884 8192000000 10138973808 10583298048 1072178448 11.573 94 18 35 1 1139254400 63477936 3598016
13:21:13 109033 8192000000 10071432960 10499608576 1055416016 11.413 94 41 81 2 1093819616 30229504 8174096
13:24:46 56921 8192000000 10051156160 10523238400 1099469552 11.833 98 9 18 0 1175326000 73758208 2098240
13:26:43 96032 8192000000 10072192384 10553622528 1115510336 11.985 100 11 23 1 1193023296 74826896 2686064
13:29:19 97872 8192000000 10127178896 10585915392 1116516560 11.994 99 21 42 0 1218666688 97549952 4600176
13:32:09 80494 8192000000 10123273680 10578386944 1114994368 11.980 98 30 60 0 1205755600 84276480 6484752
13:34:35 53596 8192000000 10096514752 10561867776 1118571296 12.013 99 18 36 1 1203848160 81420448 3856416
13:36:29 60684 8192000000 9516394480 9910898688 621397952 7.050 55 14 27 0 670316640 46410928 2507760

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 22-05-2022 13:38:38 to 22-05-2022 13:38:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 117 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-05-2022 13:38:55 to 22-05-2022 13:38: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          213604
MON$OLDEST_ACTIVE               213605
MON$OLDEST_SNAPSHOT             213605
MON$NEXT_TRANSACTION            213605
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                       2035744
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             763
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2767 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2767 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2767 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2767 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 ---
:152: 
:153: mscs-test-161.budzdorov.ru    Sun May 22 13:37:32 2022
:154:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:155: 
:156: 
:157: mscs-test-161.budzdorov.ru    Sun May 22 13:37:48 2022
: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'

22.05.22 13:38: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
2022-05-22 13:39:00 No severe PSQL-related problems occured
22.05.22 13:39:02. End of report.