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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16189884 kB
MemAvailable 31270584 kB
Buffers 0 kB
Cached 15468752 kB
SwapCached 1216 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 2022-01-10 07:37:11
DTS_END                         2022-01-10 07:37:11.7210
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/54410
TRN_ID                          219318
ATT_ID                          667
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: 8833.81 31237 1060057
customer order: creation 164.87 467 19784
customer order: refuse 14.05 393 1686
order to supplier: creation 44.08 1321 5290
order to supplier: removal 2.97 180787 356
invoice (draft): creation 43.41 3335 5209
invoice (draft): removal 48.04 998 5765
invoice accept: apply 41.24 26206 4949
invoice accept: cancel 43.92 1276 5270
customer reserve: creation 3812.04 249 457445
customer reserve: removal 4333.38 97 520005
realization accept: apply 79.48 275 9538
realization accept: cancel 80.08 164 9609
payment from customer: creation 56.58 184 6789
payment from customer: removal 5.67 133 680
payment to supplier: creation 48.02 209 5762
payment to supplier: removal 3.39 113 407
service: total inventory turnovers 4.09 3694 491
service: total monetary turnovers 7.51 437 901
service: aggregate perf. data 0.97 15272 116
service: refresh index statistics 0.04 389135 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 10-01-2022 07:37:56 to 10-01-2022 07:37:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9411
TEST_TIME 2 9148
TEST_TIME 3 7680
TEST_TIME 4 7085
TEST_TIME 5 6757
TEST_TIME 6 6796
TEST_TIME 7 6707
TEST_TIME 8 6561
TEST_TIME 9 6523
TEST_TIME 10 6727
TEST_TIME 11 6905
TEST_TIME 12 7127
TEST_TIME 13 7420
TEST_TIME 14 7562
TEST_TIME 15 7524
TEST_TIME 16 7716
TEST_TIME 17 7676
TEST_TIME 18 7869
TEST_TIME 19 8073
TEST_TIME 20 8335
TEST_TIME 21 8520
TEST_TIME 22 8506
TEST_TIME 23 8804
TEST_TIME 24 9013
TEST_TIME 25 9139
TEST_TIME 26 9321
TEST_TIME 27 9286
TEST_TIME 28 9905
TEST_TIME 29 10010
TEST_TIME 30 10992
TEST_TIME 31 11187
TEST_TIME 32 11099
TEST_TIME 33 11289
TEST_TIME 34 11224
TEST_TIME 35 11068
TEST_TIME 36 10855
TEST_TIME 37 10774
TEST_TIME 38 10674
TEST_TIME 39 10624
TEST_TIME 40 10621
TEST_TIME 41 10486
TEST_TIME 42 10387
TEST_TIME 43 10303
TEST_TIME 44 10202
TEST_TIME 45 10210
TEST_TIME 46 10182
TEST_TIME 47 10017
TEST_TIME 48 10009
TEST_TIME 49 9944
TEST_TIME 50 9927
TEST_TIME 51 9833
TEST_TIME 52 9664
TEST_TIME 53 9663
TEST_TIME 54 9571
TEST_TIME 55 9479
TEST_TIME 56 9373
TEST_TIME 57 9315
TEST_TIME 58 9266
TEST_TIME 59 9185
TEST_TIME 60 9145
TEST_TIME 61 9020
TEST_TIME 62 9054
TEST_TIME 63 9083
TEST_TIME 64 9080
TEST_TIME 65 9069
TEST_TIME 66 9088
TEST_TIME 67 9047
TEST_TIME 68 9076
TEST_TIME 69 9063
TEST_TIME 70 9088
TEST_TIME 71 9107
TEST_TIME 72 9120
TEST_TIME 73 9166
TEST_TIME 74 9129
TEST_TIME 75 9203
TEST_TIME 76 9221
TEST_TIME 77 9153
TEST_TIME 78 9176
TEST_TIME 79 9262
TEST_TIME 80 9180
TEST_TIME 81 9128
TEST_TIME 82 9062
TEST_TIME 83 9041
TEST_TIME 84 9043
TEST_TIME 85 8998
TEST_TIME 86 8959
TEST_TIME 87 8891
TEST_TIME 88 8841
TEST_TIME 89 8828
TEST_TIME 90 8810
TEST_TIME 91 8848
TEST_TIME 92 8827
TEST_TIME 93 8829
TEST_TIME 94 8809
TEST_TIME 95 8798
TEST_TIME 96 8782
TEST_TIME 97 8835
TEST_TIME 98 8860
TEST_TIME 99 8825
TEST_TIME 100 9045
TEST_TIME 101 8993
TEST_TIME 102 9001
TEST_TIME 103 8947
TEST_TIME 104 8942
TEST_TIME 105 8895
TEST_TIME 106 8904
TEST_TIME 107 8870
TEST_TIME 108 8867
TEST_TIME 109 8856
TEST_TIME 110 8889
TEST_TIME 111 8844
TEST_TIME 112 8813
TEST_TIME 113 8773
TEST_TIME 114 8834
TEST_TIME 115 8912
TEST_TIME 116 8919
TEST_TIME 117 8909
TEST_TIME 118 8921
TEST_TIME 119 8930
TEST_TIME 120 8859

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-01-2022 07:38:00 to 10-01-2022 07:38:03.

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 1478999 1478999 0 0.00 0 18877 14 0 0
doc_list_biud 1478991 1478991 0 0.00 0 28664 40 0 0
sp_add_invoice_to_stock 5124 4949 175 3.41 70 196080 26207 0 175
sp_cancel_adding_invoice 5270 5270 0 0.00 7 96746 1277 0 0
sp_cancel_client_order 1686 1686 0 0.00 5 12202 393 0 0
sp_cancel_customer_reserve 520005 520005 0 0.00 1 27446 98 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 3 6872 133 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 4 1910 113 0 0
sp_cancel_supplier_invoice 5778 5765 13 0.22 2 28670 999 0 13
sp_cancel_supplier_order 356 356 0 0.00 17 1378967 180787 0 0
sp_cancel_write_off 9609 9609 0 0.00 2 16121 164 0 0
sp_client_order 19784 19784 0 0.00 6 64552 467 0 0
sp_customer_reserve 457445 457445 0 0.00 1 90077 249 0 0
sp_fill_shopping_cart 30282 30282 0 0.00 1 23632 93 0 0
sp_fill_shopping_cart_clo_res 457417 457417 0 0.00 0 32418 19 0 0
sp_get_clo_for_invoice 4904 4904 0 0.00 0 29974 380 0 0
sp_kill_cost_storno 15966 15966 0 0.00 0 14322 75 0 0
sp_kill_qstorno_ret_qs2qd 1055740 1055740 0 0.00 0 28663 42 0 0
sp_kill_qty_storno 557133 557133 0 0.00 0 28664 98 0 0
sp_lock_selected_doc 20080 20080 0 0.00 0 9185 7 0 0
sp_make_cost_storno 26993 26993 0 0.00 0 16860 91 0 0
sp_multiply_rows_for_pdistr 26993 26993 0 0.00 0 10105 35 0 0
sp_multiply_rows_for_qdistr 19783 19783 0 0.00 0 64533 183 0 0
sp_pay_from_customer 6789 6789 0 0.00 6 16897 184 0 0
sp_pay_to_supplier 5762 5762 0 0.00 7 23197 209 0 0
sp_payment_common 12551 12551 0 0.00 2 23153 50 0 0
sp_qd_handle_on_cancel_clo 1686 1686 0 0.00 0 12192 323 0 0
sp_qd_handle_on_invoice_upd_sts 10174 10174 0 0.00 0 20122 539 0 0
sp_qd_handle_on_reserve_upd_sts 9538 9538 0 0.00 0 12913 61 0 0
sp_reserve_write_off 9538 9538 0 0.00 8 15155 275 0 0
sp_supplier_invoice 5209 5209 0 0.00 8 81509 3336 0 0
sp_supplier_order 5290 5290 0 0.00 8 33488 1321 0 0
srv_aggregate_perf_data 116 116 0 0.00 2353 164703 15273 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 839 131020 37946 0 0
srv_find_qd_qs_mism 1011252 1011252 0 0.00 0 18821 10 0 0
srv_make_invnt_saldo 491 491 0 0.00 47 65058 3694 0 0
srv_make_money_saldo 901 901 0 0.00 1 36446 437 0 0
srv_recalc_idx_stat 5 5 0 0.00 252046 455565 389136 0 0
t$perf-abend:sp_add_invoice_to_stock 175 175 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4951 4951 0 0.00 1 9933 1001 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 1 5748 461 0 0
t$perf-norm:sp_cancel_client_order 1686 1686 0 0.00 0 6466 39 0 0
t$perf-norm:sp_cancel_customer_reserve 666 666 0 0.00 0 1324 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 2924 24 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 1007 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 419 419 0 0.00 0 5951 62 0 0
t$perf-norm:sp_cancel_supplier_order 356 356 0 0.00 0 99656 7334 0 0
t$perf-norm:sp_cancel_write_off 1012 1012 0 0.00 0 704 18 0 0
t$perf-norm:sp_client_order 19784 19784 0 0.00 0 6191 36 0 0
t$perf-norm:sp_customer_reserve 943 943 0 0.00 0 541 13 0 0
t$perf-norm:sp_pay_from_customer 6789 6789 0 0.00 0 9234 22 0 0
t$perf-norm:sp_pay_to_supplier 5762 5762 0 0.00 0 9436 20 0 0
t$perf-norm:sp_reserve_write_off 9538 9538 0 0.00 0 4046 22 0 0
t$perf-norm:sp_supplier_invoice 5209 5209 0 0.00 0 5048 33 0 0
t$perf-norm:sp_supplier_order 5290 5290 0 0.00 0 7537 35 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 70 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 676 13 0 0
t$perf-norm:srv_make_invnt_saldo 491 491 0 0.00 0 438 11 0 0
t$perf-norm:srv_make_money_saldo 901 901 0 0.00 0 262 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 141 39 0 0
v_add_invoice_to_stock 10156 9806 350 3.44 0 13346 26 0 350
v_all_customers 36226 36226 0 0.00 0 136 4 0 0
v_all_suppliers 29412 29412 0 0.00 0 91 4 0 0
v_all_wares 39566 39566 0 0.00 0 57 4 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 296 13 0 0
v_cancel_client_order 3372 3372 0 0.00 0 722 9 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 2455 15 0 0
v_cancel_customer_reserve 1332 1332 0 0.00 0 2560 12 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 897 10 0 0
v_cancel_supplier_invoice 864 838 26 3.00 0 4284 35 0 26
v_cancel_supplier_order 712 712 0 0.00 0 817 12 0 0
v_cancel_write_off 2024 2024 0 0.00 0 5705 28 0 0
v_max_id_clo_ord 5290 5290 0 0.00 0 1580 6 0 0
v_max_id_clo_res 943 943 0 0.00 0 3212 19 0 0
v_max_id_ord_sup 5208 5208 0 0.00 0 3409 7 0 0
v_max_non_paid_invoice 1554 1554 0 0.00 0 598 8 0 0
v_max_non_paid_realizn 3460 3460 0 0.00 0 4201 8 0 0
v_min_id_clo_ord 5290 5290 0 0.00 0 1774 8 0 0
v_min_id_clo_res 943 943 0 0.00 0 3366 13 0 0
v_min_id_ord_sup 5208 5208 0 0.00 0 7125 12 0 0
v_min_non_paid_invoice 5762 5762 0 0.00 0 23145 19 0 0
v_min_non_paid_realizn 6789 6789 0 0.00 0 11846 20 0 0
v_our_firm 9996 9996 0 0.00 0 59 4 0 0
v_reserve_write_off 19076 19076 0 0.00 0 6509 11 0 0
x_lock_depdocs_on_canc_invoice 10616 10616 0 0.00 0 34058 142 0 0
x_lock_depdocs_on_canc_sup_ord 356 356 0 0.00 0 3736 363 0 0
x_make_qstorno_1000_1200 5290 5290 0 0.00 3 33422 1136 0 0
x_make_qstorno_1200_2000 5209 5209 0 0.00 3 69064 3074 0 0
x_make_qstorno_2100_3300 457444 457444 0 0.00 0 89992 217 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 10-01-2022 07:38:04 to 10-01-2022 07:38:09.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:15 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:16 6098 8192000000 9707780656 10000977920 792352816 8.819 100 85 170 0 819801472 7323232 20125424
05:09:27 20328 8192000000 9751689408 10055196672 828867040 9.188 100 90 180 0 860984496 10143504 21973952
05:10:54 18688 8192000000 9794554960 10104651776 857320672 9.473 100 87 174 0 894755776 17209312 20225792
05:12:17 11229 8192000000 9837490400 10161930240 891832128 9.817 100 79 157 0 942216976 33210624 17174224
05:13:32 9171 8192000000 9910303520 10247630848 944280688 10.335 100 88 175 1 1015700512 53189184 18230640
05:14:45 1507 8192000000 9948956432 10297434112 981302784 10.697 100 86 167 1 1071528048 72654624 17570640
05:15:48 1146 8192000000 9981730800 10339024896 1013356256 11.008 100 96 188 0 1102650064 69898992 19394816
05:16:51 1480 8192000000 10013837232 10376540160 1034827744 11.215 100 92 176 3 1126803472 74275152 17700576
05:17:54 1059 8192000000 10040178272 10415697920 1057081152 11.429 100 95 181 0 1160400304 85542240 17776912
05:18:56 985 8192000000 10027109376 10404790272 1063772224 11.493 100 96 189 3 1145078688 62875824 18430640
05:19:59 3839 8192000000 10042433024 10431123456 1072503664 11.576 100 68 130 2 1158979520 74552736 11923120
05:21:04 1066 8192000000 10057060304 10438459392 1083964160 11.685 100 88 167 2 1175677680 74996176 16717344
05:22:06 1369 8192000000 10067898288 10453614592 1086658240 11.711 100 97 187 0 1188629888 83140480 18831168
05:23:07 1040 8192000000 10052409840 10441703424 1087681200 11.721 100 84 167 0 1172178064 67230320 17266544
05:24:09 3598 8192000000 10048336496 10435633152 1091531312 11.757 100 66 121 2 1168036176 64431824 12073040
05:25:14 9577 8192000000 10062933952 10456502272 1095074688 11.791 100 64 122 1 1183779312 76093504 12611120
05:26:24 8918 8192000000 10054978576 10446774272 1093166080 11.773 100 69 133 0 1166864480 61176096 12522304
05:27:33 1145 8192000000 10044053584 10430644224 1100566192 11.843 100 90 165 4 1163101136 45168736 17366208
05:28:35 957 8192000000 10065986768 10453180416 1097393264 11.813 100 90 169 2 1184819968 69857472 17569232
05:29:37 1394 8192000000 10035662912 10423984128 1074485376 11.595 99 79 150 0 1154550704 65012928 15052400
05:30:39 1108 8192000000 10033726192 10417553408 1079680192 11.644 100 87 163 2 1156904816 59447424 17777200
05:31:41 1780 8192000000 10009252192 10383261696 1046489904 11.327 99 88 166 3 1130395792 67988432 15917456
05:32:43 1361 8192000000 10017788768 10392625152 1041461424 11.279 99 80 154 1 1137696784 81367472 14867888
05:33:45 1194 8192000000 9969533296 10340478976 1023601344 11.107 100 93 175 3 1090200544 49162336 17436864
05:34:47 5376 8192000000 9971965136 10343940096 1041466544 11.279 100 81 145 2 1092274928 37043696 13764688
05:35:53 2959 8192000000 9998648672 10378616832 1034319424 11.210 100 74 140 1 1124002288 75542800 14140064
05:36:57 1170 8192000000 9984881744 10359353344 1029036128 11.159 99 96 178 1 1103352080 55703664 18612288
05:38:04 15186 8192000000 10002544144 10383015936 1052380016 11.383 100 67 126 3 1127081456 62262512 12438928
05:39:23 1398 8192000000 10034411040 10413400064 1074050880 11.591 100 80 146 3 1148899264 60314448 14533936
05:40:32 6393 8192000000 10046221056 10431098880 1071650976 11.568 100 71 136 0 1166878048 81991744 13235328
05:41:42 925 8192000000 10108796832 10493345792 1098523536 11.824 100 87 171 2 1228433408 111116016 18793856
05:42:46 1448 8192000000 10127815568 10509574144 1112377648 11.955 100 86 166 2 1240431728 110902288 17151792
05:43:52 2724 8192000000 10156872752 10548789248 1120776464 12.034 100 72 138 0 1275690128 139756208 15157456
05:44:58 3575 8192000000 10204192000 10595885056 1132466128 12.145 100 80 151 1 1317938512 169162272 16310112
05:46:04 2424 8192000000 10219799456 10610757632 1140516416 12.220 100 73 136 1 1329506896 174225088 14765392
05:47:09 1117 8192000000 10287255904 10681794560 1153856496 12.346 100 95 183 1 1400280224 226389568 20034160
05:48:13 4056 8192000000 10306885776 10706538496 1160931744 12.412 100 87 164 1 1414058176 235193872 17932560
05:49:22 1617 8192000000 10288501520 10703974400 1172630368 12.521 100 85 160 0 1398922464 209429376 16862720
05:50:26 1200 8192000000 10341717472 10743111680 1178924608 12.580 100 87 169 1 1455753984 257810432 19018944
05:51:31 6380 8192000000 10360554256 10784169984 1187912448 12.664 100 79 156 0 1467308800 261577792 17818560
05:52:43 1105 8192000000 10394251712 10812366848 1196935504 12.748 100 92 182 0 1502153616 283258784 21959328
05:53:48 2111 8192000000 10424424832 10830417920 1193531232 12.716 100 86 167 0 1533967872 321340880 19095760
05:54:53 2614 8192000000 10445800320 10875011072 1199342848 12.770 100 82 162 0 1553021024 334295968 19382208
05:55:58 899 8192000000 10490073984 10912292864 1200405488 12.780 100 95 189 1 1597908880 374763936 22739456
05:57:01 1302 8192000000 10502204224 10933624832 1206100448 12.833 100 95 185 0 1609050720 379406432 23543840
05:58:04 836 8192000000 10525229312 10953900032 1211830656 12.886 100 94 187 3 1631398320 397976912 21590752
05:59:07 1018 8192000000 10559028560 10967789568 1189176048 12.676 100 95 181 0 1665270192 454417168 21676976
06:00:09 1757 8192000000 10560006432 11026165760 1200962944 12.785 100 84 166 1 1665375056 444460416 19951696
06:01:13 922 8192000000 10519033168 10978193408 1189443488 12.678 100 96 187 2 1626793104 414932768 22416848
06:02:18 15794 8192000000 10515526176 10957008896 1187404016 12.659 100 46 92 0 1631301568 432424752 11472800
06:03:38 20505 8192000000 10504998496 10950139904 1159849520 12.402 98 39 78 0 1595237552 425253424 10134608
06:05:00 27725 8192000000 10456666816 11006328832 1161338304 12.416 100 29 58 0 1604668768 435131952 8198512
06:06:41 47205 8192000000 10262449408 10822758400 1120796256 12.035 98 26 52 0 1421904208 294084592 7023360
06:08:29 42700 8192000000 10160098256 10601840640 1100384432 11.841 97 31 61 2 1225130816 117944288 6802096
06:10:13 41157 8192000000 10140538448 10569203712 1129949440 12.121 100 24 48 1 1254346208 119206272 5190496
06:12:24 35062 8192000000 10140875424 10557194240 1132567904 12.146 100 55 104 2 1238402720 94459248 11375568
06:13:59 124179 8192000000 10143204688 10643525632 1106212688 11.897 98 20 40 2 1259550048 148457200 4880160
06:17:46 50082 8192000000 10107152144 10544291840 1111046240 11.942 99 24 48 0 1212624080 96219888 5357952
06:19:37 68904 8192000000 10126783072 10574786560 1128519872 12.107 100 24 48 0 1239600240 106034208 5046160
06:21:57 24723 8192000000 10124956816 10571079680 1125536848 12.079 100 55 101 0 1236242656 99691616 11014192
06:23:25 81508 8192000000 10111848944 10554863616 1125181792 12.076 99 14 28 0 1197956880 69909600 2865488
06:25:50 77953 8192000000 10081157888 10558550016 1128239408 12.105 99 9 18 0 1203078320 73024512 1814400
06:28:11 128801 8192000000 10080129024 10527436800 1119725056 12.024 98 17 35 0 1167534016 44135456 3673504
06:31:20 76577 8192000000 10054420032 10515787776 1142075824 12.235 100 12 24 0 1175252960 30109072 3068064
06:33:47 1997 8192000000 10061542208 10492891136 1125715296 12.081 99 64 116 4 1164989696 25851152 13423248
06:34:49 60887 8192000000 10096497520 10551603200 1130626112 12.127 99 30 58 2 1199492448 63208144 5658192
06:36:57 54154 8192000000 10148663968 10615840768 1148405728 12.295 100 23 46 1 1259954832 106898384 4650720
06:38:52 86553 8192000000 10072471104 10613387264 1118013856 12.008 97 14 28 0 1240224288 118965888 3244544
06:41:19 47301 8192000000 10067852192 10508935168 1114360432 11.974 98 19 35 1 1148159632 29849344 3949856
06:43:08 60155 8192000000 10088012768 10554023936 1106012000 11.895 97 33 63 1 1182500816 70045200 6443616
06:45:16 6762 8192000000 10089376592 10538946560 1140227360 12.218 100 69 127 2 1209922240 57465728 12229152
06:46:24 27921 8192000000 10103823488 10537398272 1125770768 12.081 99 35 70 2 1197999264 65277152 6951344
06:47:59 58727 8192000000 10107679952 10549207040 1119506752 12.022 98 31 58 4 1193383152 68203680 5672720
06:49:59 52530 8192000000 10104863824 10574073856 1132746976 12.147 99 30 60 0 1199792144 60222048 6823120
06:51:55 52323 8192000000 10131671072 10611326976 1140441888 12.220 100 44 88 2 1263447584 114273216 8732480
06:53:50 46995 8192000000 10101606192 10536370176 1111463760 11.946 97 15 28 1 1146628912 32211248 2953904
06:55:37 89288 8192000000 10075508576 10609627136 1117405056 12.002 99 16 32 1 1224670304 103976560 3288688
06:58:47 57795 8192000000 10081813248 10512801792 1098868688 11.827 97 36 72 0 1139308544 33031072 7408784
07:00:45 52438 8192000000 10053694240 10528899072 1118566784 12.013 99 15 30 0 1169845152 47812016 3466352
07:02:38 86335 8192000000 10063865728 10530955264 1122752304 12.053 99 13 25 3 1171448816 45369152 3327360
07:05:30 33494 8192000000 10076742016 10533666816 1116740144 11.996 99 13 26 0 1167611136 48078176 2792816
07:07:12 54282 8192000000 10054339984 10538999808 1106864944 11.903 98 26 52 0 1194940832 81552896 6522992
07:09:10 34492 8192000000 10110193392 10552164352 1104450736 11.880 98 42 83 0 1184635952 70698960 9486256
07:10:48 33102 8192000000 10115594672 10552463360 1114917792 11.979 99 18 36 1 1190540624 71530960 4091872
07:12:22 34686 8192000000 10143137504 10570170368 1079721472 11.645 95 32 64 1 1189584288 102650096 7212720
07:14:06 26256 8192000000 10205469232 10632286208 1122585056 12.051 99 46 92 1 1280547248 147607392 10354800
07:15:36 87048 8192000000 10227925120 10687938560 1137815392 12.195 100 33 66 0 1290364720 145279760 7269568
07:18:05 61899 8192000000 10140100704 10564366336 1117861056 12.007 98 42 84 0 1179558048 53421648 8275344
07:20:19 71956 8192000000 10088283792 10593517568 1137600768 12.193 100 21 42 1 1204965520 63333344 4031408
07:22:32 64992 8192000000 10065673104 10558681088 1121381056 12.040 98 8 16 0 1165860528 42951616 1527856
07:25:10 131020 8192000000 10074266208 10551181312 1096405168 11.804 96 20 40 0 1164289696 64023632 3860896
07:28:25 42736 8192000000 10125487632 10562985984 1140236240 12.218 99 23 46 3 1223085392 78158960 4690192
07:30:15 36102 8192000000 10237008352 10666442752 1161270720 12.415 100 44 88 2 1298024208 125895728 10857760
07:31:52 74136 8192000000 10087899872 10614095872 1122549616 12.051 98 11 22 0 1249353088 124180528 2622944
07:34:15 23379 8192000000 10126920208 10561667072 1128359440 12.106 98 49 98 2 1195566864 56855536 10351888
07:35:39 42409 8192000000 10112860368 10561671168 1116824736 11.997 97 37 72 1 1181229696 56990672 7414288

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 10-01-2022 07:38:10 to 10-01-2022 07:38:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 175 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-01-2022 07:38:27 to 10-01-2022 07:38:29.

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          214258
MON$OLDEST_ACTIVE               219462
MON$OLDEST_SNAPSHOT             219462
MON$NEXT_TRANSACTION            219462
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                       2067040
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             770
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2704 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2704 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2704 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2704 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 ---
:254: 
:255: mscs-test-161.budzdorov.ru    Mon Jan 10 07:37:12 2022
:256:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:257: 
:258: 
:259: mscs-test-161.budzdorov.ru    Mon Jan 10 07:37:16 2022
:260:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:261: 
:262: 
:263: mscs-test-161.budzdorov.ru    Mon Jan 10 07:37:17 2022
:264:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:265: 
--- end of diff output ---

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

10.01.22 07:38:30. 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
2022-01-10 07:38:33 No severe PSQL-related problems occured
10.01.22 07:38:34. End of report.