Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.12.2020 10:28
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 16780864 kB
MemAvailable 31377448 kB
Buffers 0 kB
Cached 14833568 kB
SwapCached 4820 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 2020-12-29 10:26:55
DTS_END                         2020-12-29 10:26:55.3460
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/59620
TRN_ID                          231355
ATT_ID                          652
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: 8980.22 19774 1077626
customer order: creation 173.98 399 20877
customer order: refuse 14.04 364 1685
order to supplier: creation 44.88 1467 5386
order to supplier: removal 3.31 113585 397
invoice (draft): creation 45.39 3849 5447
invoice (draft): removal 49.54 921 5945
invoice accept: apply 41.92 18522 5030
invoice accept: cancel 44.70 917 5364
customer reserve: creation 3847.38 182 461685
customer reserve: removal 4425.43 68 531051
realization accept: apply 82.48 181 9898
realization accept: cancel 80.42 140 9650
payment from customer: creation 59.16 122 7099
payment from customer: removal 6.05 86 726
payment to supplier: creation 47.40 139 5688
payment to supplier: removal 3.51 113 421
service: total inventory turnovers 3.84 3269 461
service: total monetary turnovers 5.91 595 709
service: aggregate perf. data 0.86 14361 103
service: refresh index statistics 0.03 236197 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 29-12-2020 10:29:19 to 29-12-2020 10:29:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3409
TEST_TIME 2 4398
TEST_TIME 3 4071
TEST_TIME 4 4296
TEST_TIME 5 4642
TEST_TIME 6 4638
TEST_TIME 7 4686
TEST_TIME 8 4915
TEST_TIME 9 5076
TEST_TIME 10 5202
TEST_TIME 11 5888
TEST_TIME 12 6153
TEST_TIME 13 6411
TEST_TIME 14 6759
TEST_TIME 15 7193
TEST_TIME 16 7803
TEST_TIME 17 7969
TEST_TIME 18 7939
TEST_TIME 19 8110
TEST_TIME 20 8419
TEST_TIME 21 8578
TEST_TIME 22 8979
TEST_TIME 23 9278
TEST_TIME 24 9033
TEST_TIME 25 8941
TEST_TIME 26 9244
TEST_TIME 27 9612
TEST_TIME 28 10073
TEST_TIME 29 10687
TEST_TIME 30 10446
TEST_TIME 31 10570
TEST_TIME 32 10600
TEST_TIME 33 10496
TEST_TIME 34 10342
TEST_TIME 35 10092
TEST_TIME 36 9860
TEST_TIME 37 9848
TEST_TIME 38 9685
TEST_TIME 39 9696
TEST_TIME 40 9708
TEST_TIME 41 9647
TEST_TIME 42 9549
TEST_TIME 43 9538
TEST_TIME 44 9628
TEST_TIME 45 9517
TEST_TIME 46 9522
TEST_TIME 47 9478
TEST_TIME 48 9531
TEST_TIME 49 9540
TEST_TIME 50 9559
TEST_TIME 51 9582
TEST_TIME 52 9654
TEST_TIME 53 9651
TEST_TIME 54 9636
TEST_TIME 55 9763
TEST_TIME 56 9708
TEST_TIME 57 9846
TEST_TIME 58 9871
TEST_TIME 59 9739
TEST_TIME 60 9586
TEST_TIME 61 9499
TEST_TIME 62 9527
TEST_TIME 63 9409
TEST_TIME 64 9381
TEST_TIME 65 9382
TEST_TIME 66 9329
TEST_TIME 67 9326
TEST_TIME 68 9312
TEST_TIME 69 9350
TEST_TIME 70 9329
TEST_TIME 71 9461
TEST_TIME 72 9415
TEST_TIME 73 9426
TEST_TIME 74 9401
TEST_TIME 75 9384
TEST_TIME 76 9461
TEST_TIME 77 9538
TEST_TIME 78 9555
TEST_TIME 79 9479
TEST_TIME 80 9378
TEST_TIME 81 9338
TEST_TIME 82 9282
TEST_TIME 83 9232
TEST_TIME 84 9270
TEST_TIME 85 9223
TEST_TIME 86 9196
TEST_TIME 87 9216
TEST_TIME 88 9268
TEST_TIME 89 9216
TEST_TIME 90 9261
TEST_TIME 91 9223
TEST_TIME 92 9269
TEST_TIME 93 9322
TEST_TIME 94 9285
TEST_TIME 95 9299
TEST_TIME 96 9359
TEST_TIME 97 9402
TEST_TIME 98 9407
TEST_TIME 99 9318
TEST_TIME 100 9282
TEST_TIME 101 9226
TEST_TIME 102 9222
TEST_TIME 103 9178
TEST_TIME 104 9159
TEST_TIME 105 9138
TEST_TIME 106 9145
TEST_TIME 107 9110
TEST_TIME 108 9129
TEST_TIME 109 9159
TEST_TIME 110 9120
TEST_TIME 111 9153
TEST_TIME 112 9114
TEST_TIME 113 9208
TEST_TIME 114 9193
TEST_TIME 115 9229
TEST_TIME 116 9153
TEST_TIME 117 9093
TEST_TIME 118 9056
TEST_TIME 119 9031
TEST_TIME 120 8982

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-12-2020 10:29:23 to 29-12-2020 10:29:26.

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 1504266 1504266 0 0.00 0 39809 7 0 0
doc_list_biud 1504266 1504266 0 0.00 0 82735 31 0 0
sp_add_invoice_to_stock 5180 5030 150 2.89 42 208528 18523 0 150
sp_cancel_adding_invoice 5364 5364 0 0.00 5 98669 918 0 0
sp_cancel_client_order 1685 1685 0 0.00 5 8679 365 0 0
sp_cancel_customer_reserve 531051 531051 0 0.00 1 82737 69 0 0
sp_cancel_pay_from_customer 726 726 0 0.00 3 6292 86 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 3 7849 113 0 0
sp_cancel_supplier_invoice 5961 5945 16 0.26 3 30544 922 0 16
sp_cancel_supplier_order 397 397 0 0.00 9 1008363 113585 0 0
sp_cancel_write_off 9650 9650 0 0.00 1 39771 141 0 0
sp_client_order 20877 20877 0 0.00 4 15842 400 0 0
sp_customer_reserve 461685 461685 0 0.00 0 155969 182 0 0
sp_fill_shopping_cart 31692 31692 0 0.00 1 10119 38 0 0
sp_fill_shopping_cart_clo_res 461679 461679 0 0.00 0 21599 18 0 0
sp_get_clo_for_invoice 5018 5018 0 0.00 0 43479 405 0 0
sp_kill_cost_storno 16161 16161 0 0.00 0 10689 59 0 0
sp_kill_qstorno_ret_qs2qd 1078094 1078094 0 0.00 0 82731 37 0 0
sp_kill_qty_storno 569006 569006 0 0.00 0 82735 80 0 0
sp_lock_selected_doc 20644 20644 0 0.00 0 8304 6 0 0
sp_make_cost_storno 27701 27701 0 0.00 0 14921 58 0 0
sp_multiply_rows_for_pdistr 27701 27701 0 0.00 0 7861 32 0 0
sp_multiply_rows_for_qdistr 20877 20877 0 0.00 0 13715 224 0 0
sp_pay_from_customer 7099 7099 0 0.00 4 13385 123 0 0
sp_pay_to_supplier 5688 5688 0 0.00 5 12730 139 0 0
sp_payment_common 12787 12787 0 0.00 1 10332 24 0 0
sp_qd_handle_on_cancel_clo 1685 1685 0 0.00 0 6852 322 0 0
sp_qd_handle_on_invoice_upd_sts 10380 10380 0 0.00 0 30148 375 0 0
sp_qd_handle_on_reserve_upd_sts 9898 9898 0 0.00 0 26686 44 0 0
sp_reserve_write_off 9898 9898 0 0.00 7 26701 182 0 0
sp_supplier_invoice 5447 5447 0 0.00 8 130352 3850 0 0
sp_supplier_order 5386 5386 0 0.00 5 54636 1468 0 0
srv_aggregate_perf_data 104 103 1 0.96 2348 110645 14362 0 1
srv_fill_mon_cache_memory 67 67 0 0.00 1229 161943 42547 0 0
srv_find_qd_qs_mism 1029680 1029680 0 0.00 0 39807 6 0 0
srv_make_invnt_saldo 461 461 0 0.00 53 143781 3270 0 0
srv_make_money_saldo 709 709 0 0.00 1 19444 595 0 0
srv_recalc_idx_stat 4 4 0 0.00 160085 317642 236197 0 0
t$perf-abend:sp_add_invoice_to_stock 150 150 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 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 5030 5030 0 0.00 0 7778 170 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 1 3216 95 0 0
t$perf-norm:sp_cancel_client_order 1685 1685 0 0.00 0 2199 13 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 864 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 726 726 0 0.00 0 739 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 2462 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 375 375 0 0.00 0 404 9 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 15392 1497 0 0
t$perf-norm:sp_cancel_write_off 1028 1028 0 0.00 0 3746 13 0 0
t$perf-norm:sp_client_order 20877 20877 0 0.00 0 6709 18 0 0
t$perf-norm:sp_customer_reserve 1007 1007 0 0.00 0 733 4 0 0
t$perf-norm:sp_pay_from_customer 7099 7099 0 0.00 0 5212 10 0 0
t$perf-norm:sp_pay_to_supplier 5688 5688 0 0.00 0 12439 14 0 0
t$perf-norm:sp_reserve_write_off 9898 9898 0 0.00 0 11305 9 0 0
t$perf-norm:sp_supplier_invoice 5447 5447 0 0.00 0 12665 23 0 0
t$perf-norm:sp_supplier_order 5386 5386 0 0.00 0 4895 17 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 134 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 256 7 0 0
t$perf-norm:srv_make_invnt_saldo 461 461 0 0.00 0 705 10 0 0
t$perf-norm:srv_make_money_saldo 709 709 0 0.00 0 510 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10332 10032 300 2.90 0 6206 21 0 300
v_all_customers 37838 37838 0 0.00 0 59 1 0 0
v_all_suppliers 29608 29608 0 0.00 0 72 1 0 0
v_all_wares 41754 41754 0 0.00 0 82 1 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 2427 11 0 0
v_cancel_client_order 3370 3370 0 0.00 0 2208 6 0 0
v_cancel_customer_prepayment 1452 1452 0 0.00 0 6215 10 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 1727 6 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 906 6 0 0
v_cancel_supplier_invoice 782 750 32 4.09 0 3325 19 0 32
v_cancel_supplier_order 794 794 0 0.00 0 176 3 0 0
v_cancel_write_off 2056 2056 0 0.00 0 5534 20 0 0
v_max_id_clo_ord 5386 5386 0 0.00 0 389 1 0 0
v_max_id_clo_res 1007 1007 0 0.00 0 4664 33 0 0
v_max_id_ord_sup 5429 5429 0 0.00 0 3525 4 0 0
v_max_non_paid_invoice 1699 1699 0 0.00 0 2705 5 0 0
v_max_non_paid_realizn 3798 3798 0 0.00 0 4194 4 0 0
v_min_id_clo_ord 5386 5386 0 0.00 0 878 4 0 0
v_min_id_clo_res 1007 1007 0 0.00 0 3160 13 0 0
v_min_id_ord_sup 5429 5429 0 0.00 0 5433 8 0 0
v_min_non_paid_invoice 5688 5688 0 0.00 0 8354 14 0 0
v_min_non_paid_realizn 7099 7099 0 0.00 0 7737 18 0 0
v_our_firm 10518 10518 0 0.00 0 62 1 0 0
v_reserve_write_off 19796 19796 0 0.00 0 6243 7 0 0
x_lock_depdocs_on_canc_invoice 10934 10934 0 0.00 0 9964 99 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 3408 292 0 0
x_make_qstorno_1000_1200 5386 5386 0 0.00 2 54626 1389 0 0
x_make_qstorno_1200_2000 5447 5447 0 0.00 3 130313 3702 0 0
x_make_qstorno_2100_3300 461685 461685 0 0.00 0 155967 162 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 29-12-2020 10:29:27 to 29-12-2020 10:29:32.

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
07:56:59 1 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
07:58:00 39779 8192000000 9733134288 10029588480 804118560 8.938 100 87 174 1 833208416 7859472 21230384
07:59:45 23689 8192000000 9764951008 10074202112 840967040 9.309 100 83 166 1 873368848 12653408 19748400
08:01:14 19579 8192000000 9807152880 10129231872 872298368 9.623 100 86 172 1 919296512 27019840 19978304
08:02:51 16928 8192000000 9877528768 10212835328 924830304 10.144 100 78 155 0 981050016 39598480 16621232
08:04:12 11578 8192000000 9926693072 10275393536 966595952 10.553 100 79 159 2 1039715104 55223536 17895616
08:05:27 5636 8192000000 9967186320 10320142336 997434000 10.854 100 77 154 1 1076911920 63948512 15529408
08:06:36 3945 8192000000 10003073760 10361503744 1020939472 11.081 100 85 169 4 1118445872 80634464 16871936
08:07:42 2950 8192000000 10013225904 10376822784 1045914624 11.321 100 93 187 1 1122460576 56784144 19761808
08:08:46 16611 8192000000 10035284864 10410328064 1060387136 11.460 100 70 140 1 1143617184 68773584 14456464
08:10:04 10362 8192000000 10000432288 10401673216 1065391808 11.508 100 31 62 1 1116962528 44591680 6979040
08:11:15 3866 8192000000 9997679680 10383958016 1073305520 11.584 100 63 127 0 1112492576 25719936 13467120
08:12:20 4199 8192000000 10000747824 10393292800 1082381136 11.670 100 36 74 3 1128231728 38358752 7491840
08:13:26 17666 8192000000 10006634736 10394234880 1082845664 11.675 100 52 102 1 1104998640 11490560 10662416
08:14:45 4005 8192000000 10020032640 10416058368 1090133392 11.744 100 50 98 3 1132351456 31753312 10464752
08:15:53 5447 8192000000 10015222720 10419040256 1093998208 11.781 100 39 78 2 1133867952 31429504 8440240
08:17:00 8546 8192000000 10002040080 10402639872 1083423776 11.680 99 34 68 1 1112310000 20922384 7963840
08:18:11 23431 8192000000 9950917488 10346221568 1042536096 11.289 97 30 58 1 1074426288 25496720 6393472
08:19:36 4418 8192000000 9971994896 10356637696 1028426496 11.153 98 48 93 2 1064203088 25803840 9972752
08:20:41 5713 8192000000 9943495904 10315620352 1009609760 10.972 98 33 67 1 1040824608 23827616 7387232
08:21:47 56226 8192000000 9918821984 10293366784 934733248 10.241 91 29 57 3 957083104 15958672 6391184
08:23:44 3371 8192000000 9958716896 10340458496 1032878112 11.196 100 49 95 1 1070716592 27580256 10258224
08:24:47 4396 8192000000 9963726880 10348384256 1048622800 11.347 100 45 88 3 1067472928 8715936 10134192
08:25:55 6147 8192000000 9995720608 10380075008 1059049616 11.447 100 55 111 1 1092055696 20925552 12080528
08:27:05 43461 8192000000 10009811536 10406277120 1078301200 11.631 100 37 73 1 1121857952 36210240 7346512
08:28:55 3657 8192000000 10055131472 10453872640 1106943952 11.903 100 57 112 1 1166540576 47717504 11879120
08:30:04 26313 8192000000 10089419376 10497712128 1124902304 12.073 100 41 81 1 1195811584 61577152 9332128
08:31:35 8485 8192000000 10138253536 10550226944 1137771104 12.195 100 48 96 2 1249073808 100351088 10951616
08:32:48 4848 8192000000 10158779264 10561208320 1153699152 12.344 100 55 109 0 1265726176 99989952 12037072
08:33:57 8552 8192000000 10203447856 10613440512 1164093360 12.442 100 49 97 0 1315021184 139963328 10964496
08:35:10 5871 8192000000 10256782384 10659815424 1177075776 12.563 100 71 142 0 1359743024 166732064 15935184
08:36:23 6802 8192000000 10284096672 10693697536 1172833600 12.523 100 63 126 0 1387715360 200099920 14781840
08:37:35 8856 8192000000 10277219888 10698293248 1179469152 12.585 100 48 96 1 1397882096 206361104 12051840
08:38:51 16514 8192000000 10279971840 10702348288 1180797984 12.598 100 54 107 1 1384616992 191922256 11896752
08:40:15 3843 8192000000 10281038128 10687897600 1157591360 12.381 98 78 154 1 1362796752 187604144 17601248
08:41:22 2880 8192000000 10262656896 10667520000 1148941200 12.300 99 82 164 0 1362443328 195139264 18362864
08:42:33 2194 8192000000 10240575824 10667241472 1159240944 12.396 100 86 167 2 1337912720 159691728 18980048
08:43:38 6226 8192000000 10221581920 10632597504 1136981696 12.187 99 65 128 1 1322495200 171035024 14478480
08:44:51 1228 8192000000 10306855200 10695704576 1133831600 12.157 100 90 179 1 1401802240 247738272 20232368
08:45:57 5359 8192000000 10322965040 10727841792 1139034848 12.206 100 64 127 0 1413526032 259889712 14601472
08:47:06 4761 8192000000 10303919104 10707615744 1125857744 12.082 100 59 118 0 1399610544 259347136 14405664
08:48:22 6414 8192000000 10293425392 10726936576 1120138960 12.028 99 51 101 2 1399538832 267477024 11922848
08:49:37 9197 8192000000 10250488864 10690760704 1127293728 12.096 100 37 74 0 1344562176 207935776 9332672
08:50:49 11008 8192000000 10316569088 10736762880 1126448400 12.088 100 49 98 0 1414832272 276361184 12022688
08:52:10 10471 8192000000 10340134192 10785644544 1118515904 12.013 100 31 61 1 1435286224 309117856 7652464
08:53:26 16141 8192000000 10225898576 10697592832 1098923808 11.827 100 17 34 3 1328453168 225180608 4348752
08:54:44 44723 8192000000 10111007488 10628710400 1086822944 11.712 99 16 32 3 1237889856 147223536 3843376
08:56:30 14575 8192000000 10058130064 10468667392 1118672720 12.014 100 57 115 4 1153656576 23314560 11669296
08:57:53 84692 8192000000 10083305712 10551582720 1118637104 12.014 99 7 14 1 1162700960 42226432 1837424
09:00:19 20691 8192000000 10073014352 10515410944 1133320304 12.153 100 44 88 1 1172797472 30082208 9394960
09:01:44 71589 8192000000 10056510784 10488385536 1126331616 12.087 99 50 96 0 1154804752 18560240 9912896
09:03:57 35933 8192000000 10087857952 10514448384 1123198656 12.057 99 29 58 2 1165521408 36218272 6104480
09:05:35 32094 8192000000 10111875776 10544787456 1118872288 12.016 98 31 59 2 1205391904 80261632 6257984
09:07:11 118444 8192000000 10140750992 10589986816 1126316016 12.087 98 25 49 3 1215156640 83405248 5435376
09:10:12 45555 8192000000 10079311696 10537066496 1129387600 12.116 99 19 38 1 1183752384 50193904 4170880
09:12:17 57052 8192000000 10094402496 10594807808 1140001008 12.216 100 7 14 0 1197810128 56360880 1448240
09:14:15 49710 8192000000 10106944208 10561597440 1154256624 12.349 100 24 48 2 1238140432 78567104 5316704
09:16:05 44905 8192000000 10101956112 10569973760 1132528656 12.145 98 10 20 2 1191802944 57219344 2054944
09:17:53 48337 8192000000 10097147808 10548731904 1114498736 11.975 97 22 44 1 1189136560 69743392 4894432
09:19:45 78697 8192000000 10128806000 10579980288 1134552672 12.164 99 18 34 2 1234849728 96422032 3875024
09:22:07 35674 8192000000 10129417744 10566377472 1122233088 12.048 97 35 70 3 1191759728 62759376 6767264
09:24:52 90359 8192000000 10037594144 10474881024 1102457024 11.861 99 25 50 0 1133182080 25345280 5379776
09:27:40 71359 8192000000 10051933696 10527780864 1094557936 11.786 97 18 36 0 1166848752 68569152 3721664
09:29:54 30545 8192000000 10064322224 10522116096 1120089968 12.028 99 11 22 2 1166288704 44076032 2122704
09:31:26 36937 8192000000 10035712256 10481897472 1106960048 11.904 98 5 10 0 1131563856 22906096 1697712
09:33:06 39146 8192000000 10079223488 10525544448 1102718240 11.863 98 33 66 3 1159054352 49574944 6761168
09:34:58 52718 8192000000 10093879792 10517032960 1072562336 11.577 95 25 49 3 1165927024 87241136 6123552
09:37:08 72658 8192000000 10037277152 10499284992 1067703664 11.530 96 12 24 0 1098788288 28329632 2754992
09:39:21 51126 8192000000 10091361584 10533232640 1111229152 11.944 100 27 54 1 1188439632 71686976 5523504
09:41:13 23000 8192000000 10116396368 10537885696 1092454608 11.766 98 44 88 3 1192211520 90847520 8909392
09:42:38 55326 8192000000 10069075264 10579779584 1093147856 11.773 98 16 32 0 1155078352 58467728 3462768
09:44:37 40108 8192000000 10050644976 10469167104 1113266000 11.963 99 25 50 0 1138396416 20404304 4726112
09:46:30 113831 8192000000 10086232688 10574192640 1128861184 12.111 100 21 42 1 1213879472 80321840 4696448
09:49:24 31010 8192000000 10106390288 10599202816 1131458672 12.135 100 11 23 1 1210394576 76749648 2186256
09:50:56 64552 8192000000 10070712256 10520285184 1109681296 11.929 98 9 18 0 1150528864 38867968 1979600
09:53:03 68458 8192000000 10071736016 10575015936 1108442352 11.918 98 18 36 0 1173782720 61397024 3943344
09:55:11 32362 8192000000 10080895808 10513137664 1108543328 11.919 99 39 78 2 1144359904 26832720 8983856
09:56:50 71261 8192000000 10148501008 10593406976 1138125632 12.198 100 20 40 0 1206746912 64692304 3928976
09:59:08 65734 8192000000 10081142512 10538029056 1128051424 12.103 99 13 26 0 1175121344 44562320 2507600
10:01:14 50027 8192000000 10169157904 10636627968 1144036752 12.253 99 21 42 0 1251481104 103047840 4396512
10:03:14 161943 8192000000 10099981888 10583531520 1127007008 12.093 98 18 36 1 1205027168 74394144 3626016
10:06:57 71792 8192000000 10098636672 10570248192 1134669792 12.165 99 11 22 2 1182123008 45076240 2376976
10:09:09 49542 8192000000 10077316512 10522177536 1146060144 12.273 100 20 40 0 1182036928 31793360 4183424
10:11:03 52697 8192000000 10099149232 10547785728 1142404112 12.238 99 28 56 0 1178267168 30302944 5560112
10:12:58 36711 8192000000 10119438336 10555101184 1145010080 12.263 99 22 44 1 1194450448 45137920 4302448
10:14:40 77883 8192000000 10082100096 10546962432 1135468096 12.173 98 13 26 1 1190709168 52377184 2863888
10:16:59 56955 8192000000 10102257312 10572034048 1106223840 11.897 97 20 40 1 1153504144 42907664 4372640
10:18:59 68360 8192000000 10127216240 10640879616 1144980192 12.262 100 31 62 2 1266088704 114620816 6487696
10:21:15 97801 8192000000 10041615008 10531729408 1086028944 11.705 97 12 24 1 1139438704 51014912 2394848
10:23:58 58315 8192000000 10053639760 10510348288 1109209120 11.925 99 12 23 0 1155098400 43231120 2658160
10:25:57 62325 8192000000 9750343584 10168070144 829186752 9.191 74 11 22 0 880725584 49344464 2194368

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 29-12-2020 10:29:33 to 29-12-2020 10:29:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 150 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 29-12-2020 10:29:50 to 29-12-2020 10:29:51.

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          231354
MON$OLDEST_ACTIVE               233286
MON$OLDEST_SNAPSHOT             233286
MON$NEXT_TRANSACTION            233286
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                       2092672
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             797
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2308 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2308 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2308 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.2308 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 ---
:11: 
:12: mscs-test-161.budzdorov.ru    Tue Dec 29 10:27:04 2020
:13:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:14: 
:15: 
:16: mscs-test-161.budzdorov.ru    Tue Dec 29 10:27:33 2020
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Tue Dec 29 10:27:33 2020
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Tue Dec 29 10:27:33 2020
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Tue Dec 29 10:27:33 2020
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
:31: 
:32: mscs-test-161.budzdorov.ru    Tue Dec 29 10:27:33 2020
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:34: 
--- end of diff output ---

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

29.12.20 10:29:53. 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
2020-12-29 10:29:55 No severe PSQL-related problems occured
29.12.20 10:29:56. End of report.