Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.02.2021 07:29
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 16448092 kB
MemAvailable 31211832 kB
Buffers 0 kB
Cached 15257840 kB
SwapCached 4772 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.0
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-02-12 07:26:55
DTS_END                         2021-02-12 07:26:55.5280
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/44016
TRN_ID                          225922
ATT_ID                          615
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: 8826.08 24971 1059130
customer order: creation 158.57 451 19029
customer order: refuse 13.58 341 1630
order to supplier: creation 42.81 1470 5137
order to supplier: removal 3.16 131232 379
invoice (draft): creation 42.88 3560 5146
invoice (draft): removal 50.67 1059 6080
invoice accept: apply 38.90 18897 4668
invoice accept: cancel 46.03 884 5523
customer reserve: creation 3649.74 182 437969
customer reserve: removal 4510.71 72 541285
realization accept: apply 76.62 189 9194
realization accept: cancel 75.61 131 9073
payment from customer: creation 53.40 110 6408
payment from customer: removal 5.02 78 602
payment to supplier: creation 44.94 137 5393
payment to supplier: removal 3.29 82 395
service: total inventory turnovers 3.70 3676 444
service: total monetary turnovers 5.64 640 677
service: aggregate perf. data 0.78 19810 93
service: refresh index statistics 0.04 316424 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 12-02-2021 07:29:48 to 12-02-2021 07:29:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2869
TEST_TIME 2 4118
TEST_TIME 3 4556
TEST_TIME 4 4802
TEST_TIME 5 4626
TEST_TIME 6 4709
TEST_TIME 7 4769
TEST_TIME 8 4778
TEST_TIME 9 4837
TEST_TIME 10 5231
TEST_TIME 11 5325
TEST_TIME 12 6263
TEST_TIME 13 6181
TEST_TIME 14 6573
TEST_TIME 15 6982
TEST_TIME 16 6988
TEST_TIME 17 7151
TEST_TIME 18 7344
TEST_TIME 19 7476
TEST_TIME 20 7713
TEST_TIME 21 8263
TEST_TIME 22 8345
TEST_TIME 23 8317
TEST_TIME 24 8763
TEST_TIME 25 9331
TEST_TIME 26 9324
TEST_TIME 27 9720
TEST_TIME 28 10376
TEST_TIME 29 11126
TEST_TIME 30 11265
TEST_TIME 31 11233
TEST_TIME 32 10896
TEST_TIME 33 10690
TEST_TIME 34 10847
TEST_TIME 35 10702
TEST_TIME 36 10582
TEST_TIME 37 10508
TEST_TIME 38 10418
TEST_TIME 39 10335
TEST_TIME 40 10290
TEST_TIME 41 10108
TEST_TIME 42 10117
TEST_TIME 43 10067
TEST_TIME 44 9986
TEST_TIME 45 10052
TEST_TIME 46 10152
TEST_TIME 47 10104
TEST_TIME 48 10051
TEST_TIME 49 10082
TEST_TIME 50 10054
TEST_TIME 51 10015
TEST_TIME 52 9860
TEST_TIME 53 9747
TEST_TIME 54 9628
TEST_TIME 55 9544
TEST_TIME 56 9545
TEST_TIME 57 9534
TEST_TIME 58 9511
TEST_TIME 59 9452
TEST_TIME 60 9457
TEST_TIME 61 9372
TEST_TIME 62 9234
TEST_TIME 63 9197
TEST_TIME 64 9248
TEST_TIME 65 9291
TEST_TIME 66 9283
TEST_TIME 67 9272
TEST_TIME 68 9258
TEST_TIME 69 9342
TEST_TIME 70 9339
TEST_TIME 71 9416
TEST_TIME 72 9373
TEST_TIME 73 9412
TEST_TIME 74 9372
TEST_TIME 75 9400
TEST_TIME 76 9390
TEST_TIME 77 9367
TEST_TIME 78 9408
TEST_TIME 79 9435
TEST_TIME 80 9350
TEST_TIME 81 9250
TEST_TIME 82 9226
TEST_TIME 83 9189
TEST_TIME 84 9144
TEST_TIME 85 9094
TEST_TIME 86 9085
TEST_TIME 87 9030
TEST_TIME 88 8981
TEST_TIME 89 8959
TEST_TIME 90 8946
TEST_TIME 91 8959
TEST_TIME 92 9019
TEST_TIME 93 9070
TEST_TIME 94 9057
TEST_TIME 95 9018
TEST_TIME 96 9012
TEST_TIME 97 9060
TEST_TIME 98 9073
TEST_TIME 99 9106
TEST_TIME 100 9151
TEST_TIME 101 9068
TEST_TIME 102 9013
TEST_TIME 103 8953
TEST_TIME 104 8953
TEST_TIME 105 8919
TEST_TIME 106 8880
TEST_TIME 107 8862
TEST_TIME 108 8872
TEST_TIME 109 8847
TEST_TIME 110 8817
TEST_TIME 111 8788
TEST_TIME 112 8863
TEST_TIME 113 8858
TEST_TIME 114 8825
TEST_TIME 115 8868
TEST_TIME 116 8955
TEST_TIME 117 8965
TEST_TIME 118 8972
TEST_TIME 119 8899
TEST_TIME 120 8830

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-02-2021 07:29:52 to 12-02-2021 07:29:55.

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 1464246 1464246 0 0.00 0 23474 6 0 0
doc_list_biud 1464246 1464246 0 0.00 0 33625 34 0 0
sp_add_invoice_to_stock 4782 4668 114 2.38 26 215320 18898 0 114
sp_cancel_adding_invoice 5523 5523 0 0.00 4 79088 885 0 0
sp_cancel_client_order 1630 1630 0 0.00 4 11913 341 0 0
sp_cancel_customer_reserve 541285 541285 0 0.00 1 28078 73 0 0
sp_cancel_pay_from_customer 602 602 0 0.00 3 3318 78 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 3 5725 83 0 0
sp_cancel_supplier_invoice 6086 6080 6 0.09 2 33631 1060 0 6
sp_cancel_supplier_order 379 379 0 0.00 6 995788 131233 0 0
sp_cancel_write_off 9073 9073 0 0.00 1 8892 131 0 0
sp_client_order 19029 19029 0 0.00 4 72213 451 0 0
sp_customer_reserve 437969 437969 0 0.00 0 108221 183 0 0
sp_fill_shopping_cart 29303 29303 0 0.00 1 15637 42 0 0
sp_fill_shopping_cart_clo_res 437959 437959 0 0.00 0 18144 17 0 0
sp_get_clo_for_invoice 4656 4656 0 0.00 0 39416 449 0 0
sp_kill_cost_storno 15593 15593 0 0.00 0 23468 64 0 0
sp_kill_qstorno_ret_qs2qd 1098102 1098102 0 0.00 0 33623 39 0 0
sp_kill_qty_storno 577820 577820 0 0.00 0 33624 85 0 0
sp_lock_selected_doc 19230 19230 0 0.00 0 4601 3 0 0
sp_make_cost_storno 25651 25651 0 0.00 0 15999 57 0 0
sp_multiply_rows_for_pdistr 25651 25651 0 0.00 0 9315 32 0 0
sp_multiply_rows_for_qdistr 19029 19029 0 0.00 0 64720 250 0 0
sp_pay_from_customer 6408 6408 0 0.00 4 14557 110 0 0
sp_pay_to_supplier 5393 5393 0 0.00 5 11789 138 0 0
sp_payment_common 11801 11801 0 0.00 1 9090 17 0 0
sp_qd_handle_on_cancel_clo 1630 1630 0 0.00 0 11904 306 0 0
sp_qd_handle_on_invoice_upd_sts 10179 10179 0 0.00 0 25802 410 0 0
sp_qd_handle_on_reserve_upd_sts 9194 9194 0 0.00 0 16547 71 0 0
sp_reserve_write_off 9194 9194 0 0.00 7 16577 190 0 0
sp_supplier_invoice 5146 5146 0 0.00 6 109515 3560 0 0
sp_supplier_order 5137 5137 0 0.00 5 23724 1470 0 0
srv_aggregate_perf_data 94 93 1 1.06 3262 226234 19810 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 934 180223 47588 0 0
srv_find_qd_qs_mism 1014639 1014639 0 0.00 0 20257 5 0 0
srv_make_invnt_saldo 444 444 0 0.00 51 114784 3676 0 0
srv_make_money_saldo 677 677 0 0.00 1 27748 641 0 0
srv_recalc_idx_stat 5 5 0 0.00 88723 604045 316424 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4669 4669 0 0.00 0 21570 185 0 0
t$perf-norm:sp_cancel_adding_invoice 380 380 0 0.00 1 1285 47 0 0
t$perf-norm:sp_cancel_client_order 1630 1630 0 0.00 0 3631 15 0 0
t$perf-norm:sp_cancel_customer_reserve 628 628 0 0.00 0 6823 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 602 602 0 0.00 0 387 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 849 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 1383 22 0 0
t$perf-norm:sp_cancel_supplier_order 379 379 0 0.00 0 19350 1608 0 0
t$perf-norm:sp_cancel_write_off 971 971 0 0.00 0 883 5 0 0
t$perf-norm:sp_client_order 19029 19029 0 0.00 0 17373 22 0 0
t$perf-norm:sp_customer_reserve 939 939 0 0.00 0 5583 13 0 0
t$perf-norm:sp_pay_from_customer 6408 6408 0 0.00 0 5593 8 0 0
t$perf-norm:sp_pay_to_supplier 5393 5393 0 0.00 0 4739 10 0 0
t$perf-norm:sp_reserve_write_off 9194 9194 0 0.00 0 9413 15 0 0
t$perf-norm:sp_supplier_invoice 5146 5146 0 0.00 0 9149 18 0 0
t$perf-norm:sp_supplier_order 5137 5137 0 0.00 0 11446 19 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 594 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 435 10 0 0
t$perf-norm:srv_make_invnt_saldo 444 444 0 0.00 0 889 12 0 0
t$perf-norm:srv_make_money_saldo 677 677 0 0.00 0 293 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 45 11 0 0
v_add_invoice_to_stock 9540 9312 228 2.38 0 3694 17 0 228
v_all_customers 34346 34346 0 0.00 0 16 1 0 0
v_all_suppliers 28392 28392 0 0.00 0 25 1 0 0
v_all_wares 38058 38058 0 0.00 0 56 1 0 0
v_cancel_adding_invoice 760 760 0 0.00 0 348 6 0 0
v_cancel_client_order 3260 3260 0 0.00 0 3250 8 0 0
v_cancel_customer_prepayment 1204 1204 0 0.00 0 1590 7 0 0
v_cancel_customer_reserve 1256 1256 0 0.00 0 1185 6 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 918 6 0 0
v_cancel_supplier_invoice 802 790 12 1.49 0 2178 20 0 12
v_cancel_supplier_order 758 758 0 0.00 0 1213 5 0 0
v_cancel_write_off 1942 1942 0 0.00 0 5593 17 0 0
v_max_id_clo_ord 5136 5136 0 0.00 0 3488 3 0 0
v_max_id_clo_res 939 939 0 0.00 0 1593 13 0 0
v_max_id_ord_sup 5138 5138 0 0.00 0 6920 5 0 0
v_max_non_paid_invoice 1471 1471 0 0.00 0 1737 4 0 0
v_max_non_paid_realizn 3497 3497 0 0.00 0 5219 4 0 0
v_min_id_clo_ord 5136 5136 0 0.00 0 5936 6 0 0
v_min_id_clo_res 939 939 0 0.00 0 2468 15 0 0
v_min_id_ord_sup 5138 5138 0 0.00 0 10233 10 0 0
v_min_non_paid_invoice 5393 5393 0 0.00 0 4349 10 0 0
v_min_non_paid_realizn 6408 6408 0 0.00 0 5192 11 0 0
v_our_firm 9534 9534 0 0.00 0 17 1 0 0
v_reserve_write_off 18388 18388 0 0.00 0 3574 7 0 0
x_lock_depdocs_on_canc_invoice 11208 11208 0 0.00 0 17637 108 0 0
x_lock_depdocs_on_canc_sup_ord 379 379 0 0.00 0 3922 349 0 0
x_make_qstorno_1000_1200 5137 5137 0 0.00 1 22305 1386 0 0
x_make_qstorno_1200_2000 5146 5146 0 0.00 3 102949 3409 0 0
x_make_qstorno_2100_3300 437969 437969 0 0.00 0 108221 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-02-2021 07:29:56 to 12-02-2021 07:30:00.

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
04:56:59 12 8192000000 8800771056 8812748800 6399568 0.078 2 2 3 0 6548864 79456 69840
04:57:59 30324 8192000000 9739485696 10039631872 818477056 9.083 100 89 176 2 847895504 8204832 21213616
04:59:32 18499 8192000000 9780924464 10089086976 851731184 9.417 100 90 180 0 885883968 13174832 20977952
05:00:55 16447 8192000000 9807746304 10126778368 873308896 9.633 100 83 166 1 916187920 23452256 19426768
05:02:14 20785 8192000000 9895517104 10225844224 920432720 10.100 100 88 174 0 1002011344 62359840 19218784
05:03:38 12170 8192000000 9931243616 10273554432 956310608 10.453 100 83 165 0 1041704608 67572288 17821712
05:04:55 7073 8192000000 9964591344 10313977856 997723760 10.856 100 90 178 1 1072373088 56036592 18612736
05:06:03 7950 8192000000 10007861840 10370830336 1023816304 11.109 100 81 156 1 1115810624 76839808 15154512
05:07:12 1352 8192000000 10031284544 10399379456 1052765200 11.387 100 92 182 1 1141711360 70125856 18820304
05:08:16 8889 8192000000 10023005008 10399584256 1066019104 11.514 100 69 137 0 1136759920 56074512 14666304
05:09:25 4215 8192000000 9989560128 10376781824 1074027152 11.591 100 50 102 2 1108810304 24248880 10534272
05:10:31 4899 8192000000 9993341248 10391900160 1081778624 11.664 100 51 100 1 1116789488 23996896 11013968
05:11:40 4552 8192000000 10006842288 10403418112 1086768752 11.712 100 35 70 0 1110831520 17027328 7035440
05:12:44 53673 8192000000 10013435504 10403262464 1092399152 11.765 100 55 109 3 1114244880 9761296 12084432
05:14:38 7329 8192000000 10020597920 10424672256 1098418320 11.823 100 28 56 1 1138200784 33946464 5836000
05:15:47 5777 8192000000 10010205920 10408579072 1086544032 11.710 99 22 45 0 1120059744 28189136 5326576
05:16:54 10143 8192000000 10008961408 10412756992 1076408144 11.613 98 29 58 1 1109332352 26596672 6327536
05:18:07 52483 8192000000 9964761920 10377809920 1013830448 11.012 95 27 54 1 1043894704 23931008 6133248
05:20:03 6711 8192000000 9954674176 10348122112 1006427456 10.941 97 40 81 1 1035007296 19749200 8830640
05:21:12 4083 8192000000 9924209184 10299736064 995650752 10.836 97 51 101 3 1019442288 12646256 11145280
05:22:17 10537 8192000000 9931972928 10328592384 1029616064 11.165 100 43 86 2 1056264912 16730752 9918096
05:23:28 28083 8192000000 9961179088 10359889920 1043134560 11.295 100 34 68 2 1076453408 26252512 7066336
05:24:57 6210 8192000000 9966267600 10370928640 1051546928 11.376 100 40 80 1 1078732160 18302848 8882384
05:26:04 10502 8192000000 9990953360 10392997888 1074239120 11.593 100 24 48 2 1100227488 20791472 5196896
05:27:16 14437 8192000000 10017302304 10409226240 1094270512 11.783 100 63 122 1 1128690000 22200032 12219456
05:28:38 52839 8192000000 10048357312 10457341952 1105829504 11.893 100 40 80 1 1160053648 45756816 8467328
05:30:34 16006 8192000000 10100385712 10511777792 1126427376 12.088 100 45 90 0 1214468736 78794608 9246752
05:31:56 7923 8192000000 10127373056 10534453248 1142501936 12.239 100 48 94 0 1235277024 82635536 10139552
05:33:09 10632 8192000000 10150166080 10563674112 1149908304 12.309 100 46 91 1 1261020144 100998432 10113408
05:34:27 5567 8192000000 10213362304 10625617920 1162647456 12.428 100 59 117 2 1312333888 136099168 13587264
05:35:39 8067 8192000000 10287506800 10692890624 1170520752 12.502 99 67 134 0 1384678000 198531184 15626064
05:36:51 13135 8192000000 10318254816 10737164288 1178351648 12.575 100 62 124 0 1418716192 225606720 14757824
05:38:15 14000 8192000000 10282105504 10724405248 1165117632 12.451 99 50 100 4 1385536656 208815408 11603616
05:39:35 4649 8192000000 10269888320 10682372096 1167755664 12.476 100 64 128 1 1370732448 188786928 14189856
05:40:47 7790 8192000000 10292992656 10697891840 1162438496 12.426 99 75 150 0 1387589440 209084272 16066672
05:41:59 13643 8192000000 10329076848 10749255680 1170312656 12.500 99 55 109 1 1433219200 250196688 12709856
05:43:26 5337 8192000000 10360408096 10779955200 1170097232 12.498 100 80 159 0 1455660672 267595328 17968112
05:44:36 1595 8192000000 10370863456 10766749696 1152417152 12.332 99 85 168 0 1462263552 290012064 19834336
05:45:47 3004 8192000000 10432609168 10833924096 1178472512 12.576 100 86 172 0 1525299440 326518416 20308512
05:46:53 932 8192000000 10451203648 10848636928 1152540896 12.333 100 91 181 1 1542286768 368753984 20991888
05:48:02 7187 8192000000 10413963424 10831781888 1127282576 12.096 98 62 123 0 1502083184 359442464 15358144
05:49:14 8369 8192000000 10448078112 10881699840 1146513200 12.277 100 51 102 1 1541233424 381535008 13185216
05:50:40 11741 8192000000 10457926320 10889191424 1163134656 12.433 100 39 78 1 1548411984 375243088 10034240
05:52:06 12713 8192000000 10377514400 10827505664 1158269104 12.387 100 38 74 1 1485304928 317384496 9651328
05:53:36 26325 8192000000 10333587392 10867814400 1134839888 12.167 100 22 44 1 1470991264 329847072 6304304
05:55:06 23774 8192000000 10173896080 10683985920 1100733328 11.845 98 19 38 1 1305023376 199865968 4424080
05:56:31 39992 8192000000 10148405296 10597560320 1104732016 11.883 97 23 46 0 1194961040 84974128 5254896
05:58:13 89577 8192000000 10117681632 10567290880 1094772112 11.788 97 33 64 1 1193528992 91352976 7403904
06:00:50 114445 8192000000 10048410960 10503331840 1106262352 11.897 99 5 10 0 1143108736 36083072 763312
06:03:44 41780 8192000000 10035601072 10488111104 1087116624 11.715 97 12 24 0 1108065664 19065424 1883616
06:05:29 59812 8192000000 10099316848 10542604288 1109582496 11.928 98 19 38 0 1154057936 40579552 3895888
06:07:40 27133 8192000000 10130492096 10555576320 1141886016 12.233 100 58 114 4 1211485616 57629984 11969616
06:09:09 73128 8192000000 10136088736 10579779584 1139662560 12.212 99 25 50 2 1231705040 86902784 5139696
06:11:50 67803 8192000000 10102616832 10598060032 1132175440 12.142 99 15 30 0 1224918416 89464000 3278976
06:14:02 55710 8192000000 10088594880 10538033152 1130248704 12.124 99 19 36 0 1181705216 46999488 4457024
06:15:59 78858 8192000000 10087303104 10517790720 1107142592 11.905 97 19 38 0 1158264704 47368736 3753376
06:18:29 152247 8192000000 10093076448 10550820864 1145198128 12.264 100 21 42 2 1202730784 53024512 4508144
06:22:27 70935 8192000000 10105398336 10603016192 1139190512 12.208 99 17 34 1 1213326048 70309328 3826208
06:24:38 180223 8192000000 10098453424 10577072128 1108515024 11.918 96 25 49 0 1163858736 50702512 4641200
06:28:39 67700 8192000000 10096496544 10574450688 1130675264 12.128 99 18 37 0 1209960768 75410816 3874688
06:30:51 33425 8192000000 10069051984 10515431424 1100637008 11.844 96 22 44 0 1154419376 48555296 5227072
06:32:28 31664 8192000000 10064953088 10510159872 1126735488 12.091 99 40 79 1 1160441632 25879168 7826976
06:34:03 70343 8192000000 10103786128 10559348736 1110395488 11.936 97 19 38 0 1175243056 60369440 4478128
06:36:16 54116 8192000000 10096176896 10530209792 1116756032 11.996 98 31 62 0 1169349952 46067184 6526736
06:38:16 32470 8192000000 10078876288 10517979136 1108084480 11.914 98 35 70 0 1156000944 40803984 7112480
06:39:50 38042 8192000000 10066970928 10485329920 1065836816 11.512 95 40 80 0 1115425456 41421952 8166688
06:41:28 18791 8192000000 10104986368 10545811456 1108258736 11.916 99 43 86 0 1194774496 78056768 8458992
06:42:47 84459 8192000000 10130553328 10603016192 1093977200 11.780 97 16 32 0 1163773888 66651728 3144960
06:45:40 84753 8192000000 10088414208 10556047360 1077526832 11.624 97 39 77 0 1143492256 57961216 8004208
06:50:33 74187 8192000000 10065791168 10541879296 1092010384 11.762 98 12 24 0 1142667600 47852992 2804224
06:52:47 81372 8192000000 10061241472 10540785664 1112473024 11.956 100 9 18 0 1154317136 39967936 1876176
06:55:12 55152 8192000000 10108881424 10553778176 1097397136 11.813 97 21 43 1 1165526144 63061424 5067584
06:57:09 92109 8192000000 10131670896 10654646272 1136113232 12.179 100 24 48 1 1245287152 103413008 5760912
06:59:42 43132 8192000000 10070107072 10507968512 1084282432 11.688 96 27 54 0 1116513568 26783712 5447424
07:01:29 32449 8192000000 10087462272 10510667776 1105756688 11.892 98 37 74 0 1165156368 52344688 7054992
07:03:09 60714 8192000000 10092415552 10569191424 1115539632 11.985 98 19 38 2 1176113952 56863328 3710992
07:05:21 136643 8192000000 10070840288 10555404288 1114790192 11.978 98 14 28 0 1167710128 50389408 2530528
07:08:38 52959 8192000000 10066576224 10534428672 1141287824 12.228 100 6 12 0 1180067696 37663424 1116448
07:10:32 56016 8192000000 10052635280 10531487744 1114696432 11.977 99 3 6 0 1145337664 29956592 684640
07:12:32 47783 8192000000 10089631488 10538897408 1139395840 12.210 100 16 33 0 1175462896 32844672 3222384
07:14:20 51013 8192000000 10074207184 10548686848 1113373616 11.964 97 21 42 0 1183656976 65946928 4336432
07:16:15 51435 8192000000 10135630912 10570952704 1150176320 12.311 100 23 46 1 1219419072 63894416 5348336
07:18:17 75063 8192000000 10110688208 10562215936 1119080336 12.018 97 15 30 2 1202049040 79717616 3251088
07:20:56 74433 8192000000 10148311712 10719268864 1152871856 12.336 100 11 22 1 1282999840 127171536 2956448
07:23:18 73674 8192000000 10084913200 10571759616 1072082496 11.572 94 17 34 1 1120200512 45023760 3094256
07:26:11 55153 8192000000 9233870592 9539268608 358401712 4.191 33 6 12 0 410293872 50874208 1017952

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-02-2021 07:30:02 to 12-02-2021 07:30:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 114 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-02-2021 07:30:18 to 12-02-2021 07:30:20.

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          225921
MON$OLDEST_ACTIVE               227143
MON$OLDEST_SNAPSHOT             227143
MON$NEXT_TRANSACTION            227143
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                       2114240
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             786
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16: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 ---
:302: 
:303: mscs-test-161.budzdorov.ru    Fri Feb 12 07:27:18 2021
:304:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:305: 
:306: 
:307: mscs-test-161.budzdorov.ru    Fri Feb 12 07:27:26 2021
:308:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:309: 
--- end of diff output ---

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

12.02.21 07:30:21. 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-02-12 07:30:24 No severe PSQL-related problems occured
12.02.21 07:30:25. End of report.