Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.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 16378096 kB
MemAvailable 31322032 kB
Buffers 0 kB
Cached 15284244 kB
SwapCached 1196 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-03 07:37:07
DTS_END                         2022-01-03 07:37:07.8030
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/59958
TRN_ID                          232129
ATT_ID                          642
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: 9094.79 21336 1091375
customer order: creation 171.73 396 20608
customer order: refuse 14.63 354 1755
order to supplier: creation 45.06 1420 5407
order to supplier: removal 3.42 113629 410
invoice (draft): creation 45.09 3846 5411
invoice (draft): removal 50.83 1013 6099
invoice accept: apply 41.22 20707 4946
invoice accept: cancel 46.34 1021 5561
customer reserve: creation 3799.77 206 455972
customer reserve: removal 4579.41 67 549529
realization accept: apply 83.67 190 10040
realization accept: cancel 85.60 128 10272
payment from customer: creation 58.81 91 7057
payment from customer: removal 5.93 109 711
payment to supplier: creation 49.07 115 5888
payment to supplier: removal 3.17 93 380
service: total inventory turnovers 3.79 3977 455
service: total monetary turnovers 6.39 610 767
service: aggregate perf. data 0.85 17789 102
service: refresh index statistics 0.04 260961 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-01-2022 07:38:16 to 03-01-2022 07:38:18.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4888
TEST_TIME 2 5043
TEST_TIME 3 5004
TEST_TIME 4 4748
TEST_TIME 5 4943
TEST_TIME 6 4947
TEST_TIME 7 4799
TEST_TIME 8 5551
TEST_TIME 9 6065
TEST_TIME 10 6298
TEST_TIME 11 6304
TEST_TIME 12 6685
TEST_TIME 13 7024
TEST_TIME 14 7108
TEST_TIME 15 7154
TEST_TIME 16 7198
TEST_TIME 17 7755
TEST_TIME 18 7819
TEST_TIME 19 7965
TEST_TIME 20 8380
TEST_TIME 21 8526
TEST_TIME 22 8606
TEST_TIME 23 8834
TEST_TIME 24 9162
TEST_TIME 25 9054
TEST_TIME 26 8991
TEST_TIME 27 9117
TEST_TIME 28 9495
TEST_TIME 29 10513
TEST_TIME 30 10628
TEST_TIME 31 10735
TEST_TIME 32 10625
TEST_TIME 33 10647
TEST_TIME 34 10402
TEST_TIME 35 10164
TEST_TIME 36 9894
TEST_TIME 37 9768
TEST_TIME 38 9870
TEST_TIME 39 9724
TEST_TIME 40 9683
TEST_TIME 41 9665
TEST_TIME 42 9768
TEST_TIME 43 9613
TEST_TIME 44 9607
TEST_TIME 45 9526
TEST_TIME 46 9384
TEST_TIME 47 9426
TEST_TIME 48 9565
TEST_TIME 49 9585
TEST_TIME 50 9517
TEST_TIME 51 9577
TEST_TIME 52 9582
TEST_TIME 53 9580
TEST_TIME 54 9588
TEST_TIME 55 9611
TEST_TIME 56 9625
TEST_TIME 57 9637
TEST_TIME 58 9572
TEST_TIME 59 9475
TEST_TIME 60 9457
TEST_TIME 61 9400
TEST_TIME 62 9359
TEST_TIME 63 9288
TEST_TIME 64 9315
TEST_TIME 65 9251
TEST_TIME 66 9236
TEST_TIME 67 9269
TEST_TIME 68 9208
TEST_TIME 69 9157
TEST_TIME 70 9124
TEST_TIME 71 9246
TEST_TIME 72 9342
TEST_TIME 73 9335
TEST_TIME 74 9331
TEST_TIME 75 9347
TEST_TIME 76 9457
TEST_TIME 77 9503
TEST_TIME 78 9489
TEST_TIME 79 9574
TEST_TIME 80 9502
TEST_TIME 81 9411
TEST_TIME 82 9389
TEST_TIME 83 9320
TEST_TIME 84 9326
TEST_TIME 85 9253
TEST_TIME 86 9233
TEST_TIME 87 9177
TEST_TIME 88 9093
TEST_TIME 89 9072
TEST_TIME 90 9132
TEST_TIME 91 9112
TEST_TIME 92 9110
TEST_TIME 93 9164
TEST_TIME 94 9240
TEST_TIME 95 9268
TEST_TIME 96 9218
TEST_TIME 97 9230
TEST_TIME 98 9230
TEST_TIME 99 9300
TEST_TIME 100 9295
TEST_TIME 101 9413
TEST_TIME 102 9440
TEST_TIME 103 9442
TEST_TIME 104 9454
TEST_TIME 105 9380
TEST_TIME 106 9376
TEST_TIME 107 9316
TEST_TIME 108 9292
TEST_TIME 109 9302
TEST_TIME 110 9261
TEST_TIME 111 9241
TEST_TIME 112 9194
TEST_TIME 113 9159
TEST_TIME 114 9174
TEST_TIME 115 9171
TEST_TIME 116 9151
TEST_TIME 117 9151
TEST_TIME 118 9131
TEST_TIME 119 9168
TEST_TIME 120 9095

