Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.04.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 15773376 kB
MemAvailable 31451756 kB
Buffers 20 kB
Cached 15831216 kB
SwapCached 2688 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-04-15 04:37:16
DTS_END                         2022-04-15 04:37:16.2950
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/47286
TRN_ID                          213995
ATT_ID                          600
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: 8870.13 25457 1064415
customer order: creation 170.61 496 20473
customer order: refuse 14.28 483 1714
order to supplier: creation 43.88 1486 5265
order to supplier: removal 3.21 178194 385
invoice (draft): creation 44.86 3384 5383
invoice (draft): removal 49.13 1096 5896
invoice accept: apply 40.24 29324 4829
invoice accept: cancel 44.64 1346 5357
customer reserve: creation 3805.41 271 456649
customer reserve: removal 4367.64 101 524117
realization accept: apply 80.88 308 9706
realization accept: cancel 79.78 163 9573
payment from customer: creation 58.19 189 6983
payment from customer: removal 5.61 135 673
payment to supplier: creation 45.99 204 5519
payment to supplier: removal 3.37 140 404
service: total inventory turnovers 3.79 4735 455
service: total monetary turnovers 7.81 399 937
service: aggregate perf. data 0.78 20182 93
service: refresh index statistics 0.03 266505 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-04-2022 04:38:23 to 15-04-2022 04:38:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7763
TEST_TIME 2 6717
TEST_TIME 3 6365
TEST_TIME 4 6079
TEST_TIME 5 6136
TEST_TIME 6 6446
TEST_TIME 7 6781
TEST_TIME 8 6725
TEST_TIME 9 6488
TEST_TIME 10 6429
TEST_TIME 11 6691
TEST_TIME 12 6884
TEST_TIME 13 6962
TEST_TIME 14 7080
TEST_TIME 15 7103
TEST_TIME 16 7308
TEST_TIME 17 7391
TEST_TIME 18 7370
TEST_TIME 19 7601
TEST_TIME 20 7802
TEST_TIME 21 7742
TEST_TIME 22 7794
TEST_TIME 23 7952
TEST_TIME 24 8087
TEST_TIME 25 8072
TEST_TIME 26 8161
TEST_TIME 27 8407
TEST_TIME 28 8452
TEST_TIME 29 8650
TEST_TIME 30 8592
TEST_TIME 31 8832
TEST_TIME 32 8968
TEST_TIME 33 9103
TEST_TIME 34 9198
TEST_TIME 35 9531
TEST_TIME 36 9629
TEST_TIME 37 10182
TEST_TIME 38 10202
TEST_TIME 39 10161
TEST_TIME 40 10171
TEST_TIME 41 10612
TEST_TIME 42 10640
TEST_TIME 43 10805
TEST_TIME 44 10770
TEST_TIME 45 10641
TEST_TIME 46 10490
TEST_TIME 47 10282
TEST_TIME 48 10108
TEST_TIME 49 10366
TEST_TIME 50 10324
TEST_TIME 51 10168
TEST_TIME 52 10029
TEST_TIME 53 10013
TEST_TIME 54 9942
TEST_TIME 55 9820
TEST_TIME 56 9821
TEST_TIME 57 9802
TEST_TIME 58 9749
TEST_TIME 59 9852
TEST_TIME 60 9736
TEST_TIME 61 9626
TEST_TIME 62 9734
TEST_TIME 63 9817
TEST_TIME 64 9788
TEST_TIME 65 9720
TEST_TIME 66 9707
TEST_TIME 67 9676
TEST_TIME 68 9666
TEST_TIME 69 9654
TEST_TIME 70 9601
TEST_TIME 71 9640
TEST_TIME 72 9608
TEST_TIME 73 9578
TEST_TIME 74 9697
TEST_TIME 75 9645
TEST_TIME 76 9594
TEST_TIME 77 9503
TEST_TIME 78 9614
TEST_TIME 79 9580
TEST_TIME 80 9557
TEST_TIME 81 9572
TEST_TIME 82 9523
TEST_TIME 83 9452
TEST_TIME 84 9397
TEST_TIME 85 9394
TEST_TIME 86 9320
TEST_TIME 87 9280
TEST_TIME 88 9374
TEST_TIME 89 9296
TEST_TIME 90 9229
TEST_TIME 91 9219
TEST_TIME 92 9227
TEST_TIME 93 9193
TEST_TIME 94 9181
TEST_TIME 95 9191
TEST_TIME 96 9202
TEST_TIME 97 9186
TEST_TIME 98 9158
TEST_TIME 99 9182
TEST_TIME 100 9174
TEST_TIME 101 9237
TEST_TIME 102 9245
TEST_TIME 103 9202
TEST_TIME 104 9239
TEST_TIME 105 9209
TEST_TIME 106 9151
TEST_TIME 107 9085
TEST_TIME 108 9092
TEST_TIME 109 9080
TEST_TIME 110 9071
TEST_TIME 111 9023
TEST_TIME 112 8983
TEST_TIME 113 8980
TEST_TIME 114 8968
TEST_TIME 115 8991
TEST_TIME 116 8965
TEST_TIME 117 8976
TEST_TIME 118 8963
TEST_TIME 119 8956
TEST_TIME 120 8893

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-04-2022 04:38:27 to 15-04-2022 04:38:30.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1485795 1485795 0 0.00 0 16209 15 0 0
doc_list_biud 1485783 1485783 0 0.00 0 34919 42 0 0
sp_add_invoice_to_stock 4913 4829 84 1.70 45 191607 29324 0 84
sp_cancel_adding_invoice 5357 5357 0 0.00 6 101532 1347 0 0
sp_cancel_client_order 1714 1714 0 0.00 7 9835 484 0 0
sp_cancel_customer_reserve 524117 524117 0 0.00 1 34921 101 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 4 4937 135 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 4 5441 140 0 0
sp_cancel_supplier_invoice 5905 5896 9 0.15 2 14990 1096 0 9
sp_cancel_supplier_order 385 385 0 0.00 10 1622465 178194 0 0
sp_cancel_write_off 9573 9573 0 0.00 2 9414 163 0 0
sp_client_order 20473 20473 0 0.00 5 23998 497 0 0
sp_customer_reserve 456649 456649 0 0.00 1 57780 272 0 0
sp_fill_shopping_cart 31111 31111 0 0.00 2 13210 90 0 0
sp_fill_shopping_cart_clo_res 456618 456618 0 0.00 0 23625 20 0 0
sp_get_clo_for_invoice 4784 4784 0 0.00 0 26128 317 0 0
sp_kill_cost_storno 16007 16007 0 0.00 0 9243 81 0 0
sp_kill_qstorno_ret_qs2qd 1064088 1064088 0 0.00 0 34914 44 0 0
sp_kill_qty_storno 561528 561528 0 0.00 0 34918 102 0 0
sp_lock_selected_doc 20011 20011 0 0.00 0 9359 7 0 0
sp_make_cost_storno 26990 26990 0 0.00 0 14200 89 0 0
sp_multiply_rows_for_pdistr 26988 26988 0 0.00 0 12219 40 0 0
sp_multiply_rows_for_qdistr 20473 20473 0 0.00 0 13557 179 0 0
sp_pay_from_customer 6983 6983 0 0.00 6 16215 190 0 0
sp_pay_to_supplier 5519 5519 0 0.00 7 15548 204 0 0
sp_payment_common 12500 12500 0 0.00 2 11546 49 0 0
sp_qd_handle_on_cancel_clo 1714 1714 0 0.00 1 8677 392 0 0
sp_qd_handle_on_invoice_upd_sts 10140 10140 0 0.00 0 33201 594 0 0
sp_qd_handle_on_reserve_upd_sts 9703 9703 0 0.00 0 18654 72 0 0
sp_reserve_write_off 9706 9706 0 0.00 9 19003 308 0 0
sp_supplier_invoice 5383 5383 0 0.00 7 76960 3385 0 0
sp_supplier_order 5265 5265 0 0.00 8 26624 1486 0 0
srv_aggregate_perf_data 93 93 0 0.00 2679 181576 20182 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 835 146610 33417 0 0
srv_find_qd_qs_mism 1016883 1016883 0 0.00 0 13286 11 0 0
srv_make_invnt_saldo 455 455 0 0.00 80 71874 4736 0 0
srv_make_money_saldo 937 937 0 0.00 1 16230 399 0 0
srv_recalc_idx_stat 4 4 0 0.00 94947 395730 266506 0 0
t$perf-abend:sp_add_invoice_to_stock 84 84 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 4837 4837 0 0.00 1 16061 1169 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 2 7681 591 0 0
t$perf-norm:sp_cancel_client_order 1714 1714 0 0.00 0 1789 31 0 0
t$perf-norm:sp_cancel_customer_reserve 659 659 0 0.00 0 3480 40 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 2988 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 370 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 353 353 0 0.00 0 717 40 0 0
t$perf-norm:sp_cancel_supplier_order 385 385 0 0.00 0 81564 8247 0 0
t$perf-norm:sp_cancel_write_off 945 945 0 0.00 0 2606 23 0 0
t$perf-norm:sp_client_order 20473 20473 0 0.00 0 6475 42 0 0
t$perf-norm:sp_customer_reserve 990 990 0 0.00 0 1363 18 0 0
t$perf-norm:sp_pay_from_customer 6983 6983 0 0.00 0 3779 22 0 0
t$perf-norm:sp_pay_to_supplier 5519 5519 0 0.00 0 5404 21 0 0
t$perf-norm:sp_reserve_write_off 9706 9706 0 0.00 0 3449 25 0 0
t$perf-norm:sp_supplier_invoice 5383 5383 0 0.00 0 3901 39 0 0
t$perf-norm:sp_supplier_order 5265 5265 0 0.00 0 3756 39 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 572 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 623 12 0 0
t$perf-norm:srv_make_invnt_saldo 455 455 0 0.00 0 763 22 0 0
t$perf-norm:srv_make_money_saldo 937 937 0 0.00 0 1224 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 10 111 63 0 0
v_add_invoice_to_stock 9724 9556 168 1.72 0 4749 28 0 168
v_all_customers 37588 37588 0 0.00 0 124 4 0 0
v_all_suppliers 29608 29608 0 0.00 0 69 5 0 0
v_all_wares 40944 40944 0 0.00 0 65 4 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 1222 17 0 0
v_cancel_client_order 3428 3428 0 0.00 0 2565 12 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 1780 14 0 0
v_cancel_customer_reserve 1318 1318 0 0.00 0 3797 20 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 5386 23 0 0
v_cancel_supplier_invoice 724 706 18 2.48 0 3779 38 0 18
v_cancel_supplier_order 770 770 0 0.00 0 809 11 0 0
v_cancel_write_off 1890 1890 0 0.00 0 3029 26 0 0
v_max_id_clo_ord 5263 5263 0 0.00 0 1498 6 0 0
v_max_id_clo_res 990 990 0 0.00 0 1128 13 0 0
v_max_id_ord_sup 5374 5374 0 0.00 0 7088 8 0 0
v_max_non_paid_invoice 1352 1352 0 0.00 0 191 9 0 0
v_max_non_paid_realizn 3546 3546 0 0.00 0 312 7 0 0
v_min_id_clo_ord 5263 5263 0 0.00 0 1298 9 0 0
v_min_id_clo_res 990 990 0 0.00 0 6159 20 0 0
v_min_id_ord_sup 5374 5374 0 0.00 0 8755 10 0 0
v_min_non_paid_invoice 5519 5519 0 0.00 0 11541 16 0 0
v_min_non_paid_realizn 6981 6981 0 0.00 0 5715 17 0 0
v_our_firm 10226 10226 0 0.00 0 70 5 0 0
v_reserve_write_off 19406 19406 0 0.00 0 5246 12 0 0
x_lock_depdocs_on_canc_invoice 10900 10900 0 0.00 0 13769 138 0 0
x_lock_depdocs_on_canc_sup_ord 385 385 0 0.00 0 18466 545 0 0
x_make_qstorno_1000_1200 5265 5265 0 0.00 2 26592 1286 0 0
x_make_qstorno_1200_2000 5383 5383 0 0.00 3 76815 3151 0 0
x_make_qstorno_2100_3300 456648 456648 0 0.00 0 53195 237 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-04-2022 04:38:31 to 15-04-2022 04:38:35.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:20 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:21 28656 8192000000 9730079792 10029096960 794845216 8.844 100 89 176 3 824150320 7977248 21327856
02:10:09 22061 8192000000 9771369568 10080968704 844386640 9.344 100 93 186 0 884566800 18347360 21832800
02:11:42 14644 8192000000 9799650032 10117787648 864327568 9.543 100 87 172 2 907086448 22699344 20059536
02:13:11 11723 8192000000 9862666720 10195820544 912973408 10.027 100 81 162 0 983196816 52688928 17534480
02:14:26 6737 8192000000 9919086592 10264981504 957846416 10.468 100 79 156 1 1030354384 55512336 16995632
02:15:40 3387 8192000000 9957826080 10316935168 1003070112 10.908 100 82 162 0 1075381344 55629904 16681328
02:16:46 4179 8192000000 9993072848 10355499008 1019874512 11.071 100 87 173 0 1120610512 83794864 16941136
02:17:54 888 8192000000 10021868480 10395377664 1043023104 11.294 100 97 186 0 1143184048 81292480 18868464
02:18:57 1004 8192000000 10011614096 10382700544 1053037888 11.390 100 88 168 0 1129471888 60142400 16291600
02:20:01 1000 8192000000 10017109648 10395189248 1061085056 11.467 100 93 179 1 1143180416 63322128 18773232
02:21:03 1135 8192000000 10019224816 10398711808 1065679168 11.511 100 93 186 0 1139844016 55499504 18665344
02:22:04 1146 8192000000 10038160128 10417594368 1068724416 11.540 100 93 179 1 1156309328 70120928 17463984
02:23:07 1103 8192000000 10055387360 10442350592 1075812416 11.608 100 92 173 0 1177719936 83020624 18886896
02:24:10 1188 8192000000 10059387712 10447319040 1082095024 11.667 100 96 189 1 1179589536 77731504 19763008
02:25:14 931 8192000000 10042792864 10432524288 1083771552 11.683 100 76 150 1 1162711216 64748832 14190832
02:26:16 8338 8192000000 10031605104 10417258496 1084474240 11.690 100 71 140 0 1157386288 59472768 13439280
02:27:26 1247 8192000000 10036430816 10419732480 1079176272 11.640 100 89 169 2 1155116464 58456944 17483248
02:28:29 1209 8192000000 10067802368 10458648576 1090736640 11.750 100 96 179 1 1191188608 81566784 18885184
02:29:31 1217 8192000000 10048672848 10436206592 1079506384 11.643 100 87 168 1 1171848192 75306080 17035728
02:30:35 1197 8192000000 10030967632 10409336832 1062079072 11.476 100 96 180 0 1152491632 71988192 18424368
02:31:37 1094 8192000000 10011704016 10388996096 1056009664 11.418 100 95 186 1 1135998608 60235008 19753936
02:32:39 1136 8192000000 10005742368 10382594048 1045127488 11.314 100 93 178 0 1129071024 65730992 18212544
02:33:41 7494 8192000000 10008207184 10399010816 1059142288 11.448 100 81 156 1 1142234112 67865136 15226688
02:34:54 946 8192000000 9991599712 10366840832 1036571472 11.232 100 89 171 1 1116796208 62651808 17572928
02:35:56 3735 8192000000 9985644928 10361671680 1029474192 11.163 99 71 130 2 1098749536 56966864 12308480
02:37:02 6091 8192000000 9997894320 10382704640 1043805776 11.301 100 61 116 0 1114047024 59328944 10912304
02:38:17 1102 8192000000 10031632848 10411364352 1076730960 11.616 100 94 180 1 1150844512 55770000 18343552
02:39:24 24230 8192000000 10015331152 10409660416 1068171984 11.535 99 61 120 1 1142731616 62539152 12020480
02:40:55 6356 8192000000 10079536416 10469347328 1089923744 11.742 100 82 153 1 1191101712 85808032 15369936
02:42:18 12867 8192000000 10080467952 10470727680 1094296640 11.783 99 74 141 1 1199400048 90676960 14426448
02:43:34 1174 8192000000 10108927120 10493493248 1112509312 11.956 100 95 182 1 1226017504 93187504 20320688
02:44:40 12002 8192000000 10110637056 10517524480 1122984912 12.055 100 67 131 0 1229645568 92880800 13779856
02:46:02 944 8192000000 10148343280 10541506560 1133513696 12.154 100 84 159 0 1261125696 111068720 16543280
02:47:08 1266 8192000000 10183042784 10578452480 1142665264 12.241 100 93 180 3 1294559328 132633760 19260304
02:48:13 1405 8192000000 10230649888 10630569984 1156397488 12.370 100 87 158 1 1345076016 170131536 18546992
02:49:18 1081 8192000000 10220642752 10628063232 1157989488 12.384 100 92 172 2 1328795632 151576368 19229776
02:50:23 1381 8192000000 10243535280 10648526848 1171093472 12.507 100 93 177 2 1359563568 168382416 20087680
02:51:29 2270 8192000000 10281326896 10688196608 1181427744 12.604 100 82 154 3 1396153056 197803344 16921968
02:52:33 1257 8192000000 10297553920 10704556032 1172587344 12.521 99 89 174 1 1405194880 212736416 19871120
02:53:38 2058 8192000000 10292696736 10717593600 1165170096 12.452 99 91 174 1 1403959840 219383424 19406320
02:54:44 821 8192000000 10335525872 10739712000 1164149120 12.442 100 96 190 0 1444871280 259140736 21581424
02:55:49 1158 8192000000 10372074896 10786004992 1169888672 12.496 99 95 186 1 1476724496 285523136 21312688
02:56:53 984 8192000000 10381844672 10801381376 1189317264 12.677 100 98 191 1 1489057504 278468416 21271824
02:57:57 1839 8192000000 10398882144 10808127488 1180140336 12.592 100 91 176 0 1504790512 303541936 21108240
02:59:05 1547 8192000000 10419131360 10851807232 1188640016 12.671 100 97 183 3 1523671696 312651920 22379760
03:00:10 1169 8192000000 10448712864 10872496128 1195575888 12.735 100 94 183 0 1552894416 335050864 22267664
03:01:14 1581 8192000000 10459706704 10882433024 1199971008 12.776 100 89 172 0 1566811632 346417024 20423600
03:02:21 842 8192000000 10467145952 10882977792 1174688432 12.541 100 96 185 2 1570946048 373051168 23206448
03:03:25 1815 8192000000 10479924400 10908884992 1172551536 12.521 100 99 187 0 1578189312 383534736 22103040
03:04:29 1379 8192000000 10504066208 10962051072 1177110704 12.563 100 82 161 1 1598537328 401547264 19879360
03:05:35 9118 8192000000 10490983760 10939654144 1183040448 12.619 100 59 116 0 1619916880 422000160 14876272
03:06:47 9006 8192000000 10506451520 10955939840 1174723216 12.541 100 46 92 1 1612965520 426800528 11441776
03:08:01 25208 8192000000 10474688960 10924060672 1133485760 12.154 98 48 96 0 1556872576 410848480 12538336
03:09:36 5990 8192000000 10441657408 10874466304 1147413008 12.285 100 47 90 0 1542810416 384066464 11330944
03:10:58 7662 8192000000 10422853520 10876579840 1138679424 12.203 99 49 88 2 1518248144 368001776 11566944
03:12:13 4135 8192000000 10420935360 10922954752 1158048864 12.385 100 62 124 0 1526512400 352867152 15596384
03:13:23 5563 8192000000 10361207040 10822705152 1162019680 12.422 100 51 98 2 1473209488 299328528 11861280
03:14:34 35680 8192000000 10281479328 10777636864 1161502336 12.417 100 21 42 2 1393265408 226023712 5739360
03:16:11 52566 8192000000 10288705312 10759372800 1159695776 12.400 100 25 50 0 1406374128 240895648 5782704
03:18:49 104367 8192000000 10124574240 10665586688 1120307616 12.030 97 17 34 1 1222413184 98285376 3820192
03:21:34 70307 8192000000 10079663120 10519068672 1074066336 11.591 94 39 78 2 1114873952 31681952 9125664
03:24:14 146610 8192000000 10147590464 10646786048 1143636384 12.250 100 42 84 1 1298151328 145289088 9225856
03:27:52 71663 8192000000 10084052352 10560593920 1110039216 11.933 98 17 34 1 1165689632 52160544 3489872
03:30:05 1012 8192000000 10112064432 10530045952 1137847488 12.195 100 92 180 1 1210385936 52873872 19664576
03:31:11 44995 8192000000 10134098672 10594480128 1121554416 12.042 98 22 44 0 1216211520 89527776 5129328
03:32:57 50486 8192000000 10200432304 10678132736 1147362272 12.285 100 28 54 1 1314636400 160929888 6344240
03:34:52 30778 8192000000 10210933728 10702721024 1145672832 12.269 100 17 34 0 1302392992 152999520 3720640
03:36:57 32442 8192000000 10171094624 10623881216 1147843776 12.289 100 29 51 5 1269267792 116194592 5229424
03:38:33 42417 8192000000 10174531856 10697285632 1149721344 12.307 100 19 38 3 1324735408 170336928 4677136
03:40:15 28863 8192000000 10069424896 10524114944 1127294544 12.096 98 25 48 1 1182182736 50209568 4678624
03:41:50 80437 8192000000 10074874016 10552590336 1129554112 12.117 99 15 30 0 1167480848 34984112 2942624
03:44:17 31407 8192000000 10067545056 10520031232 1136524816 12.183 99 24 46 1 1179204192 38071856 4607520
03:45:57 67068 8192000000 10101744288 10541244416 1125190000 12.076 98 34 63 2 1167357776 36086432 6081344
03:48:09 15552 8192000000 10145290096 10603311104 1137376832 12.191 100 47 95 1 1227017136 79962384 9677920
03:49:25 60439 8192000000 10121597472 10596171776 1104953744 11.885 97 15 30 0 1197109136 87976752 4178640
03:51:39 58952 8192000000 10065933392 10504687616 1109720032 11.930 98 17 34 0 1145508096 32543344 3244720
03:53:49 35063 8192000000 10115541264 10543038464 1122545072 12.051 99 38 72 0 1177309312 46239104 8525136
03:55:30 13837 8192000000 10091550000 10562281472 1119874656 12.026 99 32 64 3 1181530304 55300288 6355360
03:56:49 65458 8192000000 10116873008 10582945792 1124724976 12.072 99 23 46 0 1223887744 94497824 4664944
03:58:58 59020 8192000000 10081808864 10534961152 1124645168 12.071 99 26 52 0 1195484928 65887024 4952736
04:00:58 28381 8192000000 10087693552 10516807680 1128929120 12.111 100 51 102 0 1186368608 47137328 10302160
04:02:27 81422 8192000000 10125970000 10592555008 1141118160 12.226 100 16 32 0 1248502672 103807312 3577200
04:05:25 48885 8192000000 10061889840 10580070400 1121939008 12.045 99 10 20 0 1193913008 69688192 2285808
04:07:24 63539 8192000000 10074768736 10537099264 1120013120 12.027 99 17 32 3 1164149488 40763040 3373328
04:09:35 60588 8192000000 10060395424 10505310208 1084546464 11.691 96 11 22 0 1113562384 26739616 2276304
04:11:39 59210 8192000000 10074576272 10535079936 1119930880 12.026 99 32 65 0 1168189472 41966080 6292512
04:13:42 39011 8192000000 10090733568 10545311744 1122662416 12.052 99 38 76 0 1167340352 36685184 7992752
04:15:27 46293 8192000000 10120672272 10567933952 1129147632 12.113 99 31 62 0 1214139168 78605184 6386352
04:17:18 82912 8192000000 10103523520 10593681408 1103712832 11.873 97 33 67 0 1211037056 100442912 6881312
04:19:43 23254 8192000000 10049400080 10464555008 1105638304 11.891 98 72 131 1 1130653008 9987744 15026960
04:21:06 57832 8192000000 10104206912 10574368768 1109032784 11.923 98 44 88 0 1185257952 67190352 9034816
04:23:05 74603 8192000000 10121976544 10562871296 1131685872 12.137 100 50 98 1 1216091120 74554048 9851200
04:25:21 63060 8192000000 10118294096 10576052224 1137069520 12.188 100 27 53 1 1240347856 97689568 5588768
04:27:25 44669 8192000000 10085745664 10589876224 1136451056 12.182 100 10 20 0 1195548720 57089920 2007744
04:29:10 85482 8192000000 10101076912 10563600384 1106826592 11.902 97 17 34 1 1181908800 71221232 3860976
04:31:36 81322 8192000000 10041467072 10489839616 1106486640 11.899 98 21 41 2 1152148720 41458496 4203584
04:34:04 66301 8192000000 10089211168 10563276800 1097173936 11.811 97 26 52 1 1148770368 46432112 5164320
04:36:19 50313 8192000000 10124325488 10566041600 1134314560 12.162 100 32 64 1 1208482144 67759872 6407712

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-04-2022 04:38:37 to 15-04-2022 04:38:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 84 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 15-04-2022 04:38:54 to 15-04-2022 04:38:56.

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          213294
MON$OLDEST_ACTIVE               215324
MON$OLDEST_SNAPSHOT             215324
MON$NEXT_TRANSACTION            215324
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                       2123776
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             765
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2761 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2761 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2761 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 ---
:85: 
:86: mscs-test-161.budzdorov.ru    Fri Apr 15 04:37:18 2022
:87:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:88: 
:89: 
:90: mscs-test-161.budzdorov.ru    Fri Apr 15 04:37:25 2022
:91:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:92: 
:93: 
:94: mscs-test-161.budzdorov.ru    Fri Apr 15 04:37:26 2022
:95:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:96: 
--- end of diff output ---

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

15.04.22 04:38:57. 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-04-15 04:39:00 No severe PSQL-related problems occured
15.04.22 04:39:01. End of report.