Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.05.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 16059508 kB
MemAvailable 31387840 kB
Buffers 20 kB
Cached 15549468 kB
SwapCached 2664 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-15 04:37:15
DTS_END                         2022-05-15 04:37:15.6420
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/51008
TRN_ID                          215170
ATT_ID                          648
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: 8961.81 27002 1075417
customer order: creation 164.42 531 19730
customer order: refuse 13.63 496 1635
order to supplier: creation 42.83 1435 5140
order to supplier: removal 2.98 214051 358
invoice (draft): creation 43.64 3253 5237
invoice (draft): removal 50.69 1088 6083
invoice accept: apply 40.71 27665 4885
invoice accept: cancel 46.08 1316 5530
customer reserve: creation 3797.57 261 455708
customer reserve: removal 4473.25 111 536790
realization accept: apply 80.41 301 9649
realization accept: cancel 81.82 170 9818
payment from customer: creation 56.83 194 6820
payment from customer: removal 5.10 128 612
payment to supplier: creation 46.24 213 5549
payment to supplier: removal 3.16 163 379
service: total inventory turnovers 3.69 5101 443
service: total monetary turnovers 7.64 435 917
service: aggregate perf. data 1.08 14923 129
service: refresh index statistics 0.04 268214 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-05-2022 04:38:24 to 15-05-2022 04:38:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7648
TEST_TIME 2 5337
TEST_TIME 3 6552
TEST_TIME 4 5780
TEST_TIME 5 6076
TEST_TIME 6 6109
TEST_TIME 7 6005
TEST_TIME 8 6152
TEST_TIME 9 6099
TEST_TIME 10 6780
TEST_TIME 11 6821
TEST_TIME 12 6747
TEST_TIME 13 6934
TEST_TIME 14 6771
TEST_TIME 15 6799
TEST_TIME 16 6820
TEST_TIME 17 7026
TEST_TIME 18 6900
TEST_TIME 19 6865
TEST_TIME 20 7057
TEST_TIME 21 7018
TEST_TIME 22 7359
TEST_TIME 23 7410
TEST_TIME 24 7513
TEST_TIME 25 7397
TEST_TIME 26 7620
TEST_TIME 27 7690
TEST_TIME 28 8259
TEST_TIME 29 8534
TEST_TIME 30 8493
TEST_TIME 31 8639
TEST_TIME 32 9147
TEST_TIME 33 9327
TEST_TIME 34 9593
TEST_TIME 35 9704
TEST_TIME 36 9902
TEST_TIME 37 10389
TEST_TIME 38 10432
TEST_TIME 39 10213
TEST_TIME 40 10289
TEST_TIME 41 10175
TEST_TIME 42 10174
TEST_TIME 43 10050
TEST_TIME 44 9911
TEST_TIME 45 10183
TEST_TIME 46 10179
TEST_TIME 47 9966
TEST_TIME 48 9794
TEST_TIME 49 9702
TEST_TIME 50 9599
TEST_TIME 51 9576
TEST_TIME 52 9477
TEST_TIME 53 9413
TEST_TIME 54 9368
TEST_TIME 55 9326
TEST_TIME 56 9316
TEST_TIME 57 9240
TEST_TIME 58 9245
TEST_TIME 59 9175
TEST_TIME 60 9169
TEST_TIME 61 9058
TEST_TIME 62 9165
TEST_TIME 63 9274
TEST_TIME 64 9416
TEST_TIME 65 9459
TEST_TIME 66 9436
TEST_TIME 67 9435
TEST_TIME 68 9426
TEST_TIME 69 9431
TEST_TIME 70 9352
TEST_TIME 71 9434
TEST_TIME 72 9536
TEST_TIME 73 9537
TEST_TIME 74 9584
TEST_TIME 75 9482
TEST_TIME 76 9370
TEST_TIME 77 9423
TEST_TIME 78 9340
TEST_TIME 79 9325
TEST_TIME 80 9242
TEST_TIME 81 9330
TEST_TIME 82 9268
TEST_TIME 83 9239
TEST_TIME 84 9171
TEST_TIME 85 9178
TEST_TIME 86 9124
TEST_TIME 87 9127
TEST_TIME 88 9205
TEST_TIME 89 9157
TEST_TIME 90 9144
TEST_TIME 91 9163
TEST_TIME 92 9212
TEST_TIME 93 9316
TEST_TIME 94 9338
TEST_TIME 95 9334
TEST_TIME 96 9363
TEST_TIME 97 9335
TEST_TIME 98 9327
TEST_TIME 99 9440
TEST_TIME 100 9439
TEST_TIME 101 9396
TEST_TIME 102 9347
TEST_TIME 103 9310
TEST_TIME 104 9290
TEST_TIME 105 9239
TEST_TIME 106 9242
TEST_TIME 107 9192
TEST_TIME 108 9150
TEST_TIME 109 9131
TEST_TIME 110 9073
TEST_TIME 111 9041
TEST_TIME 112 9027
TEST_TIME 113 9013
TEST_TIME 114 9034
TEST_TIME 115 9025
TEST_TIME 116 8994
TEST_TIME 117 9017
TEST_TIME 118 9022
TEST_TIME 119 9016
TEST_TIME 120 8982

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-05-2022 04:38:28 to 15-05-2022 04: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 1493513 1493513 0 0.00 0 32677 15 0 0
doc_list_biud 1493508 1493508 0 0.00 0 40233 46 0 0
sp_add_invoice_to_stock 5054 4885 169 3.34 45 184349 27665 0 169
sp_cancel_adding_invoice 5530 5530 0 0.00 8 86776 1317 0 0
sp_cancel_client_order 1635 1635 0 0.00 6 9708 497 0 0
sp_cancel_customer_reserve 536790 536790 0 0.00 1 40254 111 0 0
sp_cancel_pay_from_customer 612 612 0 0.00 3 3526 128 0 0
sp_cancel_pay_to_supplier 379 379 0 0.00 4 10278 163 0 0
sp_cancel_supplier_invoice 6099 6083 16 0.26 3 31207 1089 0 16
sp_cancel_supplier_order 358 358 0 0.00 7 1621112 214052 0 0
sp_cancel_write_off 9818 9818 0 0.00 2 10342 171 0 0
sp_client_order 19730 19730 0 0.00 5 43293 531 0 0
sp_customer_reserve 455708 455708 0 0.00 1 54765 262 0 0
sp_fill_shopping_cart 30097 30097 0 0.00 2 15809 85 0 0
sp_fill_shopping_cart_clo_res 455665 455665 0 0.00 0 21165 20 0 0
sp_get_clo_for_invoice 4840 4840 0 0.00 0 19315 272 0 0
sp_kill_cost_storno 16339 16339 0 0.00 0 11893 84 0 0
sp_kill_qstorno_ret_qs2qd 1089839 1089839 0 0.00 0 40174 48 0 0
sp_kill_qty_storno 574703 574703 0 0.00 0 40230 111 0 0
sp_lock_selected_doc 19828 19828 0 0.00 0 10317 8 0 0
sp_make_cost_storno 26858 26858 0 0.00 0 13376 93 0 0
sp_multiply_rows_for_pdistr 26858 26858 0 0.00 0 10505 42 0 0
sp_multiply_rows_for_qdistr 19730 19730 0 0.00 0 23805 192 0 0
sp_pay_from_customer 6820 6820 0 0.00 5 12625 194 0 0
sp_pay_to_supplier 5549 5549 0 0.00 7 15770 214 0 0
sp_payment_common 12369 12369 0 0.00 2 6319 51 0 0
sp_qd_handle_on_cancel_clo 1635 1635 0 0.00 0 9682 406 0 0
sp_qd_handle_on_invoice_upd_sts 10370 10370 0 0.00 0 20754 575 0 0
sp_qd_handle_on_reserve_upd_sts 9649 9649 0 0.00 0 11718 66 0 0
sp_reserve_write_off 9649 9649 0 0.00 9 13609 302 0 0
sp_supplier_invoice 5237 5237 0 0.00 9 89555 3254 0 0
sp_supplier_order 5140 5140 0 0.00 8 46601 1436 0 0
srv_aggregate_perf_data 129 129 0 0.00 2602 133866 14923 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 770 133673 30902 0 0
srv_find_qd_qs_mism 1027441 1027441 0 0.00 0 32675 11 0 0
srv_make_invnt_saldo 443 443 0 0.00 87 117717 5101 0 0
srv_make_money_saldo 917 917 0 0.00 1 12261 435 0 0
srv_recalc_idx_stat 5 5 0 0.00 83916 435652 268215 0 0
t$perf-abend:sp_add_invoice_to_stock 169 169 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 4886 4886 0 0.00 1 10803 1000 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 1 5015 449 0 0
t$perf-norm:sp_cancel_client_order 1635 1635 0 0.00 0 3602 33 0 0
t$perf-norm:sp_cancel_customer_reserve 659 659 0 0.00 0 5633 40 0 0
t$perf-norm:sp_cancel_pay_from_customer 612 612 0 0.00 0 1817 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 379 379 0 0.00 0 631 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 386 386 0 0.00 0 1229 40 0 0
t$perf-norm:sp_cancel_supplier_order 358 358 0 0.00 0 110400 9504 0 0
t$perf-norm:sp_cancel_write_off 911 911 0 0.00 0 3299 26 0 0
t$perf-norm:sp_client_order 19730 19730 0 0.00 0 10709 43 0 0
t$perf-norm:sp_customer_reserve 972 972 0 0.00 0 632 16 0 0
t$perf-norm:sp_pay_from_customer 6820 6820 0 0.00 0 3802 20 0 0
t$perf-norm:sp_pay_to_supplier 5549 5549 0 0.00 0 23879 26 0 0
t$perf-norm:sp_reserve_write_off 9649 9649 0 0.00 0 22615 27 0 0
t$perf-norm:sp_supplier_invoice 5237 5237 0 0.00 0 5019 35 0 0
t$perf-norm:sp_supplier_order 5140 5140 0 0.00 0 3108 36 0 0
t$perf-norm:srv_aggregate_perf_data 129 129 0 0.00 0 2896 39 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 287 12 0 0
t$perf-norm:srv_make_invnt_saldo 443 443 0 0.00 0 3300 31 0 0
t$perf-norm:srv_make_money_saldo 917 917 0 0.00 0 426 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 223 69 0 0
v_add_invoice_to_stock 10016 9678 338 3.37 0 7832 31 0 338
v_all_customers 36004 36004 0 0.00 0 141 4 0 0
v_all_suppliers 28882 28882 0 0.00 0 109 4 0 0
v_all_wares 39458 39458 0 0.00 0 55 4 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 793 13 0 0
v_cancel_client_order 3270 3270 0 0.00 0 5996 16 0 0
v_cancel_customer_prepayment 1224 1224 0 0.00 0 1224 12 0 0
v_cancel_customer_reserve 1318 1318 0 0.00 0 1303 10 0 0
v_cancel_payment_to_supplier 758 758 0 0.00 0 876 11 0 0
v_cancel_supplier_invoice 804 772 32 3.98 0 2074 32 0 32
v_cancel_supplier_order 716 716 0 0.00 0 550 12 0 0
v_cancel_write_off 1822 1822 0 0.00 0 3557 28 0 0
v_max_id_clo_ord 5139 5139 0 0.00 0 1940 5 0 0
v_max_id_clo_res 972 972 0 0.00 0 3504 17 0 0
v_max_id_ord_sup 5229 5229 0 0.00 0 4236 6 0 0
v_max_non_paid_invoice 1476 1476 0 0.00 0 147 8 0 0
v_max_non_paid_realizn 3618 3618 0 0.00 0 6296 10 0 0
v_min_id_clo_ord 5139 5139 0 0.00 0 3802 11 0 0
v_min_id_clo_res 972 972 0 0.00 0 10754 40 0 0
v_min_id_ord_sup 5229 5229 0 0.00 0 3157 8 0 0
v_min_non_paid_invoice 5549 5549 0 0.00 0 5699 20 0 0
v_min_non_paid_realizn 6820 6820 0 0.00 0 4562 19 0 0
v_our_firm 9858 9858 0 0.00 0 57 5 0 0
v_reserve_write_off 19298 19298 0 0.00 0 9101 13 0 0
x_lock_depdocs_on_canc_invoice 11227 11227 0 0.00 0 14095 131 0 0
x_lock_depdocs_on_canc_sup_ord 358 358 0 0.00 0 8029 426 0 0
x_make_qstorno_1000_1200 5140 5140 0 0.00 3 46581 1250 0 0
x_make_qstorno_1200_2000 5237 5237 0 0.00 4 89413 3032 0 0
x_make_qstorno_2100_3300 455707 455707 0 0.00 0 48243 229 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-05-2022 04:38:32 to 15-05-2022 04: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
02:07:19 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:20 19569 8192000000 9716302304 10012835840 790649008 8.801 100 90 179 0 820482288 7788016 22045264
02:09:43 27061 8192000000 9756154048 10063912960 827787376 9.177 100 86 172 0 859608704 11113456 20707872
02:11:12 17178 8192000000 9794937648 10112745472 861714784 9.517 100 83 166 0 899414016 18399088 19300144
02:12:34 12921 8192000000 9859646208 10192773120 912417168 10.021 100 80 160 0 967749056 37657968 17673920
02:13:49 5753 8192000000 9906845184 10250866688 951503920 10.406 100 86 171 0 1023704128 54117024 18083184
02:14:58 5257 8192000000 9967031984 10319011840 983051072 10.714 100 79 158 0 1077028128 77409600 16567456
02:16:07 1507 8192000000 9985264512 10345521152 1007118256 10.947 100 95 186 0 1105140352 79446176 18575920
02:17:12 1307 8192000000 9985540912 10352873472 1030701120 11.175 100 87 163 2 1116246064 68790016 16754928
02:18:14 1266 8192000000 10038339232 10412474368 1057984672 11.437 100 96 184 4 1165248048 88199664 19063712
02:19:17 1352 8192000000 10023540288 10403405824 1060317696 11.460 100 88 167 1 1147709024 70103040 17288288
02:20:20 11440 8192000000 10017012768 10399485952 1072208816 11.573 100 72 127 2 1156314480 71820304 12285360
02:21:32 1086 8192000000 10063556096 10445606912 1080450272 11.652 100 94 188 1 1181904496 81983392 19470832
02:22:34 4050 8192000000 10060167600 10448711680 1084049568 11.686 100 71 139 1 1186848256 88198832 14599856
02:23:39 1079 8192000000 10071048592 10457915392 1092622352 11.768 100 95 180 2 1192173744 80829728 18721664
02:24:42 954 8192000000 10050365328 10440531968 1096516480 11.805 100 86 172 0 1172867856 58551392 17799984
02:25:44 1379 8192000000 10055890016 10448367616 1105613584 11.891 100 86 171 2 1180914960 58708032 16593344
02:26:47 933 8192000000 10051265168 10441924608 1101778176 11.855 100 90 171 1 1172821936 53508880 17534880
02:27:48 3461 8192000000 10051486224 10445123584 1097967568 11.818 100 74 142 2 1175005872 63385424 13652880
02:28:53 823 8192000000 10061457424 10450628608 1095120320 11.791 100 92 175 0 1180235440 66711552 18403568
02:29:55 2493 8192000000 10049537296 10438287360 1089700720 11.740 100 90 167 0 1178181824 70206496 18274608
02:30:58 7199 8192000000 10042667472 10435522560 1084422800 11.690 100 71 139 1 1180782832 82978032 13382000
02:32:07 1208 8192000000 10005829824 10381520896 1053564784 11.395 100 93 168 3 1125206192 53418688 18222720
02:33:09 1271 8192000000 10000141136 10377584640 1048990512 11.351 100 91 168 3 1126957360 60363056 17603792
02:34:11 838 8192000000 10019135280 10395103232 1044007280 11.303 100 93 175 1 1140630448 78246208 18376960
02:35:14 1010 8192000000 10002016928 10381012992 1049852336 11.359 100 90 170 3 1122049776 53830800 18366640
02:36:16 5004 8192000000 10004061968 10385244160 1050222960 11.363 99 74 140 2 1122871744 59247760 13401024
02:37:22 33432 8192000000 9997262976 10376466432 1047640592 11.338 99 71 121 3 1117296208 58762224 10893392
02:39:00 1138 8192000000 10024200512 10403651584 1070596592 11.558 100 82 159 1 1140986896 54575568 15814736
02:40:06 4075 8192000000 10043665648 10424598528 1082245872 11.669 100 78 153 0 1167770192 69380512 16143808
02:41:11 2932 8192000000 10071699968 10461253632 1091797824 11.760 100 73 145 0 1184681504 77920736 14962944
02:42:18 7230 8192000000 10105540512 10501976064 1101547072 11.852 100 63 123 0 1227071088 112353232 13170784
02:43:30 1161 8192000000 10162665184 10552807424 1121401424 12.040 100 95 178 2 1280356000 139206864 19747712
02:44:38 3149 8192000000 10181847968 10578329600 1132196192 12.142 100 79 155 2 1297208240 147986800 17025248
02:45:52 2727 8192000000 10181498368 10579353600 1133277616 12.152 100 74 138 2 1290077744 142250704 14549424
02:46:59 811 8192000000 10220416656 10644393984 1143470480 12.248 100 95 186 1 1334826160 171807840 19547840
02:48:03 1201 8192000000 10227858352 10623881216 1144792512 12.261 100 93 179 3 1342196768 176739200 20665056
02:49:10 3985 8192000000 10249352928 10664095744 1151152000 12.320 100 67 128 0 1365437360 199610432 14674928
02:50:20 893 8192000000 10320172576 10725806080 1170652112 12.503 100 96 189 2 1433700592 241444160 21604320
02:51:25 1004 8192000000 10343611776 10745507840 1175003056 12.544 100 97 192 1 1453350768 255060400 23287312
02:52:31 1472 8192000000 10356383408 10778226688 1181727392 12.606 100 95 185 0 1464386720 260787136 21872192
02:53:38 1120 8192000000 10367334240 10790846464 1184968880 12.637 100 90 180 0 1471026560 265110448 20947232
02:54:45 1590 8192000000 10388264464 10793496576 1190958960 12.692 100 96 187 1 1495281584 283095856 21226768
02:55:56 946 8192000000 10453102560 10860736512 1199541584 12.772 100 97 186 1 1559636864 337033936 23061344
02:56:59 764 8192000000 10483890832 10910998528 1189460304 12.678 100 98 192 3 1589863712 377631488 22771920
02:58:04 1371 8192000000 10517988944 10926530560 1195848448 12.738 100 98 192 0 1628347440 409028992 23470000
02:59:11 1247 8192000000 10508905920 10957881344 1192221408 12.704 100 93 183 1 1615781696 401697424 21862864
03:00:14 836 8192000000 10526981248 10946568192 1184863808 12.636 100 96 186 0 1629224144 422193344 22166992
03:01:18 1189 8192000000 10559919200 10984083456 1198051040 12.758 100 97 194 1 1664186512 442122240 24013232
03:02:22 1584 8192000000 10571720192 11013390336 1193091712 12.712 100 86 168 0 1674585504 459498560 21995232
03:03:28 1008 8192000000 10575100560 11037081600 1198250368 12.760 100 84 167 0 1680970128 461754208 20965552
03:04:35 2324 8192000000 10553240992 11041484800 1193037488 12.712 100 80 160 1 1657180064 443629744 20512832
03:05:39 1597 8192000000 10532146320 11004190720 1188322608 12.668 100 70 138 0 1636727232 431240800 17163824
03:06:53 7635 8192000000 10497325584 10966024192 1185972864 12.646 100 59 118 0 1610099872 409158960 14968048
03:08:06 12753 8192000000 10467854720 10983264256 1186954000 12.655 100 39 78 1 1617409296 419681664 10773632
03:09:21 14134 8192000000 10430594000 10915360768 1191515440 12.697 100 40 80 0 1533059200 331665984 9877776
03:10:37 17720 8192000000 10393153648 10887409664 1182390912 12.612 100 35 68 0 1506610448 315426096 8793440
03:11:56 24146 8192000000 10291728240 10750926848 1127987296 12.102 96 27 54 0 1390611408 255855200 6768912
03:13:43 29047 8192000000 10188217408 10709512192 1136230832 12.180 100 11 22 1 1326178080 187076064 2871184
03:16:18 24819 8192000000 10087222544 10516590592 1133739408 12.157 99 61 123 0 1183144208 35923520 13481280
03:17:46 99344 8192000000 10080981344 10572853248 1107379648 11.908 96 19 38 4 1146464432 35234752 3850032
03:20:27 48861 8192000000 10171121728 10617974784 1142634048 12.240 99 29 55 0 1234239776 85582656 6023072
03:22:26 93980 8192000000 10084018560 10608816128 1139001712 12.206 100 4 8 0 1217517888 77373728 1142448
03:25:58 10169 8192000000 10085949024 10517733376 1122324784 12.049 99 75 144 2 1172448320 35117600 15005936
03:27:24 21802 8192000000 10074148240 10503294976 1094983168 11.790 99 48 96 0 1158870432 54182704 9704560
03:28:49 21619 8192000000 10047111392 10499477504 1094278640 11.783 99 11 22 0 1137739792 40672784 2788368
03:30:13 5929 8192000000 10091546704 10511065088 1116199104 11.991 100 68 123 1 1180906208 51468080 13239024
03:31:25 21878 8192000000 10115411088 10552569856 1126853264 12.092 100 42 79 0 1212197776 76294512 9050000
03:32:51 40711 8192000000 10174187840 10640715776 1136369600 12.181 100 31 62 0 1278412832 135382720 6660512
03:34:46 26653 8192000000 10165113344 10639675392 1138875760 12.205 100 19 38 3 1284238928 140849840 4513328
03:36:21 75755 8192000000 10216716976 10668318720 1123987200 12.065 98 19 38 0 1296144320 167811376 4345744
03:38:40 106826 8192000000 10179084640 10641743872 1092066112 11.762 96 15 29 2 1283640976 188296816 3278048
03:41:27 87012 8192000000 10061579360 10544959488 1105673536 11.891 97 11 22 1 1156037760 48237104 2127120
03:43:54 73780 8192000000 10131158720 10583113728 1103405552 11.870 97 36 72 0 1192132032 81040448 7686032
03:46:09 17977 8192000000 10107319120 10540642304 1103637168 11.872 98 40 73 0 1175803792 64222240 7944384
03:47:31 28692 8192000000 10182329712 10611351552 1133405536 12.153 100 67 134 1 1264246976 116688128 14153312
03:49:01 48221 8192000000 10122239584 10590777344 1135001312 12.168 100 16 29 2 1216154256 77514288 3638656
03:50:50 52270 8192000000 10086581328 10540871680 1092074992 11.762 97 18 36 1 1165788240 69449728 4263520
03:52:43 133673 8192000000 10094973504 10586390528 1118753616 12.015 99 38 70 1 1196972928 69900304 8319008
03:56:31 59338 8192000000 10126865184 10600316928 1135889968 12.177 100 9 18 1 1220160352 82097152 2173232
03:58:31 70247 8192000000 10067310144 10517241856 1089416064 11.737 96 12 23 2 1159532272 67405072 2711136
04:00:43 40512 8192000000 10094336032 10548936704 1132587856 12.146 100 10 20 1 1191646752 56635520 2423376
04:02:24 81844 8192000000 10131727904 10585997312 1112041600 11.952 98 9 18 1 1187135072 73126416 1967056
04:04:55 42775 8192000000 10095692800 10585137152 1132128160 12.141 100 16 32 2 1182734592 47070208 3536224
04:06:40 31348 8192000000 10146221152 10593599488 1135593808 12.174 99 44 86 1 1240503440 95699648 9209984
04:08:22 59269 8192000000 10181301840 10682777600 1130284112 12.124 99 25 50 1 1265186800 129725280 5177408
04:10:29 50598 8192000000 10124468192 10578604032 1139006960 12.206 100 36 72 1 1271231520 124888624 7335936
04:12:20 14816 8192000000 10125358944 10573115392 1134880336 12.167 99 42 82 3 1210068592 66749968 8438288
04:13:35 21915 8192000000 10088748192 10514063360 1107738320 11.911 97 52 98 1 1172897072 55436688 9722064
04:14:59 39946 8192000000 10194822128 10635366400 1130042480 12.122 99 50 99 1 1250014336 109689696 10282160
04:16:39 102617 8192000000 10065865696 10558185472 1122459088 12.050 99 19 38 1 1235501392 108535552 4506752
04:19:31 23297 8192000000 10056316768 10504822784 1114411472 11.974 99 27 52 1 1145414768 25264800 5738496
04:21:01 39134 8192000000 10074755264 10539466752 1108875264 11.922 98 11 22 0 1172432064 61293520 2263280
04:24:41 53446 8192000000 10037331808 10483499008 1117743984 12.006 99 15 28 1 1137658400 16936912 2977504
04:26:36 67634 8192000000 10047749536 10512883712 1107598800 11.910 98 14 28 0 1142333488 31739232 2995456
04:28:44 52656 8192000000 10049968480 10506326016 1120677152 12.033 99 14 28 1 1144151936 21098448 2376336
04:30:40 28909 8192000000 10097372496 10544652288 1139016000 12.206 100 32 61 1 1201698192 56982496 5699696
04:32:20 49479 8192000000 10089507424 10542952448 1090012048 11.743 96 27 54 4 1150275536 54645360 5618128
04:34:16 60992 8192000000 10108633280 10593447936 1100508416 11.842 98 24 48 1 1207001568 100971616 5521536
04:36:24 38479 8192000000 10144127728 10589356032 1120247360 12.029 100 31 63 1 1246042528 119240128 6555040

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 169 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 15-05-2022 04:38:55 to 15-05-2022 04: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          211564
MON$OLDEST_ACTIVE               215429
MON$OLDEST_SNAPSHOT             215429
MON$NEXT_TRANSACTION            215429
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                       2078368
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             767
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2764 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2764 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2764 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2764 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 ---
:153: 
:154: mscs-test-161.budzdorov.ru    Sun May 15 04:37:27 2022
:155:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:156: 
--- end of diff output ---

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

15.05.22 04: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-15 04:39:01 No severe PSQL-related problems occured
15.05.22 04:39:02. End of report.