Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.12.2021 07: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 16398836 kB
MemAvailable 31567456 kB
Buffers 0 kB
Cached 15314552 kB
SwapCached 0 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.1
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 2021-12-01 07:37:07
DTS_END                         2021-12-01 07:37:07.9100
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/52100
TRN_ID                          225456
ATT_ID                          640
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: 9035.93 23632 1084312
customer order: creation 162.78 411 19533
customer order: refuse 14.29 362 1715
order to supplier: creation 43.56 1478 5227
order to supplier: removal 3.34 139828 401
invoice (draft): creation 41.97 3690 5036
invoice (draft): removal 53.03 1170 6364
invoice accept: apply 38.86 19589 4663
invoice accept: cancel 48.31 941 5797
customer reserve: creation 3599.48 191 431937
customer reserve: removal 4750.50 75 570060
realization accept: apply 77.95 229 9354
realization accept: cancel 81.61 141 9793
payment from customer: creation 55.17 125 6620
payment from customer: removal 5.43 111 652
payment to supplier: creation 46.06 147 5527
payment to supplier: removal 3.48 75 417
service: total inventory turnovers 3.37 4873 404
service: total monetary turnovers 5.94 648 713
service: aggregate perf. data 0.79 18477 95
service: refresh index statistics 0.03 280069 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-12-2021 07:39:18 to 01-12-2021 07:39:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8980
TEST_TIME 2 6399
TEST_TIME 3 6245
TEST_TIME 4 5855
TEST_TIME 5 5844
TEST_TIME 6 5728
TEST_TIME 7 5361
TEST_TIME 8 5625
TEST_TIME 9 5420
TEST_TIME 10 5698
TEST_TIME 11 5819
TEST_TIME 12 5667
TEST_TIME 13 5930
TEST_TIME 14 5946
TEST_TIME 15 6075
TEST_TIME 16 6213
TEST_TIME 17 6184
TEST_TIME 18 6396
TEST_TIME 19 6461
TEST_TIME 20 6673
TEST_TIME 21 7154
TEST_TIME 22 7472
TEST_TIME 23 7546
TEST_TIME 24 8024
TEST_TIME 25 7824
TEST_TIME 26 7709
TEST_TIME 27 7949
TEST_TIME 28 8111
TEST_TIME 29 8515
TEST_TIME 30 9555
TEST_TIME 31 9782
TEST_TIME 32 10675
TEST_TIME 33 10569
TEST_TIME 34 10639
TEST_TIME 35 10630
TEST_TIME 36 10584
TEST_TIME 37 10634
TEST_TIME 38 10489
TEST_TIME 39 10440
TEST_TIME 40 10523
TEST_TIME 41 10391
TEST_TIME 42 10252
TEST_TIME 43 10113
TEST_TIME 44 9947
TEST_TIME 45 9897
TEST_TIME 46 9805
TEST_TIME 47 9858
TEST_TIME 48 9758
TEST_TIME 49 9677
TEST_TIME 50 9782
TEST_TIME 51 9723
TEST_TIME 52 9755
TEST_TIME 53 9684
TEST_TIME 54 9590
TEST_TIME 55 9511
TEST_TIME 56 9399
TEST_TIME 57 9400
TEST_TIME 58 9437
TEST_TIME 59 9387
TEST_TIME 60 9416
TEST_TIME 61 9424
TEST_TIME 62 9412
TEST_TIME 63 9396
TEST_TIME 64 9431
TEST_TIME 65 9524
TEST_TIME 66 9514
TEST_TIME 67 9573
TEST_TIME 68 9556
TEST_TIME 69 9532
TEST_TIME 70 9624
TEST_TIME 71 9643
TEST_TIME 72 9672
TEST_TIME 73 9546
TEST_TIME 74 9471
TEST_TIME 75 9444
TEST_TIME 76 9400
TEST_TIME 77 9347
TEST_TIME 78 9288
TEST_TIME 79 9279
TEST_TIME 80 9191
TEST_TIME 81 9217
TEST_TIME 82 9220
TEST_TIME 83 9178
TEST_TIME 84 9213
TEST_TIME 85 9163
TEST_TIME 86 9201
TEST_TIME 87 9220
TEST_TIME 88 9223
TEST_TIME 89 9237
TEST_TIME 90 9279
TEST_TIME 91 9332
TEST_TIME 92 9312
TEST_TIME 93 9346
TEST_TIME 94 9372
TEST_TIME 95 9425
TEST_TIME 96 9347
TEST_TIME 97 9256
TEST_TIME 98 9310
TEST_TIME 99 9246
TEST_TIME 100 9236
TEST_TIME 101 9189
TEST_TIME 102 9148
TEST_TIME 103 9140
TEST_TIME 104 9098
TEST_TIME 105 9075
TEST_TIME 106 9042
TEST_TIME 107 9109
TEST_TIME 108 9108
TEST_TIME 109 9093
TEST_TIME 110 9103
TEST_TIME 111 9164
TEST_TIME 112 9208
TEST_TIME 113 9240
TEST_TIME 114 9296
TEST_TIME 115 9250
TEST_TIME 116 9190
TEST_TIME 117 9161
TEST_TIME 118 9160
TEST_TIME 119 9113
TEST_TIME 120 9039

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 01-12-2021 07:39:21 to 01-12-2021 07:39:25.

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 1484345 1484345 0 0.00 0 15344 7 0 0
doc_list_biud 1484344 1484344 0 0.00 0 35980 37 0 0
sp_add_invoice_to_stock 4801 4663 138 2.87 45 171068 19589 0 138
sp_cancel_adding_invoice 5797 5797 0 0.00 5 129415 942 0 0
sp_cancel_client_order 1715 1715 0 0.00 5 8170 363 0 0
sp_cancel_customer_reserve 570060 570060 0 0.00 1 35983 76 0 0
sp_cancel_pay_from_customer 652 652 0 0.00 3 4090 111 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 3 3703 76 0 0
sp_cancel_supplier_invoice 6369 6364 5 0.07 2 31551 1170 0 5
sp_cancel_supplier_order 401 401 0 0.00 11 1412853 139828 0 0
sp_cancel_write_off 9793 9793 0 0.00 1 14595 141 0 0
sp_client_order 19533 19533 0 0.00 4 36371 412 0 0
sp_customer_reserve 431937 431937 0 0.00 0 135632 191 0 0
sp_fill_shopping_cart 29792 29792 0 0.00 1 19326 41 0 0
sp_fill_shopping_cart_clo_res 431930 431930 0 0.00 0 17781 17 0 0
sp_get_clo_for_invoice 4656 4656 0 0.00 0 21800 378 0 0
sp_kill_cost_storno 16659 16659 0 0.00 0 10798 73 0 0
sp_kill_qstorno_ret_qs2qd 1156678 1156678 0 0.00 0 35971 41 0 0
sp_kill_qty_storno 608140 608140 0 0.00 0 35979 89 0 0
sp_lock_selected_doc 19549 19549 0 0.00 0 9666 5 0 0
sp_make_cost_storno 26157 26157 0 0.00 0 12539 65 0 0
sp_multiply_rows_for_pdistr 26157 26157 0 0.00 0 8564 37 0 0
sp_multiply_rows_for_qdistr 19533 19533 0 0.00 0 36148 189 0 0
sp_pay_from_customer 6620 6620 0 0.00 4 16702 126 0 0
sp_pay_to_supplier 5527 5527 0 0.00 6 17485 147 0 0
sp_payment_common 12147 12147 0 0.00 1 9894 23 0 0
sp_qd_handle_on_cancel_clo 1715 1715 0 0.00 0 8164 327 0 0
sp_qd_handle_on_invoice_upd_sts 10453 10453 0 0.00 0 27365 452 0 0
sp_qd_handle_on_reserve_upd_sts 9354 9354 0 0.00 0 13871 78 0 0
sp_reserve_write_off 9354 9354 0 0.00 7 13945 229 0 0
sp_supplier_invoice 5036 5036 0 0.00 8 93505 3691 0 0
sp_supplier_order 5227 5227 0 0.00 5 52139 1478 0 0
srv_aggregate_perf_data 95 95 0 0.00 3076 179192 18477 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2204 178036 44470 0 0
srv_find_qd_qs_mism 1039637 1039637 0 0.00 0 10925 5 0 0
srv_make_invnt_saldo 404 404 0 0.00 64 124129 4873 0 0
srv_make_money_saldo 713 713 0 0.00 1 18385 649 0 0
srv_recalc_idx_stat 4 4 0 0.00 146209 382832 280070 0 0
t$perf-abend:sp_add_invoice_to_stock 138 138 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 4664 4664 0 0.00 0 21491 221 0 0
t$perf-norm:sp_cancel_adding_invoice 381 381 0 0.00 1 15026 179 0 0
t$perf-norm:sp_cancel_client_order 1715 1715 0 0.00 0 2388 12 0 0
t$perf-norm:sp_cancel_customer_reserve 649 649 0 0.00 0 526 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 652 652 0 0.00 0 727 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 2193 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 8466 55 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 23767 2060 0 0
t$perf-norm:sp_cancel_write_off 920 920 0 0.00 0 1227 9 0 0
t$perf-norm:sp_client_order 19533 19533 0 0.00 0 13922 25 0 0
t$perf-norm:sp_customer_reserve 940 940 0 0.00 0 1653 7 0 0
t$perf-norm:sp_pay_from_customer 6620 6620 0 0.00 0 12746 16 0 0
t$perf-norm:sp_pay_to_supplier 5527 5527 0 0.00 0 9169 19 0 0
t$perf-norm:sp_reserve_write_off 9354 9354 0 0.00 0 7944 17 0 0
t$perf-norm:sp_supplier_invoice 5036 5036 0 0.00 0 12500 25 0 0
t$perf-norm:sp_supplier_order 5227 5227 0 0.00 0 3922 19 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 527 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 577 15 0 0
t$perf-norm:srv_make_invnt_saldo 404 404 0 0.00 0 3986 16 0 0
t$perf-norm:srv_make_money_saldo 713 713 0 0.00 0 766 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 9588 9312 276 2.87 0 7888 18 0 276
v_all_customers 35692 35692 0 0.00 0 18 1 0 0
v_all_suppliers 28602 28602 0 0.00 0 39 1 0 0
v_all_wares 39066 39066 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 762 762 0 0.00 0 1520 12 0 0
v_cancel_client_order 3430 3430 0 0.00 0 3319 8 0 0
v_cancel_customer_prepayment 1304 1304 0 0.00 0 4068 17 0 0
v_cancel_customer_reserve 1298 1298 0 0.00 0 1426 6 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 2776 13 0 0
v_cancel_supplier_invoice 818 808 10 1.22 0 1178 15 0 10
v_cancel_supplier_order 802 802 0 0.00 0 297 4 0 0
v_cancel_write_off 1840 1840 0 0.00 0 5103 23 0 0
v_max_id_clo_ord 5226 5226 0 0.00 0 2812 2 0 0
v_max_id_clo_res 940 940 0 0.00 0 9065 34 0 0
v_max_id_ord_sup 5033 5033 0 0.00 0 2883 5 0 0
v_max_non_paid_invoice 1485 1485 0 0.00 0 3045 4 0 0
v_max_non_paid_realizn 3426 3426 0 0.00 0 1518 4 0 0
v_min_id_clo_ord 5226 5226 0 0.00 0 3549 6 0 0
v_min_id_clo_res 940 940 0 0.00 0 1457 6 0 0
v_min_id_ord_sup 5033 5033 0 0.00 0 5803 7 0 0
v_min_non_paid_invoice 5527 5527 0 0.00 0 7751 16 0 0
v_min_non_paid_realizn 6620 6620 0 0.00 0 9889 14 0 0
v_our_firm 9762 9762 0 0.00 0 16 1 0 0
v_reserve_write_off 18708 18708 0 0.00 0 5712 8 0 0
x_lock_depdocs_on_canc_invoice 11757 11757 0 0.00 0 18502 123 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 11660 450 0 0
x_make_qstorno_1000_1200 5227 5227 0 0.00 2 52071 1391 0 0
x_make_qstorno_1200_2000 5036 5036 0 0.00 3 92882 3536 0 0
x_make_qstorno_2100_3300 431937 431937 0 0.00 0 135630 171 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 01-12-2021 07:39:25 to 01-12-2021 07:39:30.

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
05:07:11 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:12 29350 8192000000 9728455920 10026336256 799614464 8.892 100 86 172 0 828032352 7878448 20539440
05:09:52 28249 8192000000 9765444512 10072551424 841468176 9.315 100 93 186 0 876847024 13641664 21737184
05:11:26 14765 8192000000 9808935984 10125766656 874382608 9.644 100 90 180 0 914988480 20080752 20525120
05:12:44 13852 8192000000 9872008128 10201960448 907899760 9.977 100 78 155 1 973838912 48005264 17933888
05:13:59 8124 8192000000 9915206208 10258329600 943216688 10.325 100 75 150 0 1036329184 77164384 15948112
05:15:11 5364 8192000000 9943200896 10293907456 978113008 10.666 100 79 155 1 1050465264 56571216 15781040
05:16:19 3800 8192000000 9997559968 10354593792 1023851616 11.109 100 91 182 1 1108890304 65892432 19146256
05:17:25 2533 8192000000 10030355648 10395525120 1048239104 11.344 100 85 170 1 1133928640 68816304 16873232
05:18:30 2096 8192000000 10047407184 10420338688 1059401360 11.451 100 84 168 0 1159545968 83392528 16752080
05:19:34 14540 8192000000 10060070224 10431442944 1068340592 11.536 100 85 171 1 1174287232 88630032 17316608
05:20:50 4957 8192000000 9994915216 10393681920 1079747136 11.645 100 40 76 2 1131212912 43782656 7683120
05:21:57 3187 8192000000 10028961504 10424688640 1082445344 11.671 100 46 92 0 1137962256 46137856 9379056
05:23:03 28460 8192000000 10005401712 10402516992 1091319152 11.755 100 46 91 1 1118379920 17406752 9654016
05:24:38 3748 8192000000 10006919952 10410725376 1091524016 11.757 100 41 85 1 1124668032 24029808 9114208
05:25:43 8295 8192000000 9996661904 10401378304 1094823568 11.788 100 36 72 0 1130256352 26272000 9160784
05:26:55 3728 8192000000 9997045664 10384486400 1073066704 11.581 98 50 103 4 1099727248 15396240 11264304
05:28:01 26828 8192000000 10012211152 10400256000 1082895168 11.675 99 61 123 1 1107314864 10740032 13679664
05:29:33 7072 8192000000 9989211392 10378240000 1036316896 11.229 97 40 80 3 1060768384 15980256 8471232
05:30:40 7756 8192000000 9958225376 10342813696 1005647312 10.933 96 37 74 0 1035489312 21999168 7842832
05:31:49 10032 8192000000 9942433808 10323693568 993494016 10.815 97 42 83 1 1029543872 26756768 9293088
05:33:03 43258 8192000000 9942914864 10314153984 1003005968 10.908 97 42 83 2 1021482448 9704448 8772032
05:34:46 6219 8192000000 9963010992 10359349248 1033344624 11.201 99 59 116 1 1062575056 16329040 12901392
05:35:58 11224 8192000000 9960952176 10350940160 1038157952 11.247 99 44 86 2 1066901344 18801936 9941456
05:37:12 6665 8192000000 9993685328 10379395072 1068350880 11.536 100 38 73 1 1102944832 27076624 7517328
05:38:24 59647 8192000000 10025682176 10426236928 1086926960 11.713 100 41 82 0 1157507008 61524160 9055888
05:40:28 18124 8192000000 10047006864 10458488832 1107504320 11.909 100 48 96 1 1155783184 38110288 10168576
05:41:50 19513 8192000000 10081132720 10484092928 1114582112 11.976 100 53 106 1 1189223760 63468608 11173040
05:43:16 14842 8192000000 10088850464 10501267456 1134506832 12.164 100 41 82 0 1211501088 67482848 9511408
05:44:34 22020 8192000000 10168293216 10574577664 1146103792 12.273 100 50 99 1 1265420320 108484816 10831712
05:46:05 39701 8192000000 10187704256 10602459136 1154547936 12.352 100 43 86 2 1301051520 136388032 10115552
05:47:59 5217 8192000000 10237854304 10647478272 1172385504 12.519 100 56 110 0 1342878976 158429424 12064048
05:49:11 16816 8192000000 10283727120 10694115328 1174187488 12.536 100 67 130 1 1379948256 191058096 14702672
05:50:33 8661 8192000000 10297410576 10718662656 1170379696 12.500 99 50 98 2 1396196880 214764096 11053088
05:51:49 13850 8192000000 10306723872 10737152000 1185674176 12.643 100 57 114 1 1416254624 217218320 13362128
05:53:12 4204 8192000000 10337107408 10758766592 1183302160 12.621 100 70 139 0 1436374816 236959120 16113536
05:54:26 3612 8192000000 10411106032 10815913984 1185003472 12.637 100 79 158 0 1510372048 307127280 18241296
05:55:40 3794 8192000000 10437023392 10851868672 1190631488 12.689 100 85 167 2 1534745728 323714096 20400144
05:56:49 3530 8192000000 10442447312 10844057600 1169133280 12.489 100 86 172 0 1541206528 351667120 20406128
05:57:58 2202 8192000000 10485382608 10903584768 1187282992 12.658 100 91 182 0 1582955680 374073072 21599616
05:59:08 4512 8192000000 10491330784 10914054144 1186343136 12.649 100 67 132 3 1589272272 385829008 17100128
06:00:18 14410 8192000000 10455236496 10894827520 1179337584 12.584 100 42 82 0 1553460544 363678000 10444960
06:01:40 4769 8192000000 10472323904 10909732864 1178411520 12.575 100 64 124 0 1587609648 394160752 15037376
06:02:49 7181 8192000000 10515286608 10940268544 1173403984 12.529 100 44 87 1 1614172336 429325616 11442736
06:04:00 7320 8192000000 10611824400 11040382976 1165761584 12.457 100 45 90 1 1706340848 527895424 12683840
06:05:20 6068 8192000000 10604546192 11035459584 1157945840 12.384 100 51 102 0 1697340464 525720416 13674208
06:06:28 17498 8192000000 10586842912 11043246080 1142179344 12.236 100 32 63 1 1680317872 529137632 9000896
06:07:47 31627 8192000000 10294407312 10802495488 1132715600 12.147 99 19 38 0 1420541584 282815840 5010144
06:09:20 17614 8192000000 10116240496 10642722816 1108405904 11.917 99 20 40 0 1255101840 142173904 4522032
06:11:13 86041 8192000000 10066964576 10508759040 1098281696 11.821 97 11 21 0 1128153232 28289744 1581792
06:13:40 86273 8192000000 10075070304 10538967040 1116855408 11.997 98 15 30 1 1154182416 34297392 3029616
06:16:08 48917 8192000000 10082680224 10497331200 1117906896 12.007 99 41 81 4 1171973104 46055648 8010560
06:17:58 52290 8192000000 10076708336 10489761792 1136770720 12.185 100 20 40 1 1165285232 24377072 4137440
06:19:53 111060 8192000000 10117324592 10560729088 1116708624 11.996 98 31 61 2 1198724816 74993776 7022416
06:22:48 178036 8192000000 10112446128 10557325312 1080570720 11.653 95 32 64 0 1156763776 69319056 6874000
06:26:49 42562 8192000000 10146377200 10620567552 1144566256 12.258 100 24 48 0 1257684784 107022432 6096096
06:29:34 31169 8192000000 10072093216 10536112128 1124454304 12.069 99 12 23 1 1172039664 45136544 2448816
06:31:06 35990 8192000000 10076201760 10542055424 1129864240 12.120 100 11 22 1 1192962592 60737264 2361088
06:32:46 70362 8192000000 10131513008 10612121600 1129815152 12.120 100 13 27 0 1217160496 84710736 2634608
06:35:02 32997 8192000000 10058918816 10595815424 1122667808 12.052 100 10 20 0 1162059392 36921248 2470336
06:36:51 39847 8192000000 10086873040 10568609792 1131750272 12.138 100 23 46 0 1212768800 76438960 4579568
06:38:34 44432 8192000000 10104896656 10555822080 1142048736 12.235 99 26 52 0 1201337936 53604848 5684352
06:40:20 70128 8192000000 10173364688 10615717888 1107804176 11.912 96 16 31 2 1190158656 78850608 3503872
06:42:31 60139 8192000000 10080908800 10531262464 1072221152 11.573 94 12 24 0 1133683104 58876256 2585696
06:44:33 55146 8192000000 10068474032 10504978432 1085849696 11.703 97 35 68 0 1128329696 35689840 6790160
06:46:33 59206 8192000000 10072024064 10532552704 1105903680 11.894 98 15 30 1 1187876896 78516576 3456640
06:48:37 40625 8192000000 10091797856 10570440704 1110388112 11.936 98 32 64 0 1189874480 73009072 6477296
06:51:34 80377 8192000000 10084864432 10550448128 1115260944 11.982 98 16 31 0 1146912544 28108176 3543424
06:55:28 44201 8192000000 10062244464 10531799040 1113514992 11.966 98 16 32 0 1175216256 58118112 3583152
06:57:30 100579 8192000000 10079212128 10599870464 1112421408 11.955 98 21 42 0 1190705984 73753680 4530896
07:00:10 65283 8192000000 10086717200 10548576256 1116810464 11.997 99 25 49 1 1169004048 46578240 5615344
07:02:16 22646 8192000000 10059499104 10494644224 1102688624 11.863 98 46 90 2 1142531472 30597296 9245552
07:03:46 54518 8192000000 10031472528 10470105088 1067714928 11.530 96 28 56 2 1134307056 60632640 5959488
07:05:43 62073 8192000000 10070700912 10519846912 1120937552 12.036 100 21 42 0 1207381360 82027120 4416688
07:08:06 67481 8192000000 10071051728 10558820352 1080703120 11.654 95 10 20 0 1135052432 52467728 1881584
07:10:15 53248 8192000000 10062838400 10502696960 1112689376 11.958 98 14 27 1 1153238272 38118256 2430640
07:12:08 96817 8192000000 10085002336 10567114752 1109804336 11.931 98 11 22 0 1193216128 80814048 2597744
07:14:56 41239 8192000000 10060263776 10549022720 1118476096 12.013 99 24 48 0 1149873600 26795664 4601840
07:16:39 34369 8192000000 10063731984 10554146816 1130859216 12.129 100 12 24 0 1193057024 59387936 2809872
07:18:18 75038 8192000000 10076144112 10525679616 1110815472 11.940 98 8 16 0 1148049920 35617600 1616848
07:20:37 108586 8192000000 10081350080 10536804352 1085310800 11.698 96 15 30 0 1128872368 40590768 2970800
07:23:32 51989 8192000000 10116431424 10592415744 1135436496 12.173 100 35 68 1 1227624528 84593312 7594720
07:25:30 70849 8192000000 10152718192 10615353344 1144365552 12.257 100 25 50 0 1225200896 75483904 5351440
07:27:41 49452 8192000000 10128757760 10578931712 1111082016 11.943 97 26 52 1 1220548672 103848400 5618256
07:29:41 37948 8192000000 10143348240 10590343168 1139027152 12.206 100 33 66 2 1220386336 74805808 6553376
07:31:25 53453 8192000000 10049674432 10501136384 1079194608 11.640 96 18 36 0 1127067088 44355680 3516800
07:33:19 114984 8192000000 10071099072 10543423488 1109650416 11.929 99 28 56 0 1199654080 84057152 5946512
07:36:14 62223 8192000000 9730169744 10150076416 826239696 9.161 74 7 13 0 871080640 43480736 1360208

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-12-2021 07:39:31 to 01-12-2021 07:39:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 138 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 01-12-2021 07:39:47 to 01-12-2021 07:39:49.

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          226240
MON$OLDEST_ACTIVE               226241
MON$OLDEST_SNAPSHOT             226241
MON$NEXT_TRANSACTION            226241
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                       2077472
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             783
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2672 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2672 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2672 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2672 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 ---
--- end of diff output ---

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

01.12.21 07:39:50. 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
2021-12-01 07:39:53 No severe PSQL-related problems occured
01.12.21 07:39:54. End of report.