Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.12.2021 04:39
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 16481380 kB
MemAvailable 31423620 kB
Buffers 20 kB
Cached 15178392 kB
SwapCached 0 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.1
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-12-20 04:37:10
DTS_END                         2021-12-20 04:37:10.7740
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/36300
TRN_ID                          229974
ATT_ID                          590
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: 9106.41 26386 1092769
customer order: creation 162.20 449 19464
customer order: refuse 13.61 341 1633
order to supplier: creation 44.13 1464 5295
order to supplier: removal 3.55 110309 426
invoice (draft): creation 43.60 3909 5232
invoice (draft): removal 51.96 863 6235
invoice accept: apply 40.19 19869 4823
invoice accept: cancel 46.93 767 5631
customer reserve: creation 3786.20 193 454344
customer reserve: removal 4629.88 67 555585
realization accept: apply 80.32 191 9638
realization accept: cancel 81.31 132 9757
payment from customer: creation 56.59 134 6791
payment from customer: removal 5.63 113 676
payment to supplier: creation 46.22 149 5546
payment to supplier: removal 3.58 96 429
service: total inventory turnovers 3.67 3449 440
service: total monetary turnovers 5.89 568 707
service: aggregate perf. data 0.94 15318 113
service: refresh index statistics 0.03 369344 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 20-12-2021 04:39:37 to 20-12-2021 04:39:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3634
TEST_TIME 2 4662
TEST_TIME 3 5528
TEST_TIME 4 5800
TEST_TIME 5 5479
TEST_TIME 6 6218
TEST_TIME 7 5785
TEST_TIME 8 5638
TEST_TIME 9 5869
TEST_TIME 10 6071
TEST_TIME 11 6125
TEST_TIME 12 6156
TEST_TIME 13 6252
TEST_TIME 14 6515
TEST_TIME 15 6597
TEST_TIME 16 7169
TEST_TIME 17 7734
TEST_TIME 18 7999
TEST_TIME 19 8267
TEST_TIME 20 8319
TEST_TIME 21 8729
TEST_TIME 22 9066
TEST_TIME 23 9589
TEST_TIME 24 9777
TEST_TIME 25 11192
TEST_TIME 26 11032
TEST_TIME 27 11067
TEST_TIME 28 10864
TEST_TIME 29 10675
TEST_TIME 30 10408
TEST_TIME 31 10276
TEST_TIME 32 10245
TEST_TIME 33 10184
TEST_TIME 34 10141
TEST_TIME 35 10074
TEST_TIME 36 10037
TEST_TIME 37 9972
TEST_TIME 38 9863
TEST_TIME 39 9765
TEST_TIME 40 9786
TEST_TIME 41 9717
TEST_TIME 42 9927
TEST_TIME 43 9928
TEST_TIME 44 9952
TEST_TIME 45 9969
TEST_TIME 46 10032
TEST_TIME 47 10063
TEST_TIME 48 10105
TEST_TIME 49 10070
TEST_TIME 50 10058
TEST_TIME 51 10003
TEST_TIME 52 10167
TEST_TIME 53 9985
TEST_TIME 54 9867
TEST_TIME 55 9817
TEST_TIME 56 9714
TEST_TIME 57 9680
TEST_TIME 58 9584
TEST_TIME 59 9570
TEST_TIME 60 9482
TEST_TIME 61 9539
TEST_TIME 62 9538
TEST_TIME 63 9495
TEST_TIME 64 9415
TEST_TIME 65 9495
TEST_TIME 66 9532
TEST_TIME 67 9500
TEST_TIME 68 9600
TEST_TIME 69 9621
TEST_TIME 70 9609
TEST_TIME 71 9600
TEST_TIME 72 9696
TEST_TIME 73 9620
TEST_TIME 74 9530
TEST_TIME 75 9427
TEST_TIME 76 9415
TEST_TIME 77 9417
TEST_TIME 78 9348
TEST_TIME 79 9302
TEST_TIME 80 9248
TEST_TIME 81 9271
TEST_TIME 82 9215
TEST_TIME 83 9225
TEST_TIME 84 9218
TEST_TIME 85 9198
TEST_TIME 86 9188
TEST_TIME 87 9258
TEST_TIME 88 9277
TEST_TIME 89 9241
TEST_TIME 90 9228
TEST_TIME 91 9305
TEST_TIME 92 9362
TEST_TIME 93 9350
TEST_TIME 94 9258
TEST_TIME 95 9211
TEST_TIME 96 9169
TEST_TIME 97 9219
TEST_TIME 98 9241
TEST_TIME 99 9235
TEST_TIME 100 9182
TEST_TIME 101 9220
TEST_TIME 102 9184
TEST_TIME 103 9227
TEST_TIME 104 9197
TEST_TIME 105 9209
TEST_TIME 106 9225
TEST_TIME 107 9227
TEST_TIME 108 9315
TEST_TIME 109 9311
TEST_TIME 110 9383
TEST_TIME 111 9372
TEST_TIME 112 9313
TEST_TIME 113 9285
TEST_TIME 114 9250
TEST_TIME 115 9248
TEST_TIME 116 9235
TEST_TIME 117 9204
TEST_TIME 118 9195
TEST_TIME 119 9181
TEST_TIME 120 9107

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-12-2021 04:39:41 to 20-12-2021 04:39:44.

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 1513273 1513273 0 0.00 0 22737 7 0 0
doc_list_biud 1513273 1513273 0 0.00 0 30485 31 0 0
sp_add_invoice_to_stock 4951 4823 128 2.58 32 193401 19870 0 128
sp_cancel_adding_invoice 5631 5631 0 0.00 6 58232 767 0 0
sp_cancel_client_order 1633 1633 0 0.00 4 12694 341 0 0
sp_cancel_customer_reserve 555585 555585 0 0.00 1 30489 67 0 0
sp_cancel_pay_from_customer 676 676 0 0.00 2 16330 114 0 0
sp_cancel_pay_to_supplier 429 429 0 0.00 3 4355 97 0 0
sp_cancel_supplier_invoice 6243 6235 8 0.12 2 26526 863 0 8
sp_cancel_supplier_order 426 426 0 0.00 8 1080695 110309 0 0
sp_cancel_write_off 9757 9757 0 0.00 1 31745 132 0 0
sp_client_order 19464 19464 0 0.00 4 30811 450 0 0
sp_customer_reserve 454344 454344 0 0.00 0 121701 194 0 0
sp_fill_shopping_cart 29967 29967 0 0.00 1 17653 43 0 0
sp_fill_shopping_cart_clo_res 454343 454343 0 0.00 0 20198 17 0 0
sp_get_clo_for_invoice 4822 4822 0 0.00 0 51735 390 0 0
sp_kill_cost_storno 16493 16493 0 0.00 0 9827 55 0 0
sp_kill_qstorno_ret_qs2qd 1127588 1127588 0 0.00 0 30481 36 0 0
sp_kill_qty_storno 593727 593727 0 0.00 0 30485 77 0 0
sp_lock_selected_doc 19987 19987 0 0.00 0 7950 7 0 0
sp_make_cost_storno 26797 26797 0 0.00 0 16533 57 0 0
sp_multiply_rows_for_pdistr 26797 26797 0 0.00 0 8096 31 0 0
sp_multiply_rows_for_qdistr 19464 19464 0 0.00 0 16547 210 0 0
sp_pay_from_customer 6791 6791 0 0.00 4 31193 134 0 0
sp_pay_to_supplier 5546 5546 0 0.00 4 13703 149 0 0
sp_payment_common 12337 12337 0 0.00 1 8603 27 0 0
sp_qd_handle_on_cancel_clo 1633 1633 0 0.00 0 12633 311 0 0
sp_qd_handle_on_invoice_upd_sts 10453 10453 0 0.00 0 12543 321 0 0
sp_qd_handle_on_reserve_upd_sts 9638 9638 0 0.00 0 11204 62 0 0
sp_reserve_write_off 9638 9638 0 0.00 7 31418 191 0 0
sp_supplier_invoice 5232 5232 0 0.00 6 88611 3910 0 0
sp_supplier_order 5295 5295 0 0.00 5 44195 1465 0 0
srv_aggregate_perf_data 114 113 1 0.87 2885 248918 15318 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 995 165281 48596 0 0
srv_find_qd_qs_mism 1046788 1046788 0 0.00 0 22736 6 0 0
srv_make_invnt_saldo 440 440 0 0.00 56 120708 3449 0 0
srv_make_money_saldo 707 707 0 0.00 1 13220 569 0 0
srv_recalc_idx_stat 4 4 0 0.00 208551 554581 369345 0 0
t$perf-abend:sp_add_invoice_to_stock 128 128 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 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 4823 4823 0 0.00 0 10860 165 0 0
t$perf-norm:sp_cancel_adding_invoice 374 374 0 0.00 0 16179 129 0 0
t$perf-norm:sp_cancel_client_order 1633 1633 0 0.00 0 3783 14 0 0
t$perf-norm:sp_cancel_customer_reserve 644 644 0 0.00 0 2389 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 676 676 0 0.00 0 2475 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 429 429 0 0.00 0 1376 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 377 377 0 0.00 0 6414 31 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 14385 1166 0 0
t$perf-norm:sp_cancel_write_off 968 968 0 0.00 0 2823 15 0 0
t$perf-norm:sp_client_order 19464 19464 0 0.00 0 8954 17 0 0
t$perf-norm:sp_customer_reserve 999 999 0 0.00 0 3173 10 0 0
t$perf-norm:sp_pay_from_customer 6791 6791 0 0.00 0 16781 13 0 0
t$perf-norm:sp_pay_to_supplier 5546 5546 0 0.00 0 4649 10 0 0
t$perf-norm:sp_reserve_write_off 9638 9638 0 0.00 0 17329 15 0 0
t$perf-norm:sp_supplier_invoice 5232 5232 0 0.00 0 6609 14 0 0
t$perf-norm:sp_supplier_order 5295 5295 0 0.00 0 5814 15 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 346 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 261 6 0 0
t$perf-norm:srv_make_invnt_saldo 440 440 0 0.00 0 1128 11 0 0
t$perf-norm:srv_make_money_saldo 707 707 0 0.00 0 10506 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 9900 9644 256 2.58 0 11035 20 0 256
v_all_customers 35368 35368 0 0.00 0 24 1 0 0
v_all_suppliers 28996 28996 0 0.00 0 44 1 0 0
v_all_wares 38928 38928 0 0.00 0 29 1 0 0
v_cancel_adding_invoice 748 748 0 0.00 0 592 7 0 0
v_cancel_client_order 3266 3266 0 0.00 0 2820 6 0 0
v_cancel_customer_prepayment 1352 1352 0 0.00 0 16288 22 0 0
v_cancel_customer_reserve 1288 1288 0 0.00 0 2279 6 0 0
v_cancel_payment_to_supplier 858 858 0 0.00 0 3594 12 0 0
v_cancel_supplier_invoice 770 754 16 2.07 0 1447 16 0 16
v_cancel_supplier_order 852 852 0 0.00 0 27297 46 0 0
v_cancel_write_off 1936 1936 0 0.00 0 31682 32 0 0
v_max_id_clo_ord 5295 5295 0 0.00 0 480 1 0 0
v_max_id_clo_res 999 999 0 0.00 0 1851 13 0 0
v_max_id_ord_sup 5208 5208 0 0.00 0 5882 4 0 0
v_max_non_paid_invoice 1551 1551 0 0.00 0 513 2 0 0
v_max_non_paid_realizn 3647 3647 0 0.00 0 2354 5 0 0
v_min_id_clo_ord 5295 5295 0 0.00 0 3689 6 0 0
v_min_id_clo_res 999 999 0 0.00 0 17569 37 0 0
v_min_id_ord_sup 5208 5208 0 0.00 0 5744 11 0 0
v_min_non_paid_invoice 5546 5546 0 0.00 0 6296 18 0 0
v_min_non_paid_realizn 6791 6791 0 0.00 0 7812 17 0 0
v_our_firm 9848 9848 0 0.00 0 15 1 0 0
v_reserve_write_off 19276 19276 0 0.00 0 31356 7 0 0
x_lock_depdocs_on_canc_invoice 11489 11489 0 0.00 0 18814 128 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 3227 282 0 0
x_make_qstorno_1000_1200 5295 5295 0 0.00 1 44167 1391 0 0
x_make_qstorno_1200_2000 5232 5232 0 0.00 3 86720 3733 0 0
x_make_qstorno_2100_3300 454344 454344 0 0.00 0 121697 175 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-12-2021 04:39:45 to 20-12-2021 04:39:49.

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
02:07:14 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:15 16859 8192000000 9740376752 10041823232 818411776 9.082 100 86 172 0 846668880 7611648 20645456
02:09:40 17858 8192000000 9775858672 10083463168 845659040 9.357 100 88 176 0 879957696 13656832 20641824
02:11:04 14623 8192000000 9812186416 10133446656 878869168 9.688 100 81 162 0 924991504 27258160 18864176
02:12:21 14402 8192000000 9873446384 10205515776 912647936 10.023 100 80 161 0 983283184 52567824 18067424
02:13:41 8243 8192000000 9920211952 10264821760 960678176 10.496 100 80 156 1 1036148080 58266992 17202912
02:14:50 6201 8192000000 9951589680 10303152128 985705744 10.740 100 83 166 1 1062365296 59794368 16865184
02:15:59 3507 8192000000 10023585824 10385313792 1018095520 11.054 100 90 178 1 1136953040 100338480 18519040
02:17:04 4011 8192000000 10014564512 10380693504 1039198320 11.257 100 83 163 5 1127609040 72246224 16164496
02:18:09 1498 8192000000 10054681072 10425663488 1068087488 11.534 100 95 188 2 1156496800 69061344 19347968
02:19:11 2645 8192000000 10016131840 10391437312 1069514512 11.547 100 87 177 2 1120723760 32093728 19115520
02:20:15 6659 8192000000 10021824384 10412318720 1082693424 11.673 100 58 119 0 1133533008 37715120 13124464
02:21:22 4862 8192000000 9993463200 10393882624 1085510176 11.700 100 42 85 0 1106139920 11727600 8902144
02:22:30 61447 8192000000 10018846080 10413248512 1094231760 11.783 100 66 131 2 1121240160 13240528 13767872
02:24:32 4563 8192000000 10029205200 10428649472 1100134176 11.839 100 56 108 1 1140079696 28227568 11717952
02:25:37 10460 8192000000 10022839280 10439151616 1092594768 11.767 99 28 56 0 1130000384 30695584 6710032
02:26:53 4858 8192000000 9998085664 10388766720 1046756800 11.330 96 37 74 0 1080561344 25513280 8291264
02:28:00 24211 8192000000 9974192944 10381086720 1013927088 11.013 93 19 37 1 1034436720 16664272 3845360
02:29:25 9614 8192000000 9969337680 10369261568 1026966032 11.139 96 31 60 2 1053774736 19957424 6851280
02:30:37 8247 8192000000 9935021056 10320232448 971076720 10.597 94 33 63 3 998834080 20459344 7298016
02:31:49 91775 8192000000 9947518336 10337984512 1002686944 10.905 97 26 52 1 1025445072 17194928 5563200
02:34:21 5944 8192000000 9959373920 10349416448 1043776816 11.301 100 38 76 3 1070214864 18069712 8368336
02:35:35 4607 8192000000 9977437008 10369757184 1061109120 11.467 100 55 111 3 1094974128 21506336 12358672
02:36:40 78318 8192000000 9994839376 10386329600 1082768240 11.674 100 45 87 0 1108051520 16880688 8402592
02:39:07 7903 8192000000 10056756096 10453286912 1097364784 11.813 100 53 105 2 1170058064 61832464 10860816
02:40:22 7082 8192000000 10055136416 10454843392 1112815664 11.959 100 49 99 0 1168486704 45081680 10589360
02:41:31 17487 8192000000 10087019728 10502221824 1123433072 12.059 100 40 79 1 1208432944 75938768 9061104
02:42:54 7278 8192000000 10147465568 10550292480 1142413200 12.238 100 55 108 0 1245683568 91058160 12212208
02:44:05 13508 8192000000 10159336960 10571399168 1154632848 12.353 100 34 66 0 1255454064 93722512 7098704
02:45:25 5183 8192000000 10207876816 10613186560 1159423040 12.398 100 65 129 1 1313512048 139990128 14098880
02:46:34 13732 8192000000 10230998528 10641608704 1171575952 12.512 100 51 102 0 1343001408 160240320 11185136
02:47:56 2836 8192000000 10272763632 10679091200 1176545856 12.558 99 72 144 1 1369138576 176141344 16451376
02:49:02 6223 8192000000 10310288208 10718003200 1184517552 12.632 100 73 144 1 1414185344 212967168 16700624
02:50:13 3188 8192000000 10383258800 10796642304 1186801392 12.654 100 69 138 0 1478604768 275847088 15956288
02:51:26 993 8192000000 10426589696 10843529216 1194108016 12.722 100 92 183 3 1525396032 310465088 20822928
02:52:33 4888 8192000000 10435563872 10842308608 1182870224 12.617 100 72 144 0 1536399920 336672000 16857696
02:53:40 1262 8192000000 10436012832 10831310848 1165452944 12.454 99 87 172 3 1535128464 349678576 19996944
02:54:45 1847 8192000000 10444096976 10856800256 1162722368 12.429 100 82 164 0 1544191120 361611600 19857152
02:55:51 13977 8192000000 10433381472 10863378432 1140888128 12.224 98 40 78 1 1524775952 373279696 10608128
02:57:09 11877 8192000000 10463706304 10884841472 1158198048 12.386 100 45 90 0 1561407952 391555664 11654240
02:58:32 15202 8192000000 10392187680 10828324864 1101665296 11.853 97 31 62 1 1488944416 378706304 8572816
02:59:52 21001 8192000000 10396675600 10835271680 1110259024 11.935 99 24 48 0 1473227360 356915424 6052912
03:01:15 40469 8192000000 10240929888 10744557568 1035289520 11.219 94 21 43 0 1364039888 323147328 5603040
03:02:57 44045 8192000000 10024736528 10448842752 1077747040 11.626 98 4 8 0 1109982576 31618688 616848
03:04:44 115951 8192000000 10071492064 10502475776 1099933872 11.837 99 41 77 3 1130154384 23268304 6952208
03:07:40 38823 8192000000 10080137360 10514247680 1093181424 11.773 98 51 101 0 1156825024 52035760 11607840
03:09:48 52886 8192000000 10127198336 10550534144 1093671408 11.778 96 53 104 0 1166782832 62500976 10610448
03:11:55 5713 8192000000 10132613520 10566160384 1141865840 12.233 100 37 70 0 1245045440 95524592 7655008
03:13:01 104091 8192000000 10119847472 10566160384 1124491376 12.069 99 12 24 1 1196288976 68832944 2964656
03:15:50 165265 8192000000 10207879056 10666618880 1122664368 12.052 98 25 49 1 1256834416 128817040 5353008
03:19:37 46800 8192000000 10158544304 10629971968 1138379056 12.200 99 41 82 1 1307393952 159755040 9259856
03:21:29 57073 8192000000 10118086048 10594848768 1140086272 12.216 99 20 40 1 1245560720 101351008 4123440
03:23:26 56640 8192000000 10115041888 10578759680 1154758144 12.354 100 27 54 0 1216146704 55819088 5569472
03:25:25 49028 8192000000 10097355376 10520489984 1146897920 12.280 99 41 78 0 1180809616 25208368 8703328
03:27:18 10832 8192000000 10118330992 10546192384 1155468400 12.361 100 63 123 2 1213920240 45920480 12531360
03:28:39 83330 8192000000 10109143360 10619826176 1152995840 12.338 99 19 38 0 1237841744 81021824 3824080
03:31:07 62851 8192000000 10115816720 10568228864 1152627424 12.334 99 35 70 0 1202405168 41632160 8145584
03:33:15 38118 8192000000 10106340240 10614759424 1158721456 12.391 100 7 14 0 1241064160 80858096 1484608
03:35:00 74751 8192000000 10088419664 10554806272 1141599936 12.231 99 18 36 0 1223615392 78108432 3907024
03:37:15 51297 8192000000 10112431984 10582110208 1139582720 12.212 99 12 24 1 1211718720 69685280 2450720
03:39:13 73504 8192000000 10088897616 10551595008 1153959440 12.347 100 17 34 1 1189105264 31485200 3660624
03:41:28 52054 8192000000 10169966592 10624245760 1159362576 12.397 99 26 52 0 1233246992 68493680 5390736
03:43:20 49314 8192000000 10118276208 10604269568 1152083984 12.329 100 18 36 0 1262873728 106884384 3905360
03:45:10 46035 8192000000 10092348224 10521329664 1122458736 12.050 97 42 84 0 1178011072 47281696 8270640
03:46:59 81100 8192000000 10071665664 10511437824 1064709312 11.502 94 38 74 1 1119073440 46448416 7915712
03:49:23 102583 8192000000 10060786576 10499768320 1108611888 11.919 98 25 50 1 1163534208 49514816 5407504
03:52:10 51138 8192000000 10098662864 10530942976 1132571136 12.146 100 32 64 1 1193909424 54457824 6880464
03:54:06 47762 8192000000 10039088624 10508931072 1114783552 11.978 99 10 19 3 1153356128 36369984 2202592
03:55:55 70971 8192000000 10080536032 10533896192 1103649248 11.872 97 16 32 0 1153913536 46851728 3412560
03:58:07 75553 8192000000 10051798432 10542362624 1092969760 11.771 97 9 18 0 1124335168 29613248 1752160
04:00:25 39196 8192000000 10053777344 10497236992 1094779072 11.788 97 22 44 0 1125025456 25675584 4570800
04:02:07 55190 8192000000 10058581968 10504704000 1099057792 11.829 98 32 64 1 1152708288 46982176 6668320
04:04:02 84565 8192000000 10086545264 10531561472 1085561760 11.700 96 27 55 0 1165235056 74151904 5521392
04:06:30 58000 8192000000 10121858192 10581299200 1113343280 11.964 98 30 60 0 1177312032 58094608 5874144
04:08:59 113995 8192000000 10087735968 10521145344 1112997136 11.961 97 16 32 0 1207587744 91259152 3331456
04:12:00 88034 8192000000 10120252080 10595155968 1149489792 12.305 100 34 66 0 1231368512 74181904 7696816
04:14:29 97578 8192000000 10079214032 10573287424 1115948448 11.989 97 7 14 0 1174526288 57251024 1326816
04:17:11 17610 8192000000 10080405744 10522329088 1117316992 12.002 97 36 71 1 1152836432 27286304 8233136
04:18:29 77893 8192000000 10077561648 10527412224 1122749296 12.053 98 27 53 0 1167235424 38672000 5814128
04:20:49 80464 8192000000 10111584544 10550009856 1138362848 12.200 99 21 42 0 1189650176 47276896 4010432
04:23:17 92268 8192000000 10151052880 10655899648 1133121504 12.151 98 17 34 1 1223486976 87140224 3225248
04:25:49 90323 8192000000 10143345264 10614259712 1127532368 12.098 99 8 16 1 1179751632 50251088 1968176
04:28:22 72520 8192000000 10079313840 10540896256 1110093056 11.933 97 19 38 2 1143978832 30091376 3794400
04:31:41 35422 8192000000 10096587392 10518786048 1134927632 12.168 100 55 107 2 1185723088 39697392 11098064
04:33:17 47713 8192000000 10050036192 10502823936 1083162672 11.678 96 23 46 1 1122213488 34146576 4904240
04:35:07 48515 8192000000 10061437488 10520612864 1123348704 12.059 100 11 22 0 1172992160 47286496 2356960
04:36:57 29003 8192000000 9397962384 9747595264 542384192 6.209 49 4 8 0 556805792 13813296 608304

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-12-2021 04:39:51 to 20-12-2021 04:40:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 128 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

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

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          229758
MON$OLDEST_ACTIVE               230197
MON$OLDEST_SNAPSHOT             230197
MON$NEXT_TRANSACTION            230197
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                       2048288
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             790
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2692 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2692 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2692 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2692 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 ---
:89: 
:90: mscs-test-161.budzdorov.ru    Mon Dec 20 04:37:34 2021
:91:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:92: 
:93: 
:94: mscs-test-161.budzdorov.ru    Mon Dec 20 04:37:41 2021
:95:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:96: 
--- end of diff output ---

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

20.12.21 04:40:10. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-12-20 04:40:12 No severe PSQL-related problems occured
20.12.21 04:40:14. End of report.