Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.12.2021 10:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16627348 kB
MemAvailable 31430416 kB
Buffers 0 kB
Cached 15099696 kB
SwapCached 440 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-26 10:37:09
DTS_END                         2021-12-26 10:37:09.2770
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/49266
TRN_ID                          228038
ATT_ID                          665
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: 8918.13 23985 1070176
customer order: creation 161.06 404 19327
customer order: refuse 13.86 347 1663
order to supplier: creation 42.68 1393 5121
order to supplier: removal 3.17 116764 380
invoice (draft): creation 43.73 3695 5247
invoice (draft): removal 51.73 916 6207
invoice accept: apply 39.17 19500 4700
invoice accept: cancel 47.00 848 5640
customer reserve: creation 3613.25 193 433590
customer reserve: removal 4610.09 63 553211
realization accept: apply 79.78 186 9574
realization accept: cancel 91.27 123 10952
payment from customer: creation 54.76 109 6571
payment from customer: removal 5.46 78 655
payment to supplier: creation 46.93 125 5632
payment to supplier: removal 3.51 89 421
service: total inventory turnovers 3.85 3264 462
service: total monetary turnovers 5.87 554 704
service: aggregate perf. data 0.96 11973 115
service: refresh index statistics 0.03 319072 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-12-2021 10:39:04 to 26-12-2021 10:39:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4284
TEST_TIME 2 4635
TEST_TIME 3 5270
TEST_TIME 4 5394
TEST_TIME 5 5789
TEST_TIME 6 5543
TEST_TIME 7 5874
TEST_TIME 8 5722
TEST_TIME 9 5775
TEST_TIME 10 6242
TEST_TIME 11 6260
TEST_TIME 12 6391
TEST_TIME 13 6417
TEST_TIME 14 6537
TEST_TIME 15 6969
TEST_TIME 16 7529
TEST_TIME 17 7828
TEST_TIME 18 8656
TEST_TIME 19 8964
TEST_TIME 20 9154
TEST_TIME 21 9476
TEST_TIME 22 9784
TEST_TIME 23 10273
TEST_TIME 24 10812
TEST_TIME 25 11110
TEST_TIME 26 10956
TEST_TIME 27 10755
TEST_TIME 28 10605
TEST_TIME 29 10426
TEST_TIME 30 10194
TEST_TIME 31 10048
TEST_TIME 32 10144
TEST_TIME 33 9942
TEST_TIME 34 9781
TEST_TIME 35 9643
TEST_TIME 36 9502
TEST_TIME 37 9559
TEST_TIME 38 9579
TEST_TIME 39 9439
TEST_TIME 40 9430
TEST_TIME 41 9358
TEST_TIME 42 9503
TEST_TIME 43 9552
TEST_TIME 44 9691
TEST_TIME 45 9619
TEST_TIME 46 9581
TEST_TIME 47 9569
TEST_TIME 48 9484
TEST_TIME 49 9690
TEST_TIME 50 9738
TEST_TIME 51 9744
TEST_TIME 52 9565
TEST_TIME 53 9450
TEST_TIME 54 9552
TEST_TIME 55 9531
TEST_TIME 56 9491
TEST_TIME 57 9398
TEST_TIME 58 9340
TEST_TIME 59 9213
TEST_TIME 60 9296
TEST_TIME 61 9281
TEST_TIME 62 9247
TEST_TIME 63 9291
TEST_TIME 64 9336
TEST_TIME 65 9301
TEST_TIME 66 9306
TEST_TIME 67 9425
TEST_TIME 68 9471
TEST_TIME 69 9486
TEST_TIME 70 9538
TEST_TIME 71 9582
TEST_TIME 72 9619
TEST_TIME 73 9575
TEST_TIME 74 9553
TEST_TIME 75 9438
TEST_TIME 76 9376
TEST_TIME 77 9356
TEST_TIME 78 9294
TEST_TIME 79 9222
TEST_TIME 80 9195
TEST_TIME 81 9145
TEST_TIME 82 9081
TEST_TIME 83 9063
TEST_TIME 84 9084
TEST_TIME 85 9074
TEST_TIME 86 9036
TEST_TIME 87 9058
TEST_TIME 88 9051
TEST_TIME 89 9058
TEST_TIME 90 9068
TEST_TIME 91 9146
TEST_TIME 92 9180
TEST_TIME 93 9169
TEST_TIME 94 9174
TEST_TIME 95 9177
TEST_TIME 96 9216
TEST_TIME 97 9214
TEST_TIME 98 9137
TEST_TIME 99 9088
TEST_TIME 100 9060
TEST_TIME 101 9013
TEST_TIME 102 8974
TEST_TIME 103 8947
TEST_TIME 104 8888
TEST_TIME 105 8884
TEST_TIME 106 8868
TEST_TIME 107 8841
TEST_TIME 108 8874
TEST_TIME 109 8903
TEST_TIME 110 8894
TEST_TIME 111 8905
TEST_TIME 112 8866
TEST_TIME 113 8917
TEST_TIME 114 8903
TEST_TIME 115 8928
TEST_TIME 116 9054
TEST_TIME 117 9026
TEST_TIME 118 9066
TEST_TIME 119 8999
TEST_TIME 120 8925

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-12-2021 10:39:08 to 26-12-2021 10:39:11.

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 1471121 1471121 0 0.00 0 49303 7 0 0
doc_list_biud 1471121 1471121 0 0.00 0 67997 30 0 0
sp_add_invoice_to_stock 4820 4700 120 2.48 63 216361 19500 0 120
sp_cancel_adding_invoice 5640 5640 0 0.00 5 87185 848 0 0
sp_cancel_client_order 1663 1663 0 0.00 4 11938 347 0 0
sp_cancel_customer_reserve 553211 553211 0 0.00 1 50832 63 0 0
sp_cancel_pay_from_customer 655 655 0 0.00 3 3501 79 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 3 5110 89 0 0
sp_cancel_supplier_invoice 6216 6207 9 0.14 2 48188 916 0 9
sp_cancel_supplier_order 380 380 0 0.00 14 1281571 116764 0 0
sp_cancel_write_off 10952 10952 0 0.00 1 12585 124 0 0
sp_client_order 19327 19327 0 0.00 4 31232 405 0 0
sp_customer_reserve 433590 433590 0 0.00 0 70358 194 0 0
sp_fill_shopping_cart 29673 29673 0 0.00 1 35674 47 0 0
sp_fill_shopping_cart_clo_res 433579 433579 0 0.00 0 15829 16 0 0
sp_get_clo_for_invoice 4680 4680 0 0.00 0 22684 398 0 0
sp_kill_cost_storno 17668 17668 0 0.00 0 10027 57 0 0
sp_kill_qstorno_ret_qs2qd 1123961 1123961 0 0.00 0 50829 35 0 0
sp_kill_qty_storno 592306 592306 0 0.00 0 67996 74 0 0
sp_lock_selected_doc 19733 19733 0 0.00 0 8389 6 0 0
sp_make_cost_storno 26456 26456 0 0.00 0 49299 51 0 0
sp_multiply_rows_for_pdistr 26456 26456 0 0.00 0 7149 31 0 0
sp_multiply_rows_for_qdistr 19327 19327 0 0.00 0 21674 222 0 0
sp_pay_from_customer 6571 6571 0 0.00 4 21511 110 0 0
sp_pay_to_supplier 5632 5632 0 0.00 5 14365 125 0 0
sp_payment_common 12203 12203 0 0.00 1 14348 27 0 0
sp_qd_handle_on_cancel_clo 1663 1663 0 0.00 0 11916 315 0 0
sp_qd_handle_on_invoice_upd_sts 10319 10319 0 0.00 0 67996 338 0 0
sp_qd_handle_on_reserve_upd_sts 9574 9574 0 0.00 0 9595 52 0 0
sp_reserve_write_off 9574 9574 0 0.00 7 49315 186 0 0
sp_supplier_invoice 5247 5247 0 0.00 8 90319 3695 0 0
sp_supplier_order 5121 5121 0 0.00 5 83102 1393 0 0
srv_aggregate_perf_data 116 115 1 0.86 3136 119690 11974 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 1148 167863 48710 0 0
srv_find_qd_qs_mism 1025089 1025089 0 0.00 0 15904 6 0 0
srv_make_invnt_saldo 462 462 0 0.00 18 96623 3264 0 0
srv_make_money_saldo 704 704 0 0.00 1 16156 555 0 0
srv_recalc_idx_stat 4 4 0 0.00 204116 459508 319072 0 0
t$perf-abend:sp_add_invoice_to_stock 120 120 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 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 4700 4700 0 0.00 0 15150 142 0 0
t$perf-norm:sp_cancel_adding_invoice 373 373 0 0.00 0 2973 81 0 0
t$perf-norm:sp_cancel_client_order 1663 1663 0 0.00 0 1209 8 0 0
t$perf-norm:sp_cancel_customer_reserve 613 613 0 0.00 0 1011 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 655 655 0 0.00 0 1896 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 277 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 3446 31 0 0
t$perf-norm:sp_cancel_supplier_order 380 380 0 0.00 0 17161 1296 0 0
t$perf-norm:sp_cancel_write_off 984 984 0 0.00 0 4201 12 0 0
t$perf-norm:sp_client_order 19327 19327 0 0.00 0 6069 16 0 0
t$perf-norm:sp_customer_reserve 983 983 0 0.00 0 3493 11 0 0
t$perf-norm:sp_pay_from_customer 6571 6571 0 0.00 0 11389 12 0 0
t$perf-norm:sp_pay_to_supplier 5632 5632 0 0.00 0 5626 10 0 0
t$perf-norm:sp_reserve_write_off 9574 9574 0 0.00 0 5114 10 0 0
t$perf-norm:sp_supplier_invoice 5247 5247 0 0.00 0 3290 16 0 0
t$perf-norm:sp_supplier_order 5121 5121 0 0.00 0 4534 13 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 291 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 10 1 0 0
t$perf-norm:srv_make_invnt_saldo 462 462 0 0.00 0 562 5 0 0
t$perf-norm:srv_make_money_saldo 704 704 0 0.00 0 4076 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 583 148 0 0
v_add_invoice_to_stock 9596 9356 240 2.50 0 18249 26 0 240
v_all_customers 35448 35448 0 0.00 0 13 1 0 0
v_all_suppliers 28746 28746 0 0.00 0 14 1 0 0
v_all_wares 38654 38654 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 746 746 0 0.00 0 2528 9 0 0
v_cancel_client_order 3326 3326 0 0.00 0 1718 5 0 0
v_cancel_customer_prepayment 1310 1310 0 0.00 0 3496 9 0 0
v_cancel_customer_reserve 1226 1226 0 0.00 0 894 3 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 5101 15 0 0
v_cancel_supplier_invoice 802 784 18 2.24 0 1479 17 0 18
v_cancel_supplier_order 760 760 0 0.00 0 2311 10 0 0
v_cancel_write_off 1968 1968 0 0.00 0 12511 21 0 0
v_max_id_clo_ord 5121 5121 0 0.00 0 1547 1 0 0
v_max_id_clo_res 983 983 0 0.00 0 7206 20 0 0
v_max_id_ord_sup 5224 5224 0 0.00 0 5215 6 0 0
v_max_non_paid_invoice 1602 1602 0 0.00 0 3395 4 0 0
v_max_non_paid_realizn 3389 3389 0 0.00 0 3929 5 0 0
v_min_id_clo_ord 5121 5121 0 0.00 0 3153 4 0 0
v_min_id_clo_res 983 983 0 0.00 0 6789 25 0 0
v_min_id_ord_sup 5224 5224 0 0.00 0 5831 12 0 0
v_min_non_paid_invoice 5632 5632 0 0.00 0 14342 21 0 0
v_min_non_paid_realizn 6571 6571 0 0.00 0 8651 21 0 0
v_our_firm 9570 9570 0 0.00 0 21 1 0 0
v_reserve_write_off 19148 19148 0 0.00 0 12537 7 0 0
x_lock_depdocs_on_canc_invoice 11455 11455 0 0.00 0 12215 110 0 0
x_lock_depdocs_on_canc_sup_ord 380 380 0 0.00 0 3701 301 0 0
x_make_qstorno_1000_1200 5121 5121 0 0.00 2 83023 1323 0 0
x_make_qstorno_1200_2000 5247 5247 0 0.00 5 87168 3497 0 0
x_make_qstorno_2100_3300 433590 433590 0 0.00 0 70357 175 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-12-2021 10:39:12 to 26-12-2021 10:39:16.

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
08:07:12 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:13 9815 8192000000 9687790384 9978228736 771558096 8.607 100 86 172 0 800638464 7255456 21824912
08:09:30 31613 8192000000 9751625568 10056822784 822552400 9.124 100 85 170 0 853385920 10561408 20272112
08:11:08 18582 8192000000 9798508032 10118164480 865068624 9.551 100 86 171 1 904846912 20345392 19432896
08:12:36 14948 8192000000 9871664560 10203394048 918456864 10.081 100 88 176 0 981599312 43522960 19619488
08:13:54 6766 8192000000 9935742832 10280300544 964601488 10.534 100 85 170 2 1052127360 69511808 18014064
08:15:03 6414 8192000000 9959229872 10315014144 995974048 10.839 100 79 156 0 1070290416 58152928 16163440
08:16:11 4981 8192000000 9996607024 10360033280 1022391024 11.095 100 79 157 0 1114089648 75455728 16242896
08:17:19 1543 8192000000 10032912960 10397843456 1050606080 11.366 100 93 187 0 1146208208 76683024 18919104
08:18:22 1919 8192000000 10027736864 10404847616 1074378416 11.594 100 83 166 0 1145963072 53791696 17792960
08:19:25 5273 8192000000 10023220576 10404306944 1076686560 11.616 100 69 136 0 1141893264 51117216 14089488
08:20:30 9472 8192000000 9995819728 10400382976 1082091776 11.667 100 41 78 2 1118637520 28377984 8167760
08:21:41 6885 8192000000 9998090720 10386432000 1086723776 11.711 100 42 83 1 1113712576 17544080 9444720
08:22:49 17045 8192000000 9993941184 10406694912 1091645984 11.758 100 25 51 1 1113627872 16408224 5573664
08:24:06 26086 8192000000 10034715600 10422624256 1096231312 11.802 100 84 170 3 1137871936 22500032 19140592
08:25:32 3496 8192000000 10011935840 10416320512 1100193072 11.839 100 34 68 1 1125548800 17725776 7629952
08:26:37 4772 8192000000 9998206480 10409127936 1088673728 11.730 99 26 51 1 1113157104 18404816 6078560
08:27:43 19458 8192000000 10006398192 10421813248 1081018512 11.657 99 28 56 0 1115605696 28531728 6055456
08:29:08 8208 8192000000 9968734720 10372435968 1035303200 11.219 98 37 71 1 1074251376 31218144 7730032
08:30:17 5570 8192000000 9937155968 10307104768 990360160 10.785 95 41 82 3 1020140512 21027136 8753216
08:31:23 67283 8192000000 9928523152 10329026560 975871968 10.644 95 17 35 0 988836736 9205200 3759568
08:33:31 8127 8192000000 9953090896 10338271232 1030913424 11.177 99 43 88 0 1062756208 21724768 10118016
08:34:40 6554 8192000000 9966850128 10359865344 1054511312 11.404 100 43 86 1 1081439712 17658144 9270256
08:35:49 6512 8192000000 9982806976 10374758400 1060555744 11.462 100 45 93 1 1087326192 17175200 9595248
08:36:59 92675 8192000000 10004650400 10397495296 1076452720 11.614 100 47 94 0 1109228544 22637296 10138528
08:39:36 15272 8192000000 10082151152 10475937792 1106725712 11.901 100 51 101 0 1192269760 74925872 10618176
08:40:53 8966 8192000000 10111154880 10506915840 1124168368 12.066 100 57 112 1 1213881120 77697168 12015584
08:42:04 7441 8192000000 10152196720 10556362752 1138205936 12.199 100 61 122 1 1261356144 109662848 13487360
08:43:15 13124 8192000000 10184719120 10593734656 1149172320 12.302 100 51 100 1 1292297856 132657632 10467904
08:44:32 7749 8192000000 10206210608 10616078336 1152679840 12.335 100 51 102 1 1314692272 151129648 10882784
08:45:47 8082 8192000000 10231667632 10643300352 1161360848 12.416 100 57 112 4 1342540128 168642784 12536496
08:47:09 6160 8192000000 10290622784 10700500992 1173827328 12.533 100 66 132 0 1396309472 206148416 16333728
08:48:23 1146 8192000000 10331124736 10729558016 1180456768 12.594 100 82 162 1 1432610144 232949536 19203840
08:49:30 7390 8192000000 10383501040 10790973440 1181913360 12.608 100 72 144 1 1487436368 289369808 16153200
08:50:45 2911 8192000000 10429137536 10827141120 1164860640 12.449 99 87 174 1 1522708544 337193440 20654464
08:51:54 3305 8192000000 10448679184 10864037888 1172806688 12.523 100 78 151 1 1558229072 367002880 18419504
08:53:01 1527 8192000000 10471478016 10871156736 1162219248 12.424 100 93 184 0 1562039248 377856592 21963408
08:54:08 2864 8192000000 10393005648 10811293696 1146690400 12.278 100 68 134 1 1490033840 327789904 15553536
08:55:19 8888 8192000000 10409039360 10845986816 1110652576 11.939 97 37 74 0 1515973792 395308448 10012768
08:56:29 19598 8192000000 10340067520 10763952128 1139950224 12.215 100 30 60 1 1432371536 284845856 7575456
08:57:58 8137 8192000000 10348818752 10768887808 1131639760 12.137 99 51 102 1 1443367168 299628896 12098512
08:59:08 26540 8192000000 10274570304 10761482240 1096183776 11.801 98 22 44 1 1370338224 268306256 5848192
09:00:39 34219 8192000000 10186241008 10625396736 1080659024 11.654 98 23 46 1 1274569712 188100384 5810304
09:02:16 42934 8192000000 10100506784 10551762944 1111634864 11.948 100 11 22 0 1214350688 100286064 2429760
09:04:07 72278 8192000000 10032104144 10470064128 1103044448 11.867 99 31 61 3 1128632880 19069584 6518848
09:06:22 74601 8192000000 10071380992 10512666624 1091262880 11.755 98 24 48 1 1148062064 51815616 4983568
09:08:37 53305 8192000000 10091084256 10538844160 1100680384 11.844 99 11 22 0 1180341904 77690320 1971200
09:10:31 79209 8192000000 10076675648 10516959232 1111456720 11.946 100 22 43 0 1158933920 42863360 4613840
09:13:12 53282 8192000000 10112902240 10547380224 1128090144 12.103 100 18 36 1 1214643232 82257840 4295248
09:15:05 68633 8192000000 10113920832 10590683136 1139804640 12.214 100 25 50 3 1233728480 88178320 5745520
09:17:15 56543 8192000000 10162423376 10599976960 1146830048 12.280 99 23 45 1 1252358352 100236240 5292064
09:19:13 48836 8192000000 10205407904 10720845824 1152394048 12.332 100 15 30 0 1341527056 185331840 3801168
09:21:02 71395 8192000000 10102352048 10558443520 1125317776 12.077 98 20 37 1 1152757280 23530800 3908704
09:23:23 20945 8192000000 10093039792 10519433216 1122195088 12.048 99 42 84 0 1162559824 32210064 8154672
09:24:49 22849 8192000000 10150750576 10591838208 1143179584 12.245 100 32 64 0 1228152752 78292240 6680928
09:26:15 73834 8192000000 10114606064 10562146304 1127360496 12.096 99 17 34 0 1190891904 60304560 3226848
09:28:30 167863 8192000000 10171616112 10619416576 1139771376 12.213 99 19 38 1 1226385168 82657248 3956544
09:32:19 47327 8192000000 10105793552 10583236608 1149144832 12.301 100 19 38 0 1196722992 43561696 4016464
09:34:07 87042 8192000000 10115736672 10571059200 1123070576 12.056 97 15 30 0 1179733280 53472800 3189904
09:36:34 54945 8192000000 10139279456 10639364096 1151107328 12.320 100 20 40 0 1265042208 109202400 4732480
09:38:42 79060 8192000000 10140130896 10584391680 1119870976 12.026 97 10 20 1 1194969808 73063248 2035584
09:41:01 45431 8192000000 10102737360 10550493184 1146032208 12.272 99 14 28 1 1197686624 48567232 3087184
09:42:58 34938 8192000000 10151380576 10629611520 1158208336 12.386 100 18 36 1 1242059472 79614400 4236736
09:44:34 96710 8192000000 10084277440 10589917184 1098483824 11.823 96 14 28 0 1201974704 100593392 2897488
09:47:11 65669 8192000000 10103106800 10543702016 1108969856 11.923 97 27 52 2 1171823408 57595136 5258416
09:49:17 70694 8192000000 10066432272 10517671936 1063757584 11.492 94 13 24 2 1096039328 30078816 2202928
09:51:32 93100 8192000000 10070501840 10489225216 1062041936 11.476 95 43 86 0 1115597232 44868688 8686608
09:54:27 34549 8192000000 10022994832 10466381824 1108789792 11.921 100 6 12 0 1130745440 20535728 1419920
09:56:09 102283 8192000000 10062324864 10534699008 1105384880 11.889 98 7 14 0 1140845376 34210528 1249968
10:00:06 29255 8192000000 10107925968 10557194240 1123667104 12.062 99 25 51 2 1202213120 73397456 5148560
10:01:35 113564 8192000000 10049898480 10482757632 1063538768 11.490 95 16 32 0 1101598288 34651008 3408512
10:04:34 44434 8192000000 10062637040 10525188096 1118910704 12.017 100 33 66 0 1186298800 59705616 7682480
10:06:21 22276 8192000000 10076222256 10506797056 1108045792 11.914 99 38 76 0 1165094560 49324160 7724608
10:07:49 60572 8192000000 10055061152 10524450816 1115033040 11.980 99 20 40 0 1176070944 57248976 3788928
10:10:17 63041 8192000000 10092698512 10525327360 1110421456 11.936 97 31 62 0 1152679104 36667152 5590496
10:12:32 61464 8192000000 10143352512 10607013888 1146156256 12.273 99 17 34 1 1227484688 77745776 3582656
10:15:24 91437 8192000000 10048924176 10485272576 1089382512 11.737 96 36 72 0 1132212032 34867856 7961664
10:17:56 67325 8192000000 10082167840 10538995712 1137406896 12.191 100 18 36 0 1178727056 37377040 3943120
10:20:05 82296 8192000000 10074785648 10521026560 1120264816 12.029 99 9 18 0 1157607520 35290000 2052704
10:22:28 85727 8192000000 10086619680 10547900416 1129491296 12.117 99 13 26 0 1193999376 61519376 2988704
10:24:53 48523 8192000000 10088571152 10540388352 1102851440 11.865 97 22 44 0 1170261376 62404192 5005744
10:26:44 54706 8192000000 10074467344 10524872704 1090719152 11.749 96 25 50 0 1147129184 50523216 5886816
10:28:49 55460 8192000000 10133985248 10568642560 1088603840 11.729 96 19 38 0 1194996112 102011904 4380368
10:30:48 55499 8192000000 10172677376 10619183104 1133413632 12.154 100 17 34 0 1249122784 111892288 3816864
10:32:43 38744 8192000000 10117922384 10631860224 1137197504 12.189 100 20 40 1 1242504496 100942256 4364736
10:34:23 48320 8192000000 10106803136 10555564032 1136463168 12.182 100 28 56 0 1189989328 47900352 5625808
10:36:24 51745 8192000000 9296682144 9612439552 427543200 4.960 38 9 17 1 451742336 22475520 1723616

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-12-2021 10:39:18 to 26-12-2021 10:39:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 120 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-12-2021 10:39:34 to 26-12-2021 10:39:36.

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          226371
MON$OLDEST_ACTIVE               228346
MON$OLDEST_SNAPSHOT             228346
MON$NEXT_TRANSACTION            228346
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                       2033696
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             783
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2696 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2696 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2696 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2696 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

26.12.21 10:39:37. 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-26 10:39:39 No severe PSQL-related problems occured
26.12.21 10:39:41. End of report.