Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.04.2022 13:37
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 15091604 kB
MemAvailable 31513124 kB
Buffers 0 kB
Cached 16585288 kB
SwapCached 2796 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-21 13:37:18
DTS_END                         2022-04-21 13:37:18.2200
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/59056
TRN_ID                          208162
ATT_ID                          587
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: 8497.18 22081 1019662
customer order: creation 164.93 496 19792
customer order: refuse 13.88 469 1666
order to supplier: creation 43.27 1454 5192
order to supplier: removal 3.24 192726 389
invoice (draft): creation 42.99 3593 5159
invoice (draft): removal 47.39 1161 5687
invoice accept: apply 38.97 31585 4676
invoice accept: cancel 42.52 1365 5102
customer reserve: creation 3642.03 300 437044
customer reserve: removal 4182.52 116 501902
realization accept: apply 77.18 290 9262
realization accept: cancel 76.89 190 9227
payment from customer: creation 55.36 189 6643
payment from customer: removal 5.13 129 616
payment to supplier: creation 45.13 203 5416
payment to supplier: removal 3.14 153 377
service: total inventory turnovers 3.93 4231 471
service: total monetary turnovers 7.70 386 924
service: aggregate perf. data 0.92 15614 110
service: refresh index statistics 0.06 186978 7

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-04-2022 13:38:07 to 21-04-2022 13:38:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9896
TEST_TIME 2 7385
TEST_TIME 3 7349
TEST_TIME 4 6470
TEST_TIME 5 6190
TEST_TIME 6 7006
TEST_TIME 7 6623
TEST_TIME 8 6613
TEST_TIME 9 6517
TEST_TIME 10 6761
TEST_TIME 11 6658
TEST_TIME 12 6648
TEST_TIME 13 6631
TEST_TIME 14 6566
TEST_TIME 15 6478
TEST_TIME 16 6487
TEST_TIME 17 6419
TEST_TIME 18 6562
TEST_TIME 19 6523
TEST_TIME 20 6628
TEST_TIME 21 6779
TEST_TIME 22 6854
TEST_TIME 23 6817
TEST_TIME 24 7181
TEST_TIME 25 7208
TEST_TIME 26 7607
TEST_TIME 27 7678
TEST_TIME 28 8219
TEST_TIME 29 8358
TEST_TIME 30 8890
TEST_TIME 31 8896
TEST_TIME 32 9447
TEST_TIME 33 9893
TEST_TIME 34 9869
TEST_TIME 35 9950
TEST_TIME 36 9960
TEST_TIME 37 9754
TEST_TIME 38 9666
TEST_TIME 39 9580
TEST_TIME 40 9490
TEST_TIME 41 9366
TEST_TIME 42 9406
TEST_TIME 43 9344
TEST_TIME 44 9370
TEST_TIME 45 9252
TEST_TIME 46 9335
TEST_TIME 47 9347
TEST_TIME 48 9383
TEST_TIME 49 9412
TEST_TIME 50 9327
TEST_TIME 51 9292
TEST_TIME 52 9219
TEST_TIME 53 9107
TEST_TIME 54 9219
TEST_TIME 55 9091
TEST_TIME 56 9079
TEST_TIME 57 9123
TEST_TIME 58 9109
TEST_TIME 59 9013
TEST_TIME 60 8997
TEST_TIME 61 8960
TEST_TIME 62 8939
TEST_TIME 63 8902
TEST_TIME 64 8871
TEST_TIME 65 8869
TEST_TIME 66 8804
TEST_TIME 67 8839
TEST_TIME 68 8819
TEST_TIME 69 8788
TEST_TIME 70 8714
TEST_TIME 71 8902
TEST_TIME 72 8944
TEST_TIME 73 9013
TEST_TIME 74 8965
TEST_TIME 75 8965
TEST_TIME 76 8974
TEST_TIME 77 9051
TEST_TIME 78 9027
TEST_TIME 79 9004
TEST_TIME 80 8905
TEST_TIME 81 8959
TEST_TIME 82 8882
TEST_TIME 83 8847
TEST_TIME 84 8785
TEST_TIME 85 8756
TEST_TIME 86 8706
TEST_TIME 87 8715
TEST_TIME 88 8715
TEST_TIME 89 8672
TEST_TIME 90 8651
TEST_TIME 91 8626
TEST_TIME 92 8595
TEST_TIME 93 8636
TEST_TIME 94 8598
TEST_TIME 95 8612
TEST_TIME 96 8644
TEST_TIME 97 8734
TEST_TIME 98 8842
TEST_TIME 99 8853
TEST_TIME 100 8840
TEST_TIME 101 8790
TEST_TIME 102 8725
TEST_TIME 103 8700
TEST_TIME 104 8746
TEST_TIME 105 8707
TEST_TIME 106 8665
TEST_TIME 107 8625
TEST_TIME 108 8564
TEST_TIME 109 8567
TEST_TIME 110 8540
TEST_TIME 111 8548
TEST_TIME 112 8527
TEST_TIME 113 8509
TEST_TIME 114 8516
TEST_TIME 115 8552
TEST_TIME 116 8537
TEST_TIME 117 8580
TEST_TIME 118 8564
TEST_TIME 119 8580
TEST_TIME 120 8518

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-04-2022 13:38:11 to 21-04-2022 13:38:14.

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 1422017 1422017 0 0.00 0 20560 15 0 0
doc_list_biud 1422003 1422003 0 0.00 0 67469 48 0 0
sp_add_invoice_to_stock 4801 4676 125 2.60 70 245908 31586 0 125
sp_cancel_adding_invoice 5102 5102 0 0.00 7 90674 1365 0 0
sp_cancel_client_order 1666 1666 0 0.00 6 9254 469 0 0
sp_cancel_customer_reserve 501902 501902 0 0.00 1 30068 116 0 0
sp_cancel_pay_from_customer 616 616 0 0.00 3 2253 130 0 0
sp_cancel_pay_to_supplier 377 377 0 0.00 3 6294 153 0 0
sp_cancel_supplier_invoice 5692 5687 5 0.08 3 67481 1161 0 5
sp_cancel_supplier_order 389 389 0 0.00 12 1369604 192726 0 0
sp_cancel_write_off 9227 9227 0 0.00 2 20059 191 0 0
sp_client_order 19792 19792 0 0.00 5 60461 496 0 0
sp_customer_reserve 437044 437044 0 0.00 1 71547 301 0 0
sp_fill_shopping_cart 30131 30131 0 0.00 1 18726 81 0 0
sp_fill_shopping_cart_clo_res 437010 437010 0 0.00 0 17750 23 0 0
sp_get_clo_for_invoice 4634 4634 0 0.00 0 37430 317 0 0
sp_kill_cost_storno 15322 15322 0 0.00 0 20239 87 0 0
sp_kill_qstorno_ret_qs2qd 1019107 1019107 0 0.00 0 67465 51 0 0
sp_kill_qty_storno 537867 537867 0 0.00 0 67468 117 0 0
sp_lock_selected_doc 19333 19333 0 0.00 0 9069 9 0 0
sp_make_cost_storno 25955 25955 0 0.00 0 16306 84 0 0
sp_multiply_rows_for_pdistr 25954 25954 0 0.00 0 7537 41 0 0
sp_multiply_rows_for_qdistr 19792 19792 0 0.00 0 60418 188 0 0
sp_pay_from_customer 6643 6643 0 0.00 6 8970 190 0 0
sp_pay_to_supplier 5416 5416 0 0.00 6 8708 204 0 0
sp_payment_common 12059 12059 0 0.00 2 4522 46 0 0
sp_qd_handle_on_cancel_clo 1666 1666 0 0.00 1 9241 385 0 0
sp_qd_handle_on_invoice_upd_sts 9735 9735 0 0.00 0 33391 584 0 0
sp_qd_handle_on_reserve_upd_sts 9261 9261 0 0.00 0 13729 64 0 0
sp_reserve_write_off 9262 9262 0 0.00 9 16332 290 0 0
sp_supplier_invoice 5159 5159 0 0.00 10 120667 3593 0 0
sp_supplier_order 5192 5192 0 0.00 7 34115 1454 0 0
srv_aggregate_perf_data 110 110 0 0.00 2228 152385 15615 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 888 177790 33116 0 0
srv_find_qd_qs_mism 973428 973428 0 0.00 0 15325 13 0 0
srv_make_invnt_saldo 471 471 0 0.00 86 63219 4231 0 0
srv_make_money_saldo 924 924 0 0.00 1 20357 386 0 0
srv_recalc_idx_stat 7 7 0 0.00 69554 296164 186978 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4677 4677 0 0.00 1 13358 1032 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 2 7757 465 0 0
t$perf-norm:sp_cancel_client_order 1666 1666 0 0.00 0 1661 29 0 0
t$perf-norm:sp_cancel_customer_reserve 648 648 0 0.00 0 1544 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 616 616 0 0.00 0 1290 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 377 377 0 0.00 0 5642 29 0 0
t$perf-norm:sp_cancel_supplier_invoice 360 360 0 0.00 0 9010 64 0 0
t$perf-norm:sp_cancel_supplier_order 389 389 0 0.00 0 109181 7811 0 0
t$perf-norm:sp_cancel_write_off 1006 1006 0 0.00 0 2046 20 0 0
t$perf-norm:sp_client_order 19793 19793 0 0.00 0 15393 40 0 0
t$perf-norm:sp_customer_reserve 918 918 0 0.00 0 15700 41 0 0
t$perf-norm:sp_pay_from_customer 6643 6643 0 0.00 0 6096 21 0 0
t$perf-norm:sp_pay_to_supplier 5416 5416 0 0.00 0 9163 24 0 0
t$perf-norm:sp_reserve_write_off 9262 9262 0 0.00 0 12431 25 0 0
t$perf-norm:sp_supplier_invoice 5159 5159 0 0.00 0 6164 37 0 0
t$perf-norm:sp_supplier_order 5192 5192 0 0.00 0 8359 40 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 783 26 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 2215 33 0 0
t$perf-norm:srv_make_invnt_saldo 471 471 0 0.00 0 1870 22 0 0
t$perf-norm:srv_make_money_saldo 924 924 0 0.00 0 998 7 0 0
t$perf-norm:srv_recalc_idx_stat 7 7 0 0.00 24 165 83 0 0
v_add_invoice_to_stock 9502 9252 250 2.63 0 6288 27 0 250
v_all_customers 36074 36074 0 0.00 0 122 4 0 0
v_all_suppliers 28819 28819 0 0.00 0 122 4 0 0
v_all_wares 39580 39580 0 0.00 0 122 4 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 189 12 0 0
v_cancel_client_order 3332 3332 0 0.00 0 4318 11 0 0
v_cancel_customer_prepayment 1232 1232 0 0.00 0 2217 12 0 0
v_cancel_customer_reserve 1296 1296 0 0.00 0 1649 11 0 0
v_cancel_payment_to_supplier 754 754 0 0.00 0 2087 14 0 0
v_cancel_supplier_invoice 730 720 10 1.36 0 560 24 0 10
v_cancel_supplier_order 778 778 0 0.00 0 928 11 0 0
v_cancel_write_off 2012 2012 0 0.00 0 3547 26 0 0
v_max_id_clo_ord 5190 5190 0 0.00 0 884 5 0 0
v_max_id_clo_res 918 918 0 0.00 0 1903 14 0 0
v_max_id_ord_sup 5151 5151 0 0.00 0 4058 6 0 0
v_max_non_paid_invoice 1346 1346 0 0.00 0 711 8 0 0
v_max_non_paid_realizn 3446 3446 0 0.00 0 2314 9 0 0
v_min_id_clo_ord 5190 5190 0 0.00 0 2596 8 0 0
v_min_id_clo_res 918 918 0 0.00 0 3871 11 0 0
v_min_id_ord_sup 5151 5151 0 0.00 0 6729 14 0 0
v_min_non_paid_invoice 5415 5415 0 0.00 0 4155 14 0 0
v_min_non_paid_realizn 6643 6643 0 0.00 0 3954 19 0 0
v_our_firm 9898 9898 0 0.00 0 58 5 0 0
v_reserve_write_off 18522 18522 0 0.00 0 4486 11 0 0
x_lock_depdocs_on_canc_invoice 10429 10429 0 0.00 0 13755 138 0 0
x_lock_depdocs_on_canc_sup_ord 389 389 0 0.00 0 10900 464 0 0
x_make_qstorno_1000_1200 5192 5192 0 0.00 3 34105 1282 0 0
x_make_qstorno_1200_2000 5159 5159 0 0.00 5 118856 3373 0 0
x_make_qstorno_2100_3300 437044 437044 0 0.00 0 71544 264 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-04-2022 13:38:15 to 21-04-2022 13:38:19.

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:22 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:23 25417 8192000000 9727342864 10030268416 802896992 8.926 100 83 166 0 830259120 7261744 20100384
11:09:51 29133 8192000000 9765157584 10074779648 836572288 9.265 100 92 184 0 872654576 14050416 22031872
11:11:33 18903 8192000000 9812688368 10136555520 873934688 9.639 100 79 158 0 919446096 27303936 18207472
11:12:59 7339 8192000000 9879280816 10212900864 914071072 10.038 100 84 166 2 992525776 60432912 18021792
11:14:14 6369 8192000000 9914077600 10258350080 945175440 10.344 100 84 166 0 1028764992 66221168 17368384
11:15:23 2762 8192000000 9942868368 10297090048 979407168 10.678 100 88 171 0 1064500288 67085840 18007280
11:16:27 2041 8192000000 9981813216 10342375424 1020838592 11.080 100 89 174 0 1105104880 66540320 17725968
11:17:32 2374 8192000000 9992145472 10363604992 1035867520 11.225 100 79 153 0 1109715136 59069664 14777952
11:18:37 921 8192000000 10016003216 10387816448 1056920224 11.427 100 95 186 1 1139092848 62513584 19659040
11:19:40 1493 8192000000 10038287696 10417573888 1063062288 11.486 100 91 173 0 1162076272 81332640 17681344
11:20:45 1453 8192000000 10045352560 10424774656 1076311472 11.612 100 95 182 3 1166278080 71351536 18615072
11:21:49 1182 8192000000 10065350592 10446245888 1082062576 11.667 100 96 188 2 1189448048 88279728 19105744
11:22:53 1606 8192000000 10067926016 10454843392 1084271296 11.688 100 91 181 0 1194873088 92106592 18495200
11:23:57 3626 8192000000 10052411232 10437550080 1091386976 11.756 100 92 175 4 1174869600 65250928 18231696
11:25:02 935 8192000000 10068299424 10460971008 1095094560 11.791 100 96 185 0 1189575264 76015344 18465360
11:26:04 995 8192000000 10055773840 10447224832 1086959840 11.714 99 92 175 4 1177108224 72071456 18076928
11:27:06 3155 8192000000 10079290304 10471419904 1098798448 11.826 100 84 154 3 1198308944 83877824 15632672
11:28:10 1310 8192000000 10075477216 10462339072 1086830048 11.713 99 91 181 0 1189108784 83866720 18412016
11:29:14 1040 8192000000 10068798208 10459865088 1101312304 11.850 100 92 180 0 1190376960 70128736 18935920
11:30:16 1231 8192000000 10057168736 10449641472 1095262848 11.793 100 94 184 1 1179144384 65038864 18842672
11:31:18 1275 8192000000 10064131904 10452385792 1096994496 11.809 100 99 184 1 1185086752 69249120 18843136
11:32:20 1224 8192000000 10025351600 10404634624 1068623312 11.539 100 95 187 1 1146781184 59225648 18932224
11:33:23 2009 8192000000 10001790848 10376310784 1047005008 11.332 100 80 149 2 1133364800 72040432 14319360
11:34:26 1052 8192000000 10024667456 10401034240 1055005232 11.409 100 95 185 2 1142409712 68133744 19270736
11:35:29 2782 8192000000 10013193984 10390994944 1054783616 11.407 100 86 160 1 1131267904 60571408 15912880
11:36:32 1339 8192000000 9957266016 10327932928 1040906016 11.273 100 79 151 0 1075514352 18812672 15795664
11:37:37 1068 8192000000 10014111744 10388430848 1057880176 11.436 100 90 176 0 1135670416 60173456 17616784
11:38:46 2247 8192000000 10024760704 10408034304 1069706688 11.549 100 80 157 1 1151417712 66028112 15682912
11:39:54 13544 8192000000 10035556560 10417831936 1071839600 11.570 99 67 130 1 1144618128 59894880 12883648
11:41:12 1010 8192000000 10075688736 10458218496 1088896784 11.732 100 91 169 2 1188168464 82710816 16560864
11:42:20 11149 8192000000 10092076912 10483163136 1105442272 11.889 100 74 139 0 1218274704 97766096 15066336
11:43:33 1801 8192000000 10108467856 10507821056 1112213760 11.953 100 90 178 1 1223165312 91217664 19733888
11:44:38 1858 8192000000 10131532256 10523918336 1113299920 11.964 100 76 150 1 1245050416 115445424 16305072
11:45:44 4612 8192000000 10151822032 10545500160 1124656640 12.071 100 82 159 2 1266747616 125806656 16284320
11:46:58 1433 8192000000 10171300336 10575101952 1141552416 12.230 100 92 176 0 1285671280 125098688 19020176
11:48:03 12449 8192000000 10181345824 10588200960 1150171056 12.311 100 60 111 0 1304798896 142067216 12560624
11:49:27 1194 8192000000 10264511136 10656956416 1170086624 12.498 100 95 178 0 1371692096 182126864 19478608
11:50:31 3785 8192000000 10272312288 10673987584 1183029488 12.618 100 85 165 4 1376786016 174831824 18924704
11:51:39 1987 8192000000 10335733952 10744438784 1194324752 12.724 100 92 182 0 1447613776 232166944 21122080
11:52:44 2874 8192000000 10394097552 10804539392 1206692896 12.838 100 94 181 2 1503356656 274999536 21664224
11:53:54 1769 8192000000 10435179456 10850054144 1213387168 12.900 100 88 173 0 1541739936 307419920 20932848
11:55:03 1295 8192000000 10463093776 10888519680 1207340704 12.844 100 97 192 0 1561577952 331480560 22756688
11:56:09 2137 8192000000 10482047184 10909827072 1206894976 12.840 100 86 172 0 1591487776 363919520 20673280
11:57:16 1241 8192000000 10498504144 10922844160 1208799152 12.858 100 94 180 3 1605062960 375048336 21215472
11:58:24 879 8192000000 10557200736 10975653888 1222772720 12.987 100 97 192 1 1664171456 417976256 23422480
11:59:29 1256 8192000000 10585672224 11005333504 1221331472 12.974 100 95 184 0 1689565200 444748128 23485600
12:00:34 915 8192000000 10569922128 11011080192 1207212784 12.843 100 98 194 0 1672710288 442444976 23052528
12:01:38 1942 8192000000 10576528688 11040718848 1200390832 12.780 100 86 172 0 1684688256 461173792 23123632
12:02:43 2828 8192000000 10611905168 11053735936 1203356016 12.807 100 92 180 0 1711659504 485876368 22427120
12:03:50 1969 8192000000 10494215328 10961076224 1184101632 12.628 100 84 165 1 1598894528 395603136 19189760
12:04:56 1394 8192000000 10526188352 11018076160 1176386080 12.556 100 80 155 1 1626314896 430595600 19333216
12:06:02 9546 8192000000 10459864016 10891157504 1173159584 12.526 100 54 105 0 1569774432 383639648 12975200
12:07:14 12911 8192000000 10395746416 10915160064 1166065632 12.460 100 41 82 0 1540083312 363434688 10582992
12:08:52 10171 8192000000 10300716368 10794819584 1145432512 12.267 100 27 54 0 1415984720 263335248 7216960
12:10:04 78740 8192000000 10130800720 10573242368 1104946272 11.885 95 16 32 0 1186229808 77453920 3829616
12:12:28 36980 8192000000 10145727520 10607316992 1147420944 12.285 99 26 50 2 1225300592 71973968 5905680
12:14:08 72234 8192000000 10189783824 10624884736 1136172320 12.180 98 45 84 1 1242742240 97674656 8895264
12:16:29 177788 8192000000 10151182112 10610589696 1112460192 11.956 96 32 64 2 1223100512 102778080 7862240
12:20:29 36896 8192000000 10176367280 10626629632 1139926816 12.215 100 16 32 1 1270227232 126132512 4167904
12:22:12 45013 8192000000 10212260976 10649026560 1140608192 12.221 99 58 110 2 1292028944 138433024 12987728
12:24:05 51287 8192000000 10168322704 10660855808 1151264208 12.321 100 15 30 0 1267649312 112976592 3408512
12:25:58 23207 8192000000 10093330944 10574614528 1124598688 12.070 98 23 45 1 1186422800 57102256 4721856
12:27:37 42543 8192000000 10105013136 10565271552 1136987440 12.187 100 21 42 1 1208349120 66493872 4867808
12:29:22 72980 8192000000 10126531824 10577235968 1136541840 12.183 100 26 52 0 1201656432 59301248 5813344
12:31:44 33386 8192000000 10084511280 10550726656 1139526352 12.211 100 20 39 0 1189477024 45635248 4315424
12:33:25 54514 8192000000 10054585088 10558947328 1095381488 11.794 97 16 32 0 1161926992 62835600 3709904
12:35:42 55862 8192000000 10076990736 10511630336 1108657936 11.920 99 27 54 1 1162338688 48080832 5599920
12:37:43 83319 8192000000 10098511120 10554949632 1117062560 11.999 99 38 76 1 1196858608 72152704 7643344
12:40:08 49308 8192000000 10073224304 10533117952 1133745072 12.157 100 35 68 0 1198365744 57893728 6726944
12:42:01 63550 8192000000 10055963728 10536214528 1119432992 12.022 98 26 47 0 1160463792 36493216 4537584
12:44:06 41179 8192000000 10065310048 10523664384 1105489920 11.890 98 24 48 0 1132606192 21915056 5201216
12:45:55 17841 8192000000 10107841104 10541129728 1140626832 12.221 100 58 112 1 1201428720 50100736 10701152
12:47:14 23660 8192000000 10192416768 10625654784 1140550016 12.221 99 42 84 0 1258400288 109172752 8677520
12:48:40 27944 8192000000 10125574896 10573729792 1135039056 12.169 99 37 73 2 1196577728 54685200 6853472
12:50:09 51185 8192000000 10080392880 10564247552 1100636256 11.844 97 23 46 0 1184082544 78393824 5052464
12:52:02 44297 8192000000 10099676832 10529325056 1100700160 11.844 97 46 88 0 1150282912 40453792 9128960
12:53:47 43255 8192000000 10115881728 10593452032 1113892976 11.969 99 35 68 1 1214938448 94295520 6749952
12:55:38 20570 8192000000 10076313040 10501156864 1069237088 11.545 96 49 98 1 1125768736 46833280 9698368
12:56:59 93428 8192000000 10087808352 10557747200 1112924768 11.960 100 52 102 3 1205858832 82256224 10677840
12:59:44 6338 8192000000 10060216544 10525417472 1119537664 12.023 100 32 65 0 1165382544 39040800 6804080
13:01:03 71303 8192000000 10069828192 10532163584 1094301312 11.784 97 10 20 0 1163553264 67208816 2043136
13:03:29 93468 8192000000 10058167152 10535702528 1122657664 12.052 100 7 14 2 1158540240 34617408 1265168
13:06:06 34706 8192000000 10049918576 10479575040 1088487344 11.728 97 29 57 1 1134678976 39716048 6475584
13:07:41 49760 8192000000 10129565728 10567389184 1107930992 11.913 98 27 54 1 1206357088 92353024 6073072
13:09:35 22419 8192000000 10196459328 10634297344 1141266176 12.227 100 49 97 1 1278094256 126369216 10458864
13:11:02 43470 8192000000 10205409984 10631987200 1130898656 12.130 98 43 80 2 1268937328 129291536 8747136
13:12:47 40564 8192000000 10241740880 10708389888 1135945440 12.177 99 47 94 2 1315805872 169148784 10711648
13:14:28 52214 8192000000 10148946832 10646851584 1130248432 12.124 99 16 32 0 1299377312 165697120 3431760
13:16:57 21599 8192000000 10100216704 10518544384 1136082432 12.179 100 48 93 2 1190298624 45954416 8261776
13:18:19 10308 8192000000 10121859616 10538119168 1135502320 12.173 100 58 116 0 1204868128 57957856 11407952
13:19:30 92545 8192000000 10056074320 10530906112 1092534208 11.767 96 42 82 3 1167851024 66878928 8437888
13:22:03 96361 8192000000 10051642720 10519609344 1098418592 11.823 97 5 10 0 1148649120 48975856 1254672
13:24:54 65920 8192000000 10099939456 10557751296 1118119744 12.009 99 18 36 1 1174302384 52421664 3760976
13:27:03 95586 8192000000 10052665120 10527764480 1079775744 11.645 96 10 21 1 1134207456 52441120 1990592
13:29:42 31351 8192000000 10079508784 10527735808 1125846880 12.082 100 32 64 1 1185646576 52787792 7011904
13:31:18 31656 8192000000 10118050688 10550325248 1111351024 11.945 99 34 68 1 1204494368 86095952 7047392
13:32:50 29309 8192000000 10084188464 10511556608 1104310064 11.879 98 38 74 3 1153560816 41642336 7608416
13:34:25 36762 8192000000 10082887264 10548727808 1105834880 11.893 98 36 71 0 1171528336 58371456 7322000
13:36:02 39184 8192000000 10068384720 10538627072 1111054688 11.942 99 32 64 1 1190026608 72438592 6533328

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 21-04-2022 13:38:21 to 21-04-2022 13:38:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 125 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-04-2022 13:38:38 to 21-04-2022 13:38:40.

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          205532
MON$OLDEST_ACTIVE               208475
MON$OLDEST_SNAPSHOT             208475
MON$NEXT_TRANSACTION            208475
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                       2027424
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             756
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 ---
:460: 
:461: mscs-test-161.budzdorov.ru    Thu Apr 21 13:37:23 2022
:462:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:463: 
:464: 
:465: mscs-test-161.budzdorov.ru    Thu Apr 21 13:37:25 2022
:466:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:467: 
--- end of diff output ---

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

21.04.22 13:38:41. 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-21 13:38:44 No severe PSQL-related problems occured
21.04.22 13:38:45. End of report.