Performance per minute, chart


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

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 1512937 1512937 0 0.00 0 15800 7 0 0
doc_list_biud 1512935 1512935 0 0.00 0 35853 31 0 0
sp_add_invoice_to_stock 5106 4946 160 3.13 48 210058 20708 0 160
sp_cancel_adding_invoice 5561 5561 0 0.00 5 135267 1021 0 0
sp_cancel_client_order 1755 1755 0 0.00 4 10835 354 0 0
sp_cancel_customer_reserve 549529 549529 0 0.00 1 34175 67 0 0
sp_cancel_pay_from_customer 711 711 0 0.00 3 6437 109 0 0
sp_cancel_pay_to_supplier 380 380 0 0.00 2 4980 94 0 0
sp_cancel_supplier_invoice 6112 6099 13 0.21 2 15086 1014 0 13
sp_cancel_supplier_order 410 410 0 0.00 6 1303169 113630 0 0
sp_cancel_write_off 10272 10272 0 0.00 1 11724 128 0 0
sp_client_order 20608 20608 0 0.00 4 23003 397 0 0
sp_customer_reserve 455972 455972 0 0.00 0 95748 206 0 0
sp_fill_shopping_cart 31411 31411 0 0.00 1 19103 47 0 0
sp_fill_shopping_cart_clo_res 455972 455972 0 0.00 0 18954 18 0 0
sp_get_clo_for_invoice 4945 4945 0 0.00 0 40958 361 0 0
sp_kill_cost_storno 16924 16924 0 0.00 0 13977 65 0 0
sp_kill_qstorno_ret_qs2qd 1115839 1115839 0 0.00 0 34170 37 0 0
sp_kill_qty_storno 588611 588611 0 0.00 0 35853 79 0 0
sp_lock_selected_doc 20740 20740 0 0.00 0 9368 6 0 0
sp_make_cost_storno 27930 27930 0 0.00 0 15729 54 0 0
sp_multiply_rows_for_pdistr 27930 27930 0 0.00 0 7328 30 0 0
sp_multiply_rows_for_qdistr 20606 20606 0 0.00 0 16764 207 0 0
sp_pay_from_customer 7057 7057 0 0.00 4 10512 92 0 0
sp_pay_to_supplier 5888 5888 0 0.00 4 20784 115 0 0
sp_payment_common 12945 12945 0 0.00 1 11758 17 0 0
sp_qd_handle_on_cancel_clo 1755 1755 0 0.00 0 10829 327 0 0
sp_qd_handle_on_invoice_upd_sts 10506 10506 0 0.00 0 35853 379 0 0
sp_qd_handle_on_reserve_upd_sts 10040 10040 0 0.00 0 22151 67 0 0
sp_reserve_write_off 10040 10040 0 0.00 7 22166 190 0 0
sp_supplier_invoice 5411 5411 0 0.00 6 119684 3846 0 0
sp_supplier_order 5407 5407 0 0.00 5 31140 1420 0 0
srv_aggregate_perf_data 102 102 0 0.00 2535 215641 17789 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1073 157336 47737 0 0
srv_find_qd_qs_mism 1043763 1043763 0 0.00 0 10809 7 0 0
srv_make_invnt_saldo 455 455 0 0.00 37 106454 3978 0 0
srv_make_money_saldo 767 767 0 0.00 1 15324 611 0 0
srv_recalc_idx_stat 5 5 0 0.00 171820 359267 260962 0 0
t$perf-abend:sp_add_invoice_to_stock 160 160 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 4946 4946 0 0.00 0 21018 255 0 0
t$perf-norm:sp_cancel_adding_invoice 449 449 0 0.00 1 14213 171 0 0
t$perf-norm:sp_cancel_client_order 1755 1755 0 0.00 0 1048 10 0 0
t$perf-norm:sp_cancel_customer_reserve 679 679 0 0.00 0 1953 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 711 711 0 0.00 0 12631 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 380 380 0 0.00 0 1005 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 4770 43 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 20796 1543 0 0
t$perf-norm:sp_cancel_write_off 957 957 0 0.00 0 9922 14 0 0
t$perf-norm:sp_client_order 20608 20608 0 0.00 0 15364 23 0 0
t$perf-norm:sp_customer_reserve 998 998 0 0.00 0 9286 20 0 0
t$perf-norm:sp_pay_from_customer 7057 7057 0 0.00 0 5693 9 0 0
t$perf-norm:sp_pay_to_supplier 5888 5888 0 0.00 0 12764 16 0 0
t$perf-norm:sp_reserve_write_off 10040 10040 0 0.00 0 7223 13 0 0
t$perf-norm:sp_supplier_invoice 5411 5411 0 0.00 0 6826 18 0 0
t$perf-norm:sp_supplier_order 5407 5407 0 0.00 0 4147 16 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 555 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 28 2 0 0
t$perf-norm:srv_make_invnt_saldo 455 455 0 0.00 0 5432 21 0 0
t$perf-norm:srv_make_money_saldo 767 767 0 0.00 0 995 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 11 5 0 0
v_add_invoice_to_stock 10210 9890 320 3.13 0 3131 16 0 320
v_all_customers 37544 37544 0 0.00 0 34 1 0 0
v_all_suppliers 30148 30148 0 0.00 0 32 1 0 0
v_all_wares 41208 41208 0 0.00 0 127 1 0 0
v_cancel_adding_invoice 898 898 0 0.00 0 1402 10 0 0
v_cancel_client_order 3510 3510 0 0.00 0 1878 5 0 0
v_cancel_customer_prepayment 1422 1422 0 0.00 0 2958 12 0 0
v_cancel_customer_reserve 1358 1358 0 0.00 0 1733 6 0 0
v_cancel_payment_to_supplier 760 760 0 0.00 0 664 6 0 0
v_cancel_supplier_invoice 854 828 26 3.04 0 1842 20 0 26
v_cancel_supplier_order 820 820 0 0.00 0 656 4 0 0
v_cancel_write_off 1914 1914 0 0.00 0 1350 13 0 0
v_max_id_clo_ord 5404 5404 0 0.00 0 7538 4 0 0
v_max_id_clo_res 998 998 0 0.00 0 7879 19 0 0
v_max_id_ord_sup 5400 5400 0 0.00 0 8631 8 0 0
v_max_non_paid_invoice 1618 1618 0 0.00 0 892 3 0 0
v_max_non_paid_realizn 3862 3862 0 0.00 0 1296 3 0 0
v_min_id_clo_ord 5404 5404 0 0.00 0 3134 6 0 0
v_min_id_clo_res 998 998 0 0.00 0 8362 22 0 0
v_min_id_ord_sup 5400 5400 0 0.00 0 3564 7 0 0
v_min_non_paid_invoice 5888 5888 0 0.00 0 11697 13 0 0
v_min_non_paid_realizn 7057 7057 0 0.00 0 8160 10 0 0
v_our_firm 10054 10054 0 0.00 0 14 1 0 0
v_reserve_write_off 20080 20080 0 0.00 0 1740 5 0 0
x_lock_depdocs_on_canc_invoice 11246 11246 0 0.00 0 15541 128 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 25904 392 0 0
x_make_qstorno_1000_1200 5407 5407 0 0.00 1 31126 1334 0 0
x_make_qstorno_1200_2000 5411 5411 0 0.00 2 119676 3667 0 0
x_make_qstorno_2100_3300 455972 455972 0 0.00 0 95747 185 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-01-2022 07:38:24 to 03-01-2022 07:38:28.

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:11 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:13 14637 8192000000 9716300528 10011045888 797735200 8.873 100 86 172 0 825815856 7428928 20651728
05:09:37 18813 8192000000 9759633792 10064945152 837536160 9.275 100 94 188 1 871842016 12185664 22120192
05:11:03 18979 8192000000 9796253984 10114387968 866989504 9.570 100 79 158 0 902288096 16615232 18683360
05:12:28 11851 8192000000 9859453984 10190381056 917096944 10.067 100 80 160 0 967885360 33494000 17294416
05:13:45 13070 8192000000 9902608768 10243481600 952029568 10.411 100 77 154 0 1015656832 47464976 16162288
05:15:02 6549 8192000000 9980598256 10335559680 990229696 10.784 100 81 158 1 1098948032 92052592 16665744
05:16:11 4514 8192000000 9984970304 10351349760 1018036848 11.053 100 75 149 2 1101814880 68637392 15140640
05:17:18 3653 8192000000 10006393600 10371661824 1037978016 11.245 100 81 162 0 1112837232 58527616 16331600
05:18:23 1304 8192000000 10037735120 10407383040 1063028816 11.485 100 89 178 0 1149028176 67868608 18130752
05:19:26 13304 8192000000 10024353312 10412630016 1071290976 11.564 100 59 117 1 1138606800 54817392 12498432
05:20:42 6440 8192000000 10002685856 10400325632 1079486976 11.643 100 37 74 0 1122722784 35566720 7669088
05:21:49 3520 8192000000 10021633392 10421035008 1090560944 11.748 100 51 100 0 1140716544 38804976 11350624
05:22:54 6929 8192000000 10023896832 10422935552 1094543856 11.786 100 36 71 1 1138264032 36485200 7234976
05:24:04 65785 8192000000 10024238144 10444464128 1097049504 11.810 100 36 70 2 1127945008 23062640 7832864
05:26:12 5888 8192000000 10026606160 10427260928 1102111056 11.858 100 44 88 0 1133760528 21808368 9841104
05:27:19 7338 8192000000 10011208416 10414288896 1101740112 11.854 100 38 76 1 1123196976 13044928 8411936
05:28:27 5730 8192000000 9996838592 10392330240 1064879408 11.503 98 35 70 1 1088455056 16116000 7459648
05:29:33 42666 8192000000 9973498912 10382008320 1031189632 11.180 96 22 44 2 1069564064 34125152 4249280
05:31:19 6865 8192000000 9946402560 10324725760 996692816 10.846 97 32 63 1 1026009520 23047264 6269440
05:32:30 5209 8192000000 9935975440 10317987840 1017351888 11.046 100 41 82 1 1038014192 11290048 9372256
05:33:36 9428 8192000000 9961331120 10352566272 1040234592 11.267 100 38 76 1 1071918272 23332320 8351360
05:34:48 36559 8192000000 9963409488 10368356352 1049698832 11.358 100 36 73 0 1076837584 19254928 7883824
05:36:26 4690 8192000000 9975760224 10364342272 1062093584 11.477 100 48 95 1 1085648816 13337488 10217744
05:37:35 8073 8192000000 10003213152 10388680704 1080871664 11.656 100 52 104 1 1106420320 14074864 11473792
05:38:49 37707 8192000000 10054146416 10449723392 1099526192 11.833 100 48 96 2 1176747696 67530576 9690928
05:40:32 89872 8192000000 10111403824 10523471872 1112177840 11.953 100 48 94 1 1208519536 86257808 10083888
05:43:34 17155 8192000000 10122464256 10524418048 1127555600 12.098 100 47 93 2 1222013536 84254720 10203216
05:44:54 13396 8192000000 10176543056 10605801472 1142518448 12.239 100 61 121 1 1279608928 123800480 13290000
05:46:16 1072 8192000000 10229067264 10627338240 1154009184 12.347 100 91 183 2 1331632928 158111712 19512032
05:47:23 5936 8192000000 10208190960 10617446400 1162192208 12.424 100 66 130 3 1317333056 141147680 13993168
05:48:32 7259 8192000000 10254662480 10662608896 1160883248 12.412 99 71 140 1 1351043712 175316544 14843920
05:49:46 2849 8192000000 10270386000 10674155520 1176598368 12.558 100 81 162 1 1366897632 173422480 16876784
05:50:55 3846 8192000000 10298035600 10695688192 1174160768 12.536 100 94 186 1 1390383504 195257824 20964912
05:52:03 4743 8192000000 10318664960 10721480704 1167999952 12.478 99 81 160 1 1414507504 228792480 17715072
05:53:12 3601 8192000000 10324480992 10732224512 1147064784 12.282 100 76 150 0 1423554384 259002016 17487584
05:54:21 5518 8192000000 10353805120 10753437696 1148902592 12.299 99 70 140 1 1453720880 287744640 17073648
05:55:36 4596 8192000000 10326581376 10735697920 1140407536 12.219 99 64 129 1 1413923232 258622448 14893248
05:56:56 7692 8192000000 10344287408 10775900160 1116680432 11.996 98 60 120 0 1444362256 313356384 14325440
05:58:11 9364 8192000000 10303886880 10718289920 1117389824 12.002 99 49 99 1 1408209152 278571952 12247376
05:59:29 1817 8192000000 10352120464 10751586304 1143217360 12.246 100 78 155 0 1447742784 286440224 18085200
06:00:38 6227 8192000000 10325607776 10722377728 1136304976 12.181 100 68 136 1 1413327680 261073728 15948976
06:01:45 13996 8192000000 10282567504 10695270400 1131438768 12.135 100 48 94 2 1385864432 242099200 12326464
06:03:06 20493 8192000000 10346710416 10796777472 1141681952 12.231 100 29 58 0 1459318384 310050224 7586208
06:04:43 19447 8192000000 10375336064 10858573824 1118281888 12.011 100 25 50 0 1495294560 369824208 7188464
06:06:06 22031 8192000000 10202654256 10715967488 1085100016 11.696 98 21 42 1 1343057360 251855952 6101392
06:07:38 78823 8192000000 10033718288 10505277440 1056643200 11.424 95 5 10 2 1121840000 64267952 928848
06:09:59 77715 8192000000 10084268016 10550030336 1124469152 12.069 100 17 34 1 1176306048 48683488 3153408
06:12:19 157335 8192000000 10113860160 10546819072 1135824112 12.176 100 39 78 0 1209426752 65012144 8590496
06:15:59 39595 8192000000 10117346416 10543624192 1121923024 12.045 98 22 43 0 1203839984 77678976 4237984
06:17:40 20538 8192000000 10138540672 10585673728 1133598336 12.155 100 26 52 0 1227311552 88173376 5539840
06:19:02 130306 8192000000 10130052656 10574446592 1130376368 12.125 99 15 30 0 1209093120 74931376 3785376
06:22:13 82632 8192000000 10149314064 10615283712 1150424064 12.313 100 18 36 0 1254720672 100174256 4122352
06:24:44 68042 8192000000 10128096960 10615304192 1148220896 12.293 100 17 34 0 1269063312 116948672 3893744
06:26:55 43220 8192000000 10092267776 10528911360 1106378576 11.898 96 50 99 1 1144306752 27256256 10671920
06:28:40 68014 8192000000 10099723328 10589990912 1115581936 11.985 98 15 30 0 1192480064 73886688 3011440
06:31:00 49734 8192000000 10089662240 10560614400 1139564160 12.211 100 15 30 1 1185896992 43124336 3208496
06:32:52 38651 8192000000 10091495456 10532507648 1129827648 12.120 98 21 41 2 1178129136 44304528 3996960
06:34:36 51918 8192000000 10092547664 10510204928 1123123056 12.056 98 41 81 0 1167964624 36273184 8568384
06:36:30 115135 8192000000 10062772144 10513190912 1130774128 12.129 99 33 66 1 1193564032 55894272 6895632
06:40:04 49755 8192000000 10130762496 10568441856 1139521984 12.211 99 17 34 0 1205963664 62967776 3473904
06:43:16 55033 8192000000 10071278896 10525425664 1105301648 11.888 97 9 18 0 1144535376 37013520 2220208
06:45:23 37251 8192000000 10060173888 10497675264 1112041216 11.952 99 17 34 0 1138251920 22618768 3591936
06:47:01 40819 8192000000 10156710976 10592579584 1119135920 12.019 98 39 78 0 1217072160 89184656 8751584
06:48:48 78232 8192000000 10044891984 10560385024 1070315168 11.555 97 15 30 0 1186665488 112713504 3636816
06:51:11 31107 8192000000 10135228032 10554146816 1134003616 12.159 100 46 92 1 1206008816 62458160 9547040
06:52:43 97283 8192000000 10086613232 10544574464 1058556528 11.443 94 16 32 0 1132558928 70749168 3253232
06:55:20 81952 8192000000 10052398336 10517458944 1067468416 11.528 96 14 28 0 1113301424 43305888 2527120
06:58:20 98012 8192000000 10018680416 10482855936 1065423104 11.508 96 21 42 0 1102926976 33469328 4034544
07:01:02 61168 8192000000 10017734208 10493784064 1099937488 11.837 99 5 12 0 1141315312 40505424 872400
07:03:08 43448 8192000000 10037376992 10489466880 1097581136 11.815 99 20 41 1 1129266480 27434208 4251136
07:04:52 95359 8192000000 10097093456 10550292480 1103518592 11.871 98 20 40 0 1190686496 81855024 5312880
07:08:51 41969 8192000000 10113928400 10569097216 1135578624 12.174 100 25 47 0 1223851664 82599840 5673200
07:10:33 84608 8192000000 10075991600 10548654080 1108490400 11.918 98 14 28 0 1202117696 90779344 2847952
07:12:58 35824 8192000000 10080298848 10507419648 1103691072 11.873 97 36 72 1 1145681680 34718560 7272048
07:14:37 27151 8192000000 10100244992 10529296384 1110408704 11.936 98 35 70 1 1164376416 46793280 7174432
07:16:09 43558 8192000000 10119418032 10637783040 1138420144 12.201 100 28 56 1 1218386080 74459248 5506688
07:17:53 51581 8192000000 10179417472 10721406976 1152458208 12.333 100 18 36 0 1262551408 106210336 3882864
07:20:03 85657 8192000000 10094493824 10597662720 1140649088 12.222 99 20 40 1 1196729472 51874400 4205984
07:22:31 93145 8192000000 10070978672 10556739584 1152511680 12.333 100 33 66 0 1203379936 43469152 7399104
07:25:15 73865 8192000000 10082131856 10567667712 1144817152 12.261 100 10 20 0 1216552656 68846560 2888944
07:27:30 34616 8192000000 10066854000 10512375808 1109527872 11.928 97 7 14 0 1166140208 55370512 1241824
07:29:05 65769 8192000000 10071561984 10518040576 1103614688 11.872 97 12 24 0 1152040944 45645872 2780384
07:31:11 84827 8192000000 10057504592 10526687232 1060713536 11.463 94 16 32 0 1088181680 24232880 3235264
07:33:36 62212 8192000000 10090957568 10537930752 1102391888 11.860 98 27 54 0 1170589456 62407008 5790560
07:35:47 77065 8192000000 10115234208 10584653824 1108582800 11.919 98 25 50 0 1189395888 75731888 5081200

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-01-2022 07:38:30 to 03-01-2022 07:38:45.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 160 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 03-01-2022 07:38:46 to 03-01-2022 07:38:48.

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          230369
MON$OLDEST_ACTIVE               232266
MON$OLDEST_SNAPSHOT             232266
MON$NEXT_TRANSACTION            232266
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                       2049248
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             792
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2700 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2700 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2700 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2700 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 ---
:26: 
:27: mscs-test-161.budzdorov.ru    Mon Jan  3 07:37:23 2022
:28:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:29: 
--- end of diff output ---

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

03.01.22 07:38:49. 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-03 07:38:51 No severe PSQL-related problems occured
03.01.22 07:38:52. End of report.