Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.11.2021 07: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 16704368 kB
MemAvailable 31513508 kB
Buffers 0 kB
Cached 14949048 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-11-29 07:37:07
DTS_END                         2021-11-29 07:37:07.8830
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(139:30) ==> SP_CANCEL_WRITE_OFF(41:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/48638
TRN_ID                          235345
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: 9114.20 21294 1093704
customer order: creation 166.83 396 20020
customer order: refuse 14.16 318 1699
order to supplier: creation 45.48 1300 5457
order to supplier: removal 3.24 120461 389
invoice (draft): creation 43.92 3263 5270
invoice (draft): removal 50.93 1017 6111
invoice accept: apply 41.25 18208 4950
invoice accept: cancel 46.45 850 5574
customer reserve: creation 3853.26 178 462391
customer reserve: removal 4570.98 67 548518
realization accept: apply 80.68 163 9681
realization accept: cancel 71.35 115 8562
payment from customer: creation 56.76 106 6811
payment from customer: removal 5.79 108 695
payment to supplier: creation 49.08 120 5889
payment to supplier: removal 2.95 79 354
service: total inventory turnovers 4.18 2625 502
service: total monetary turnovers 6.10 383 732
service: aggregate perf. data 0.79 15834 95
service: refresh index statistics 0.03 260289 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-11-2021 07:38:13 to 29-11-2021 07:38:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 880
TEST_TIME 2 2620
TEST_TIME 3 3632
TEST_TIME 4 4104
TEST_TIME 5 3880
TEST_TIME 6 4140
TEST_TIME 7 4124
TEST_TIME 8 4301
TEST_TIME 9 4818
TEST_TIME 10 5021
TEST_TIME 11 5072
TEST_TIME 12 5582
TEST_TIME 13 6269
TEST_TIME 14 6576
TEST_TIME 15 7019
TEST_TIME 16 7004
TEST_TIME 17 8208
TEST_TIME 18 8778
TEST_TIME 19 8773
TEST_TIME 20 9030
TEST_TIME 21 9301
TEST_TIME 22 9540
TEST_TIME 23 9857
TEST_TIME 24 10103
TEST_TIME 25 11587
TEST_TIME 26 11597
TEST_TIME 27 11480
TEST_TIME 28 11473
TEST_TIME 29 11400
TEST_TIME 30 11132
TEST_TIME 31 11003
TEST_TIME 32 10801
TEST_TIME 33 10688
TEST_TIME 34 10568
TEST_TIME 35 10462
TEST_TIME 36 10442
TEST_TIME 37 10428
TEST_TIME 38 10326
TEST_TIME 39 10261
TEST_TIME 40 10222
TEST_TIME 41 10228
TEST_TIME 42 10454
TEST_TIME 43 10409
TEST_TIME 44 10329
TEST_TIME 45 10383
TEST_TIME 46 10440
TEST_TIME 47 10427
TEST_TIME 48 10379
TEST_TIME 49 10408
TEST_TIME 50 10357
TEST_TIME 51 10439
TEST_TIME 52 10334
TEST_TIME 53 10147
TEST_TIME 54 9999
TEST_TIME 55 10066
TEST_TIME 56 10043
TEST_TIME 57 9954
TEST_TIME 58 9879
TEST_TIME 59 9841
TEST_TIME 60 9771
TEST_TIME 61 9756
TEST_TIME 62 9803
TEST_TIME 63 9799
TEST_TIME 64 9806
TEST_TIME 65 9815
TEST_TIME 66 9786
TEST_TIME 67 9856
TEST_TIME 68 9840
TEST_TIME 69 9890
TEST_TIME 70 9890
TEST_TIME 71 9901
TEST_TIME 72 9992
TEST_TIME 73 10048
TEST_TIME 74 9939
TEST_TIME 75 9910
TEST_TIME 76 9876
TEST_TIME 77 9828
TEST_TIME 78 9784
TEST_TIME 79 9709
TEST_TIME 80 9634
TEST_TIME 81 9595
TEST_TIME 82 9583
TEST_TIME 83 9559
TEST_TIME 84 9518
TEST_TIME 85 9508
TEST_TIME 86 9538
TEST_TIME 87 9545
TEST_TIME 88 9578
TEST_TIME 89 9531
TEST_TIME 90 9558
TEST_TIME 91 9569
TEST_TIME 92 9529
TEST_TIME 93 9586
TEST_TIME 94 9553
TEST_TIME 95 9471
TEST_TIME 96 9446
TEST_TIME 97 9460
TEST_TIME 98 9411
TEST_TIME 99 9431
TEST_TIME 100 9394
TEST_TIME 101 9383
TEST_TIME 102 9388
TEST_TIME 103 9377
TEST_TIME 104 9366
TEST_TIME 105 9386
TEST_TIME 106 9396
TEST_TIME 107 9385
TEST_TIME 108 9431
TEST_TIME 109 9421
TEST_TIME 110 9485
TEST_TIME 111 9409
TEST_TIME 112 9359
TEST_TIME 113 9327
TEST_TIME 114 9288
TEST_TIME 115 9225
TEST_TIME 116 9228
TEST_TIME 117 9205
TEST_TIME 118 9198
TEST_TIME 119 9183
TEST_TIME 120 9116

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 29-11-2021 07:38:16 to 29-11-2021 07:38:20.

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 1520894 1520894 0 0.00 0 24258 7 0 0
doc_list_biud 1520894 1520894 0 0.00 0 74871 31 0 0
sp_add_invoice_to_stock 5109 4950 159 3.11 49 255766 18209 0 159
sp_cancel_adding_invoice 5574 5574 0 0.00 6 60961 850 0 0
sp_cancel_client_order 1699 1699 0 0.00 4 11771 319 0 0
sp_cancel_customer_reserve 548518 548518 0 0.00 1 47341 68 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 3 7167 108 0 0
sp_cancel_pay_to_supplier 354 354 0 0.00 3 5324 79 0 0
sp_cancel_supplier_invoice 6123 6111 12 0.19 3 74887 1017 0 12
sp_cancel_supplier_order 389 389 0 0.00 5 1195055 120461 0 0
sp_cancel_write_off 8562 8562 0 0.00 1 15766 116 0 0
sp_client_order 20020 20020 0 0.00 4 25288 396 0 0
sp_customer_reserve 462391 462391 0 0.00 0 83780 179 0 0
sp_fill_shopping_cart 30739 30739 0 0.00 1 17488 39 0 0
sp_fill_shopping_cart_clo_res 462386 462386 0 0.00 0 28837 18 0 0
sp_get_clo_for_invoice 4939 4939 0 0.00 0 22532 361 0 0
sp_kill_cost_storno 15185 15185 0 0.00 0 24246 56 0 0
sp_kill_qstorno_ret_qs2qd 1112098 1112098 0 0.00 0 74868 37 0 0
sp_kill_qty_storno 585471 585471 0 0.00 0 74871 79 0 0
sp_lock_selected_doc 20247 20247 0 0.00 0 10447 7 0 0
sp_make_cost_storno 27318 27318 0 0.00 0 12471 53 0 0
sp_multiply_rows_for_pdistr 27318 27318 0 0.00 0 10047 27 0 0
sp_multiply_rows_for_qdistr 20020 20020 0 0.00 0 11672 206 0 0
sp_pay_from_customer 6811 6811 0 0.00 4 14992 106 0 0
sp_pay_to_supplier 5889 5889 0 0.00 5 11427 120 0 0
sp_payment_common 12700 12700 0 0.00 1 9595 23 0 0
sp_qd_handle_on_cancel_clo 1699 1699 0 0.00 0 11752 287 0 0
sp_qd_handle_on_invoice_upd_sts 10511 10511 0 0.00 0 41221 346 0 0
sp_qd_handle_on_reserve_upd_sts 9681 9681 0 0.00 0 14667 49 0 0
sp_reserve_write_off 9681 9681 0 0.00 7 16256 163 0 0
sp_supplier_invoice 5270 5270 0 0.00 7 87844 3263 0 0
sp_supplier_order 5457 5457 0 0.00 5 44663 1300 0 0
srv_aggregate_perf_data 95 95 0 0.00 3037 148033 15834 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1140 168782 47678 0 0
srv_find_qd_qs_mism 1046314 1046314 0 0.00 0 16942 7 0 0
srv_make_invnt_saldo 502 502 0 0.00 54 99716 2626 0 0
srv_make_money_saldo 732 732 0 0.00 1 17588 383 0 0
srv_recalc_idx_stat 4 4 0 0.00 153659 422013 260289 0 0
t$perf-abend:sp_add_invoice_to_stock 159 159 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4950 4950 0 0.00 0 14966 134 0 0
t$perf-norm:sp_cancel_adding_invoice 449 449 0 0.00 0 13203 97 0 0
t$perf-norm:sp_cancel_client_order 1699 1699 0 0.00 0 3206 19 0 0
t$perf-norm:sp_cancel_customer_reserve 634 634 0 0.00 0 1563 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 3879 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 354 354 0 0.00 0 420 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 1456 13 0 0
t$perf-norm:sp_cancel_supplier_order 389 389 0 0.00 0 23727 1409 0 0
t$perf-norm:sp_cancel_write_off 998 998 0 0.00 0 1294 7 0 0
t$perf-norm:sp_client_order 20020 20020 0 0.00 0 16889 16 0 0
t$perf-norm:sp_customer_reserve 954 954 0 0.00 0 2371 6 0 0
t$perf-norm:sp_pay_from_customer 6811 6811 0 0.00 0 7669 8 0 0
t$perf-norm:sp_pay_to_supplier 5889 5889 0 0.00 0 2545 7 0 0
t$perf-norm:sp_reserve_write_off 9681 9681 0 0.00 0 19539 11 0 0
t$perf-norm:sp_supplier_invoice 5270 5270 0 0.00 0 3219 14 0 0
t$perf-norm:sp_supplier_order 5457 5457 0 0.00 0 7477 15 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 260 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 356 6 0 0
t$perf-norm:srv_make_invnt_saldo 502 502 0 0.00 0 1619 9 0 0
t$perf-norm:srv_make_money_saldo 732 732 0 0.00 0 1110 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 10192 9874 318 3.12 0 4510 20 0 318
v_all_customers 36132 36132 0 0.00 0 16 1 0 0
v_all_suppliers 30252 30252 0 0.00 0 199 1 0 0
v_all_wares 40040 40040 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 898 898 0 0.00 0 2020 10 0 0
v_cancel_client_order 3398 3398 0 0.00 0 2896 5 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 5601 10 0 0
v_cancel_customer_reserve 1268 1268 0 0.00 0 968 5 0 0
v_cancel_payment_to_supplier 708 708 0 0.00 0 1602 6 0 0
v_cancel_supplier_invoice 846 822 24 2.83 0 5133 24 0 24
v_cancel_supplier_order 778 778 0 0.00 0 243 3 0 0
v_cancel_write_off 1996 1996 0 0.00 0 7805 22 0 0
v_max_id_clo_ord 5457 5457 0 0.00 0 384 1 0 0
v_max_id_clo_res 954 954 0 0.00 0 6629 20 0 0
v_max_id_ord_sup 5262 5262 0 0.00 0 3152 7 0 0
v_max_non_paid_invoice 1482 1482 0 0.00 0 337 2 0 0
v_max_non_paid_realizn 3750 3750 0 0.00 0 3992 3 0 0
v_min_id_clo_ord 5457 5457 0 0.00 0 3820 6 0 0
v_min_id_clo_res 954 954 0 0.00 0 4661 24 0 0
v_min_id_ord_sup 5262 5262 0 0.00 0 6945 12 0 0
v_min_non_paid_invoice 5889 5889 0 0.00 0 6389 13 0 0
v_min_non_paid_realizn 6811 6811 0 0.00 0 6390 15 0 0
v_our_firm 10030 10030 0 0.00 0 20 1 0 0
v_reserve_write_off 19362 19362 0 0.00 0 7072 6 0 0
x_lock_depdocs_on_canc_invoice 11274 11274 0 0.00 0 12132 102 0 0
x_lock_depdocs_on_canc_sup_ord 389 389 0 0.00 0 9817 323 0 0
x_make_qstorno_1000_1200 5457 5457 0 0.00 2 44656 1230 0 0
x_make_qstorno_1200_2000 5270 5270 0 0.00 4 86644 3111 0 0
x_make_qstorno_2100_3300 462391 462391 0 0.00 0 83779 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-11-2021 07:38:21 to 29-11-2021 07:38:25.

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 16 8192000000 8802505296 8815300608 7503728 0.091 3 2 2 0 7605216 65712 35776
05:08:12 41615 8192000000 9722808832 10020544512 793814352 8.834 100 88 176 0 823382400 8494160 21073888
05:09:57 5843 8192000000 9755986752 10062225408 835549680 9.255 100 85 170 0 868995008 13479488 19965840
05:11:12 17619 8192000000 9802247488 10119229440 860801232 9.508 100 85 170 0 908410736 28076352 19533152
05:12:34 11825 8192000000 9878837840 10209566720 910012800 9.997 100 85 170 0 985656784 56834464 18809520
05:13:49 8054 8192000000 9919197472 10267807744 958151904 10.471 100 80 160 0 1040361168 65261952 16947312
05:15:01 3185 8192000000 9981139776 10337476608 1005418992 10.931 100 84 165 1 1093254784 71713264 16122528
05:16:06 2650 8192000000 10018348624 10380066816 1032125744 11.189 100 84 168 1 1123665600 74201024 17338832
05:17:16 1465 8192000000 10010957664 10380066816 1054886032 11.408 100 97 191 2 1123595952 49639296 19070624
05:18:19 2611 8192000000 10030108816 10402926592 1063624464 11.491 100 76 150 2 1142476352 62645840 16206048
05:19:23 6998 8192000000 9987760944 10386624512 1069518384 11.547 100 29 58 1 1108996960 33417392 6061184
05:20:31 8687 8192000000 9983376640 10371821568 1079968448 11.647 100 31 62 1 1104038864 17079712 6990704
05:21:40 9508 8192000000 10016782944 10421956608 1084896144 11.694 100 30 60 0 1114522048 22836160 6789744
05:22:55 68425 8192000000 10014417952 10407669760 1092135552 11.763 100 45 88 3 1120449392 18072160 10241680
05:25:04 5044 8192000000 10030311232 10443182080 1097378160 11.813 100 44 85 6 1151503664 44636304 9489200
05:26:11 6697 8192000000 9998383200 10413076480 1095651840 11.796 100 24 46 1 1118787712 18045600 5090272
05:27:19 72844 8192000000 9961019344 10361978880 1017261264 11.046 94 35 69 1 1050609152 25207888 8140000
05:29:38 5405 8192000000 9956958496 10328645632 1010090912 10.976 98 45 91 0 1042839712 21387712 11361088
05:30:46 4119 8192000000 9950731536 10323529728 1032186528 11.190 100 38 75 0 1061876688 21102416 8587744
05:31:51 10795 8192000000 9950783360 10340204544 1026719616 11.137 99 46 93 0 1056549120 19368752 10460752
05:33:02 19233 8192000000 9976835728 10371764224 1019119856 11.064 98 40 78 0 1057110768 29691872 8299040
05:34:30 5796 8192000000 9983926000 10366193664 1062743616 11.483 100 43 86 1 1089319280 17582016 8993648
05:35:37 5450 8192000000 9983790368 10374369280 1068798352 11.541 100 26 55 2 1097967840 22641744 6527744
05:36:45 45252 8192000000 9981806256 10379268096 1077079936 11.620 100 32 63 1 1096898880 12345696 7473248
05:39:25 12301 8192000000 10084921584 10483826688 1106701568 11.901 100 50 100 3 1190242480 73099280 10441632
05:40:39 20416 8192000000 10107973344 10510716928 1130383328 12.125 100 43 86 2 1216724944 77568096 8773520
05:42:15 19020 8192000000 10196506000 10600808448 1145243600 12.265 100 47 94 0 1286467536 130831296 10392640
05:43:37 30414 8192000000 10229799824 10645168128 1153937584 12.346 100 35 70 1 1339563840 176754416 8871840
05:45:23 6389 8192000000 10305438416 10710364160 1175072000 12.544 100 65 130 0 1415221424 225153440 14995984
05:46:40 13263 8192000000 10314881472 10737790976 1175252592 12.546 100 59 116 1 1417476368 228273808 13949968
05:47:58 7892 8192000000 10351818320 10763464704 1189356032 12.677 100 63 123 0 1458134656 254608480 14170144
05:49:11 2106 8192000000 10436915024 10839891968 1189747568 12.681 100 80 160 0 1536120000 327967712 18404720
05:50:19 1139 8192000000 10437714976 10871074816 1196531056 12.744 100 77 153 0 1539175696 324680576 17964064
05:51:23 3649 8192000000 10426914784 10841792512 1167549216 12.474 100 61 120 1 1527404592 345601904 14253472
05:52:32 12236 8192000000 10411881648 10838048768 1124871568 12.073 98 62 124 1 1502666208 362398784 15395856
05:53:55 7893 8192000000 10460440240 10888441856 1142238304 12.237 99 62 124 0 1554638800 396875360 15525136
05:55:04 2685 8192000000 10379888320 10786594816 1150025024 12.310 100 68 136 1 1468725024 302401488 16298512
05:56:09 14691 8192000000 10342158720 10767122432 1128863728 12.111 99 39 78 0 1430452528 291047104 10541696
05:57:42 26658 8192000000 10341010848 10801520640 1133828144 12.157 99 30 60 0 1443289296 302196912 7264240
05:59:09 26963 8192000000 10373305840 10815541248 1129932240 12.121 100 26 52 1 1462360208 325103296 7324672
06:01:18 29852 8192000000 10302751984 10739957760 1078085008 11.629 97 15 30 0 1380008400 297823136 4100256
06:02:56 35138 8192000000 10031176624 10501775360 1061262048 11.469 96 7 14 0 1138874480 76143872 1468560
06:04:33 26861 8192000000 10052188928 10468196352 1104294448 11.878 100 47 95 2 1136173904 21897504 9981952
06:06:01 95834 8192000000 10082960704 10509529088 1103928608 11.875 98 27 55 1 1158922336 49216400 5777328
06:08:47 92512 8192000000 10020399152 10443231232 1060031584 11.457 94 16 33 1 1100068672 36770816 3266272
06:11:22 38178 8192000000 10099831152 10540240896 1115791296 11.987 100 30 59 1 1193185456 71495792 5898368
06:13:10 25697 8192000000 10120398192 10561568768 1121264576 12.039 99 20 40 0 1208112944 82758896 4089472
06:14:36 93426 8192000000 10154314096 10589937664 1140498432 12.220 100 12 24 0 1236910832 93387136 3025264
06:17:16 91703 8192000000 10158655600 10633342976 1138965088 12.206 99 22 44 0 1273592944 129722464 4905392
06:19:50 57537 8192000000 10079731616 10529976320 1120240640 12.029 98 13 26 0 1171086848 47816944 3029264
06:21:56 24012 8192000000 10118056768 10556407808 1139015408 12.206 99 52 104 1 1207663824 57757072 10891344
06:23:26 67736 8192000000 10083352896 10533871616 1130133264 12.123 98 21 42 0 1179896752 45509408 4254080
06:25:40 37119 8192000000 10120051680 10553671680 1161648320 12.419 100 32 64 0 1214357824 46216064 6493440
06:27:46 48262 8192000000 10147517696 10582204416 1148020896 12.291 99 36 72 0 1219848896 64669088 7158912
06:29:37 116469 8192000000 10140079456 10582491136 1153427920 12.342 100 27 54 1 1200694800 41105968 6160912
06:32:43 28673 8192000000 10062261040 10529386496 1137679856 12.194 99 11 22 0 1188406016 47836528 2889632
06:34:24 56053 8192000000 10077444368 10525065216 1127387120 12.097 98 4 8 0 1182107616 53976240 744256
06:36:23 43535 8192000000 10112906240 10603548672 1149203536 12.302 100 13 25 0 1198389264 46629984 2555744
06:38:12 64847 8192000000 10099340560 10593705984 1148498144 12.295 99 20 40 0 1221077408 68273552 4305712
06:40:18 30997 8192000000 10108733888 10563039232 1157760704 12.382 100 16 32 0 1197528240 36009904 3757632
06:41:49 72729 8192000000 10088034608 10532773888 1047042704 11.332 91 24 48 0 1124639888 72575680 5021504
06:44:04 50226 8192000000 10063206368 10489511936 1103954528 11.875 98 22 44 0 1146214256 37891392 4368336
06:45:55 56063 8192000000 10067967696 10536374272 1070535184 11.557 96 30 60 0 1139157280 62737136 5884960
06:47:56 23002 8192000000 10100952160 10510594048 1087634480 11.720 98 60 120 1 1171768096 71898736 12234880
06:49:21 61682 8192000000 10090215280 10602934272 1099542272 11.833 99 5 10 0 1192276400 91657040 1077088
06:51:26 95860 8192000000 10066742304 10533670912 1103028640 11.866 99 20 40 1 1154976320 47718944 4228736
06:54:12 30199 8192000000 10020901104 10493140992 1063731552 11.492 97 13 26 0 1108319664 41621296 2966816
06:55:42 65533 8192000000 10050665104 10504458240 1116987824 11.999 100 9 18 1 1171591136 52535392 2067920
06:57:50 78916 8192000000 10051638992 10525368320 1076534960 11.614 96 15 30 0 1124992416 45237328 3220128
07:01:24 32886 8192000000 10068061376 10497355776 1119632768 12.024 100 36 72 1 1164654032 37662000 7359264
07:03:06 52906 8192000000 10084702720 10549964800 1111477520 11.946 99 20 41 0 1182070832 66258704 4334608
07:05:01 70494 8192000000 10095567472 10578874368 1110438608 11.937 98 25 50 0 1216721312 101133072 5149632
07:07:35 42074 8192000000 10087552656 10534658048 1123777792 12.063 99 46 92 0 1174546096 41632288 9136016
07:09:17 29476 8192000000 10140247712 10572439552 1125525792 12.079 98 35 70 0 1200976400 68551504 6899104
07:10:54 111109 8192000000 10128803840 10628620288 1125960576 12.083 99 17 34 0 1243065088 113741552 3362960
07:13:45 47623 8192000000 10070117840 10553511936 1144024544 12.253 100 17 34 1 1181372480 33826640 3521296
07:15:33 74983 8192000000 10083166464 10577391616 1130178096 12.123 98 14 28 0 1174611600 41323392 3110112
07:17:54 87915 8192000000 10079091104 10531258368 1113400080 11.965 97 14 28 0 1156182720 39798176 2984464
07:20:22 72730 8192000000 10090436016 10534694912 1144213776 12.255 100 24 49 0 1203873776 54791888 4868112
07:22:36 90252 8192000000 10109485632 10576171008 1139530144 12.211 99 18 36 0 1200948352 58168784 3249424
07:25:17 168759 8192000000 10063517600 10538713088 1097022400 11.809 96 12 24 0 1140794080 41192928 2578752
07:29:06 79947 8192000000 10059434896 10505789440 1095359632 11.794 96 17 34 2 1132081104 33184128 3537344
07:31:34 54925 8192000000 10061829584 10513788928 1121194704 12.038 99 9 18 1 1160635392 37359552 2081136
07:33:34 61529 8192000000 10034925328 10480730112 1062885488 11.484 95 7 14 0 1102115040 37761632 1467920
07:35:36 22109 8192000000 10049699632 10495909888 1112512192 11.956 99 29 58 0 1157920880 39329616 6079072
07:37:00 12208 8192000000 9362102352 9686446080 487728832 5.619 45 24 43 2 521338256 29064816 4544608

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 29-11-2021 07:38:27 to 29-11-2021 07:38:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 159 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-11-2021 07:38:42 to 29-11-2021 07:38:44.

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          235592
MON$OLDEST_ACTIVE               235593
MON$OLDEST_SNAPSHOT             235593
MON$NEXT_TRANSACTION            235593
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                       2038688
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             800
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 ---
:54: 
:55: mscs-test-161.budzdorov.ru    Mon Nov 29 07:37:19 2021
:56:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:57: 
--- end of diff output ---

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

29.11.21 07:38:45. 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-11-29 07:38:47 No severe PSQL-related problems occured
29.11.21 07:38:49. End of report.