Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.10.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 15116224 kB
MemAvailable 30439736 kB
Buffers 0 kB
Cached 16571784 kB
SwapCached 2184 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-24 13:37:22
DTS_END                         2022-10-24 13:37:22.1210
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/35416
TRN_ID                          224704
ATT_ID                          658
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: 8878.65 22480 1065438
customer order: creation 177.38 479 21285
customer order: refuse 15.33 356 1840
order to supplier: creation 47.45 1211 5694
order to supplier: removal 3.39 164939 407
invoice (draft): creation 46.53 2800 5583
invoice (draft): removal 48.31 997 5797
invoice accept: apply 42.48 26805 5098
invoice accept: cancel 43.00 1344 5160
customer reserve: creation 3942.46 256 473095
customer reserve: removal 4214.46 106 505735
realization accept: apply 85.09 262 10211
realization accept: cancel 81.68 175 9802
payment from customer: creation 59.24 195 7109
payment from customer: removal 5.76 101 691
payment to supplier: creation 49.43 196 5931
payment to supplier: removal 3.38 118 405
service: total inventory turnovers 4.45 3594 534
service: total monetary turnovers 7.73 360 928
service: aggregate perf. data 1.07 12336 128
service: refresh index statistics 0.04 232968 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 24-10-2022 13:38:33 to 24-10-2022 13:38:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8600
TEST_TIME 2 7181
TEST_TIME 3 6780
TEST_TIME 4 7188
TEST_TIME 5 6679
TEST_TIME 6 7479
TEST_TIME 7 7226
TEST_TIME 8 7068
TEST_TIME 9 7033
TEST_TIME 10 6958
TEST_TIME 11 7108
TEST_TIME 12 7265
TEST_TIME 13 7408
TEST_TIME 14 7654
TEST_TIME 15 7536
TEST_TIME 16 7691
TEST_TIME 17 7943
TEST_TIME 18 8035
TEST_TIME 19 8319
TEST_TIME 20 8561
TEST_TIME 21 8574
TEST_TIME 22 8588
TEST_TIME 23 8504
TEST_TIME 24 8599
TEST_TIME 25 8572
TEST_TIME 26 8735
TEST_TIME 27 9026
TEST_TIME 28 9346
TEST_TIME 29 9669
TEST_TIME 30 10036
TEST_TIME 31 10669
TEST_TIME 32 10867
TEST_TIME 33 11452
TEST_TIME 34 11499
TEST_TIME 35 11191
TEST_TIME 36 10947
TEST_TIME 37 10739
TEST_TIME 38 10608
TEST_TIME 39 10452
TEST_TIME 40 10387
TEST_TIME 41 10342
TEST_TIME 42 10215
TEST_TIME 43 10206
TEST_TIME 44 10095
TEST_TIME 45 9998
TEST_TIME 46 9871
TEST_TIME 47 9701
TEST_TIME 48 9781
TEST_TIME 49 9666
TEST_TIME 50 9527
TEST_TIME 51 9705
TEST_TIME 52 9638
TEST_TIME 53 9609
TEST_TIME 54 9619
TEST_TIME 55 9632
TEST_TIME 56 9542
TEST_TIME 57 9521
TEST_TIME 58 9605
TEST_TIME 59 9617
TEST_TIME 60 9644
TEST_TIME 61 9643
TEST_TIME 62 9568
TEST_TIME 63 9685
TEST_TIME 64 9649
TEST_TIME 65 9528
TEST_TIME 66 9488
TEST_TIME 67 9441
TEST_TIME 68 9409
TEST_TIME 69 9351
TEST_TIME 70 9352
TEST_TIME 71 9309
TEST_TIME 72 9277
TEST_TIME 73 9236
TEST_TIME 74 9203
TEST_TIME 75 9278
TEST_TIME 76 9267
TEST_TIME 77 9355
TEST_TIME 78 9256
TEST_TIME 79 9471
TEST_TIME 80 9527
TEST_TIME 81 9510
TEST_TIME 82 9469
TEST_TIME 83 9389
TEST_TIME 84 9296
TEST_TIME 85 9237
TEST_TIME 86 9330
TEST_TIME 87 9294
TEST_TIME 88 9232
TEST_TIME 89 9183
TEST_TIME 90 9154
TEST_TIME 91 9103
TEST_TIME 92 9063
TEST_TIME 93 9089
TEST_TIME 94 9066
TEST_TIME 95 9124
TEST_TIME 96 9123
TEST_TIME 97 9145
TEST_TIME 98 9166
TEST_TIME 99 9167
TEST_TIME 100 9169
TEST_TIME 101 9165
TEST_TIME 102 9177
TEST_TIME 103 9242
TEST_TIME 104 9187
TEST_TIME 105 9110
TEST_TIME 106 9127
TEST_TIME 107 9066
TEST_TIME 108 9060
TEST_TIME 109 9034
TEST_TIME 110 9017
TEST_TIME 111 8989
TEST_TIME 112 8975
TEST_TIME 113 8951
TEST_TIME 114 8946
TEST_TIME 115 8966
TEST_TIME 116 8932
TEST_TIME 117 8921
TEST_TIME 118 8943
TEST_TIME 119 8923
TEST_TIME 120 8906

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-10-2022 13:38:37 to 24-10-2022 13:38:40.

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 1501094 1501094 0 0.00 0 30262 14 0 0
doc_list_biud 1501083 1501083 0 0.00 0 32273 41 0 0
sp_add_invoice_to_stock 5153 5098 55 1.06 58 232195 26805 0 55
sp_cancel_adding_invoice 5160 5160 0 0.00 6 84813 1344 0 0
sp_cancel_client_order 1840 1840 0 0.00 6 11622 356 0 0
sp_cancel_customer_reserve 505735 505735 0 0.00 1 32275 106 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 3 6405 102 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 4 4806 119 0 0
sp_cancel_supplier_invoice 5799 5797 2 0.03 3 24628 998 0 2
sp_cancel_supplier_order 407 407 0 0.00 7 1557317 164939 0 0
sp_cancel_write_off 9802 9802 0 0.00 2 25518 175 0 0
sp_client_order 21285 21285 0 0.00 5 27283 479 0 0
sp_customer_reserve 473095 473095 0 0.00 0 143918 256 0 0
sp_fill_shopping_cart 32552 32552 0 0.00 1 19941 80 0 0
sp_fill_shopping_cart_clo_res 473065 473065 0 0.00 0 21995 21 0 0
sp_get_clo_for_invoice 5054 5054 0 0.00 0 19235 290 0 0
sp_kill_cost_storno 16058 16058 0 0.00 0 22642 69 0 0
sp_kill_qstorno_ret_qs2qd 1027476 1027476 0 0.00 0 32245 46 0 0
sp_kill_qty_storno 544005 544005 0 0.00 0 32273 105 0 0
sp_lock_selected_doc 21089 21089 0 0.00 0 6518 7 0 0
sp_make_cost_storno 28305 28305 0 0.00 0 20592 79 0 0
sp_multiply_rows_for_pdistr 28304 28304 0 0.00 0 30250 40 0 0
sp_multiply_rows_for_qdistr 21285 21285 0 0.00 0 9467 212 0 0
sp_pay_from_customer 7109 7109 0 0.00 5 30268 195 0 0
sp_pay_to_supplier 5931 5931 0 0.00 7 16691 197 0 0
sp_payment_common 13040 13040 0 0.00 2 9410 51 0 0
sp_qd_handle_on_cancel_clo 1840 1840 0 0.00 1 11604 292 0 0
sp_qd_handle_on_invoice_upd_sts 10213 10213 0 0.00 0 26921 517 0 0
sp_qd_handle_on_reserve_upd_sts 10210 10210 0 0.00 0 19946 57 0 0
sp_reserve_write_off 10211 10211 0 0.00 7 21637 263 0 0
sp_supplier_invoice 5583 5583 0 0.00 9 79210 2800 0 0
sp_supplier_order 5694 5694 0 0.00 8 25766 1211 0 0
srv_aggregate_perf_data 128 128 0 0.00 2052 249239 12337 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 711 134984 35909 0 0
srv_find_qd_qs_mism 1015487 1015487 0 0.00 0 14044 11 0 0
srv_make_invnt_saldo 534 534 0 0.00 53 148119 3595 0 0
srv_make_money_saldo 928 928 0 0.00 1 31312 361 0 0
srv_recalc_idx_stat 5 5 0 0.00 67787 491613 232968 0 0
t$perf-abend:sp_add_invoice_to_stock 55 55 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 2 2 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5104 5104 0 0.00 1 10053 921 0 0
t$perf-norm:sp_cancel_adding_invoice 424 424 0 0.00 2 8261 514 0 0
t$perf-norm:sp_cancel_client_order 1840 1840 0 0.00 0 4675 25 0 0
t$perf-norm:sp_cancel_customer_reserve 684 684 0 0.00 0 2297 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 3485 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 631 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 412 412 0 0.00 0 2208 48 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 81227 5941 0 0
t$perf-norm:sp_cancel_write_off 964 964 0 0.00 0 994 19 0 0
t$perf-norm:sp_client_order 21285 21285 0 0.00 0 4825 34 0 0
t$perf-norm:sp_customer_reserve 1045 1045 0 0.00 0 5394 20 0 0
t$perf-norm:sp_pay_from_customer 7109 7109 0 0.00 0 6228 18 0 0
t$perf-norm:sp_pay_to_supplier 5931 5931 0 0.00 0 6315 21 0 0
t$perf-norm:sp_reserve_write_off 10211 10211 0 0.00 0 5863 22 0 0
t$perf-norm:sp_supplier_invoice 5583 5583 0 0.00 0 2858 26 0 0
t$perf-norm:sp_supplier_order 5694 5694 0 0.00 0 3109 27 0 0
t$perf-norm:srv_aggregate_perf_data 128 128 0 0.00 0 513 30 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 929 22 0 0
t$perf-norm:srv_make_invnt_saldo 534 534 0 0.00 0 409 9 0 0
t$perf-norm:srv_make_money_saldo 928 928 0 0.00 0 2236 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 273 70 0 0
v_add_invoice_to_stock 10214 10104 110 1.07 0 4778 23 0 110
v_all_customers 39122 39122 0 0.00 0 66 4 0 0
v_all_suppliers 31488 31488 0 0.00 0 225 4 0 0
v_all_wares 42568 42568 0 0.00 0 162 4 0 0
v_cancel_adding_invoice 848 848 0 0.00 0 4049 20 0 0
v_cancel_client_order 3680 3680 0 0.00 0 1649 8 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 1119 10 0 0
v_cancel_customer_reserve 1368 1368 0 0.00 0 4092 15 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 634 12 0 0
v_cancel_supplier_invoice 828 824 4 0.48 0 1117 22 0 4
v_cancel_supplier_order 814 814 0 0.00 0 1214 13 0 0
v_cancel_write_off 1928 1928 0 0.00 0 2422 28 0 0
v_max_id_clo_ord 5692 5692 0 0.00 0 248 5 0 0
v_max_id_clo_res 1044 1044 0 0.00 0 2047 22 0 0
v_max_id_ord_sup 5575 5575 0 0.00 0 3376 8 0 0
v_max_non_paid_invoice 1454 1454 0 0.00 0 292 8 0 0
v_max_non_paid_realizn 3608 3608 0 0.00 0 2946 8 0 0
v_min_id_clo_ord 5692 5692 0 0.00 0 3328 8 0 0
v_min_id_clo_res 1044 1044 0 0.00 0 2809 14 0 0
v_min_id_ord_sup 5575 5575 0 0.00 0 7025 9 0 0
v_min_non_paid_invoice 5931 5931 0 0.00 0 9387 24 0 0
v_min_non_paid_realizn 7109 7109 0 0.00 0 4333 20 0 0
v_our_firm 10448 10448 0 0.00 0 63 5 0 0
v_reserve_write_off 20420 20420 0 0.00 0 3803 10 0 0
x_lock_depdocs_on_canc_invoice 10545 10545 0 0.00 0 16819 145 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 4535 348 0 0
x_make_qstorno_1000_1200 5694 5694 0 0.00 2 25736 1052 0 0
x_make_qstorno_1200_2000 5583 5583 0 0.00 4 73706 2579 0 0
x_make_qstorno_2100_3300 473095 473095 0 0.00 0 143888 224 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-10-2022 13:38:42 to 24-10-2022 13:38:46.

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:26 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:27 28130 8192000000 9720302512 10019790848 799268128 8.889 100 88 177 0 828101520 7559312 21274080
11:09:58 22937 8192000000 9767613952 10075676672 837837792 9.278 100 84 168 0 869587504 12072816 19676896
11:11:32 11646 8192000000 9809559264 10129453056 878053248 9.680 100 84 169 0 918211472 20830000 19328224
11:12:46 7411 8192000000 9865165392 10197700608 917412944 10.071 100 67 132 0 965037088 33259616 14364528
11:13:57 2774 8192000000 9916392880 10257096704 954898656 10.439 100 87 170 0 1023123456 49634160 18590640
11:15:04 1920 8192000000 9966232128 10318913536 995035728 10.830 100 87 166 3 1088039360 76327360 16676272
11:16:08 913 8192000000 10005343856 10363695104 1021323776 11.085 100 96 190 0 1128722688 88916368 18482544
11:17:11 1105 8192000000 10006393632 10376572928 1035907168 11.225 100 94 178 1 1126525968 72144320 18474480
11:18:15 1140 8192000000 10007366320 10382045184 1054538192 11.404 100 92 164 1 1127976208 55912976 17525040
11:19:17 920 8192000000 10014732928 10392776704 1062333424 11.479 100 95 170 0 1137277904 56970896 17973584
11:20:20 1020 8192000000 10036284592 10420105216 1067780896 11.531 100 97 189 3 1161252416 74341360 19130160
11:21:23 1213 8192000000 10057868848 10440155136 1076379680 11.613 100 94 184 0 1179367344 84287184 18700480
11:22:24 917 8192000000 10046795936 10435047424 1084373392 11.689 100 85 165 2 1168843744 68152688 16317664
11:23:27 907 8192000000 10044457632 10429816832 1088995408 11.733 100 87 165 0 1161795264 55874752 16925104
11:24:28 1162 8192000000 10065271744 10455076864 1093445344 11.775 100 95 185 2 1185718048 73551952 18720752
11:25:30 1100 8192000000 10040587792 10431172608 1095027840 11.790 100 84 160 1 1162496432 51567344 15901248
11:26:32 3816 8192000000 10060983904 10460602368 1094937712 11.790 100 72 140 0 1180434032 71780576 13715744
11:27:37 1754 8192000000 10044709264 10433695744 1095509168 11.795 100 76 151 1 1165964032 54474048 15980816
11:28:41 1136 8192000000 10061665376 10453544960 1090475008 11.747 100 85 159 3 1182545552 75892464 16178080
11:29:42 1212 8192000000 10019043920 10401271808 1060684096 11.463 99 93 176 3 1139043808 59446416 18913296
11:30:44 2548 8192000000 10002736112 10385694720 1071104112 11.563 100 78 146 1 1124963136 39660208 14198816
11:31:48 1000 8192000000 9972179552 10350084096 1037405952 11.240 99 92 180 3 1099648208 43187056 19055200
11:32:49 2321 8192000000 9973306384 10349416448 1024408256 11.115 98 66 113 4 1096099488 59027328 12663904
11:33:53 1121 8192000000 9979543328 10349756416 1028054832 11.150 100 96 180 1 1101425168 54292832 19077504
11:34:54 1896 8192000000 9956183328 10329194496 1029916480 11.168 99 67 122 5 1070505360 27716624 12872256
11:35:57 963 8192000000 9974276336 10348052480 1033308512 11.200 100 91 174 1 1092194592 39933472 18952608
11:36:58 1020 8192000000 9973554976 10351112192 1033452208 11.202 100 83 155 1 1094723232 45340496 15930528
11:38:02 1877 8192000000 10030100352 10404347904 1057455408 11.432 100 84 152 0 1135689840 63376848 14857584
11:39:07 10428 8192000000 10042388576 10424074240 1077529088 11.624 100 74 148 0 1160799552 67989952 15280512
11:40:22 7800 8192000000 10062100592 10460680192 1091720480 11.759 100 76 144 2 1188747856 81628704 15398672
11:41:36 11940 8192000000 10093227504 10492788736 1098644368 11.825 100 65 130 2 1217408544 105623104 13141072
11:42:53 2744 8192000000 10103285888 10510467072 1106478608 11.899 100 71 135 0 1218190976 96851056 14861312
11:44:06 1728 8192000000 10117404384 10508726272 1126582464 12.089 100 89 172 1 1235896848 89705840 19608544
11:45:11 1226 8192000000 10147373168 10543378432 1133817920 12.157 100 95 175 1 1256420656 104568560 18034176
11:46:15 3083 8192000000 10204696016 10617876480 1149075776 12.301 100 64 126 0 1320945776 158008928 13861072
11:47:22 1658 8192000000 10230349488 10633728000 1156580368 12.371 100 87 154 4 1342386720 167308208 18498144
11:48:30 971 8192000000 10271001344 10678157312 1170099536 12.498 100 82 159 0 1388142128 200050928 17991664
11:49:34 946 8192000000 10308635952 10714091520 1178199344 12.573 100 89 168 1 1416862576 219642112 19021120
11:50:38 1060 8192000000 10364464128 10779283456 1183800992 12.626 100 95 177 4 1479189312 273774688 21613632
11:51:42 876 8192000000 10377529872 10790600704 1188651312 12.671 100 96 187 2 1485507568 275685536 21170720
11:52:46 778 8192000000 10393631792 10800435200 1195850432 12.738 100 95 193 0 1502022336 283848336 22323568
11:53:50 1631 8192000000 10398003472 10820595712 1188564112 12.670 100 83 162 0 1507265904 299938608 18763184
11:54:55 863 8192000000 10404579440 10836041728 1190464608 12.688 100 90 174 0 1511071248 298845392 21761248
11:55:58 1212 8192000000 10432127072 10868613120 1195718256 12.737 100 91 174 1 1537667424 320866832 21082336
11:57:02 939 8192000000 10416635232 10861740032 1202824096 12.803 100 98 186 1 1523993392 299451168 21718128
11:58:05 968 8192000000 10484486384 10894925824 1210554512 12.874 100 96 188 0 1589180176 357368416 21257248
11:59:08 1129 8192000000 10487116816 10892296192 1201191808 12.787 99 92 181 1 1591500112 369128128 21180176
12:00:12 1877 8192000000 10524258688 10929893376 1192509216 12.707 100 94 183 2 1623728688 409564944 21654528
12:01:17 704 8192000000 10578296800 11012087808 1203777376 12.811 100 92 174 0 1679874096 455243056 20853664
12:02:20 2514 8192000000 10608041760 11040890880 1202172512 12.797 100 74 148 0 1716893616 495871328 18849776
12:03:36 9574 8192000000 10582517328 11046621184 1195202880 12.732 100 45 88 1 1684080704 476673760 12204064
12:04:56 19053 8192000000 10582540896 11059503104 1180609632 12.596 100 45 90 0 1691481168 499057216 11814320
12:06:21 19953 8192000000 10477860256 10983657472 1162614448 12.428 100 29 56 0 1594276512 423815408 7846656
12:07:43 31583 8192000000 10317607584 10840985600 1146034512 12.272 100 23 46 0 1461788080 309841840 5911728
12:09:16 134983 8192000000 10066670672 10560438272 1086233376 11.707 94 15 30 0 1285630176 196078784 3318016
12:12:31 30988 8192000000 10055950336 10491944960 1125876784 12.082 99 58 112 1 1150776400 11913872 12985744
12:14:22 16138 8192000000 10075241376 10517049344 1130791744 12.129 99 45 89 0 1168505584 28763776 8950064
12:15:39 46322 8192000000 10105707488 10549407744 1139717568 12.213 100 36 72 0 1211574224 64465360 7391296
12:17:26 33594 8192000000 10072125904 10524790784 1123642864 12.061 98 18 36 0 1168892288 41694048 3555376
12:19:33 97283 8192000000 10062014000 10549424128 1103403792 11.870 97 16 30 0 1154760976 48211728 3145456
12:22:11 24406 8192000000 10088977824 10537885696 1137608976 12.193 100 14 29 0 1190926208 50470272 2846960
12:23:43 108428 8192000000 10099839072 10614005760 1124994496 12.074 99 14 28 0 1202453520 74255712 3203312
12:26:37 86705 8192000000 10136985840 10616995840 1133390896 12.153 99 13 26 0 1203774496 67596416 2787184
12:29:04 63074 8192000000 10100027344 10558267392 1142098368 12.235 100 16 31 0 1216211904 70535680 3577856
12:31:10 45736 8192000000 10087853328 10545618944 1138430208 12.201 100 24 46 0 1189501712 46192208 4879296
12:33:03 25470 8192000000 10074797312 10505506816 1142375536 12.238 100 46 90 0 1173463408 21780848 9307024
12:34:30 111776 8192000000 10099103392 10600980480 1096636896 11.806 96 18 36 0 1188231360 87816320 3778144
12:37:22 94764 8192000000 10079165808 10514812928 1133562224 12.155 99 23 42 0 1167117952 29548720 4007008
12:39:58 82499 8192000000 10082506192 10579554304 1128153792 12.104 98 28 56 0 1213443792 79949744 5340256
12:42:27 50648 8192000000 10053986400 10484240384 1082761520 11.674 95 47 91 1 1124647936 32039024 9847392
12:44:18 61927 8192000000 10062238256 10544144384 1105329920 11.888 98 8 16 0 1166115856 59207568 1578368
12:46:21 33363 8192000000 10065049920 10510954496 1129314096 12.115 100 22 44 0 1156760160 22783584 4662480
12:47:54 38239 8192000000 10086974016 10535878656 1120427248 12.031 98 28 54 1 1182917520 56976048 5514224
12:49:33 35207 8192000000 10130791120 10568818688 1127706352 12.100 98 28 56 0 1206044880 71887760 6450768
12:51:12 19021 8192000000 10181605248 10631163904 1159844208 12.402 100 42 82 0 1280140464 111313280 8982976
12:52:33 18678 8192000000 10188603792 10625519616 1157528992 12.380 100 38 76 0 1276167392 109697856 8940544
12:53:54 64327 8192000000 10182993232 10684395520 1146042144 12.272 99 22 44 0 1312203552 160980272 5181136
12:55:59 22335 8192000000 10233574752 10679484416 1130645872 12.127 99 50 100 1 1305567440 164073216 10848352
12:57:21 114701 8192000000 10070014016 10528653312 1069897136 11.551 94 19 33 0 1134317872 61394784 3025952
13:00:19 15827 8192000000 10103217392 10520305664 1110565408 11.938 99 57 112 0 1178193968 56204128 11424432
13:02:09 63785 8192000000 10065508064 10559303680 1106476032 11.899 99 31 62 0 1232989456 119956192 6557232
13:04:15 56457 8192000000 10030007104 10478960640 1096155760 11.801 98 16 32 0 1127462992 28073888 3233344
13:06:21 82381 8192000000 10099110016 10569252864 1087043728 11.715 96 22 44 1 1159035456 67277440 4714288
13:09:03 94679 8192000000 10117836976 10568511488 1099992256 11.838 97 13 26 1 1179638736 76722368 2924112
13:11:38 44395 8192000000 10110149632 10600542208 1112716752 11.958 99 35 70 0 1224093024 103569488 7806784
13:13:40 36501 8192000000 10085300688 10556903424 1122200464 12.048 99 31 60 0 1207117712 78826560 6090688
13:15:18 30421 8192000000 10093816400 10544963584 1109470528 11.927 98 41 82 1 1198841792 80844880 8526384
13:16:57 20858 8192000000 10087911616 10523193344 1116088128 11.990 99 42 84 0 1178211280 53884864 8238288
13:18:20 34227 8192000000 10134198160 10567221248 1130658176 12.128 99 51 102 1 1213531632 72272720 10600736
13:19:58 56051 8192000000 10088553376 10577068032 1127361008 12.096 99 30 60 0 1226648080 93057424 6229648
13:21:55 128885 8192000000 10062953232 10558271488 1119997632 12.027 99 20 40 0 1183180944 59252560 3930752
13:25:06 56084 8192000000 10062361296 10552004608 1125091264 12.075 100 16 30 1 1170162960 42010928 3060768
13:27:06 80920 8192000000 10068948384 10516897792 1094349152 11.784 97 21 35 1 1114024864 15976160 3699552
13:29:31 18435 8192000000 10085770592 10509553664 1112339408 11.955 99 49 88 0 1159189072 36861632 9988032
13:30:50 36286 8192000000 10117917440 10565373952 1113775120 11.968 98 31 59 0 1190061072 70800400 5485552
13:32:27 42656 8192000000 10100029632 10554519552 1094667152 11.787 97 35 70 0 1204544688 102180896 7696640
13:34:11 19471 8192000000 10159270576 10589814784 1137366688 12.191 100 42 80 0 1236532784 90438000 8728096
13:35:30 36704 8192000000 10160749808 10621440000 1136100352 12.179 99 36 72 0 1271066864 126898464 8068048
13:37:08 30404 8192000000 9400052096 9807572992 491937168 5.664 44 23 46 1 594252416 97394016 4921232

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 24-10-2022 13:38:48 to 24-10-2022 13:39:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 55 v_add_invoice_to_stock 335544517
except 2 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-10-2022 13:39:05 to 24-10-2022 13:39:07.

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          224988
MON$OLDEST_ACTIVE               224989
MON$OLDEST_SNAPSHOT             224989
MON$NEXT_TRANSACTION            224989
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                       2064992
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             786
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2861 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2861 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2861 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2861 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 ---
:815: 
:816: mscs-test-161.budzdorov.ru    Mon Oct 24 13:38:03 2022
:817:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:818: 
:819: 
:820: mscs-test-161.budzdorov.ru    Mon Oct 24 13:38:05 2022
:821:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:822: 
--- end of diff output ---

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

24.10.22 13:39:08. 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-24 13:39:11 No severe PSQL-related problems occured
24.10.22 13:39:12. End of report.