Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.06.2022 10: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 15958428 kB
MemAvailable 31273996 kB
Buffers 0 kB
Cached 15708400 kB
SwapCached 2448 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.2
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-06-08 10:37:17
DTS_END                         2022-06-08 10:37:17.4450
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55484
TRN_ID                          209735
ATT_ID                          602
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: 8528.17 24471 1023380
customer order: creation 163.48 520 19618
customer order: refuse 13.99 467 1679
order to supplier: creation 42.48 1495 5097
order to supplier: removal 3.32 187934 398
invoice (draft): creation 43.56 3643 5227
invoice (draft): removal 48.33 1102 5800
invoice accept: apply 39.28 30050 4713
invoice accept: cancel 43.49 1377 5219
customer reserve: creation 3591.94 292 431033
customer reserve: removal 4253.13 113 510376
realization accept: apply 78.61 326 9433
realization accept: cancel 83.18 185 9982
payment from customer: creation 56.56 193 6787
payment from customer: removal 5.14 136 617
payment to supplier: creation 46.25 223 5550
payment to supplier: removal 3.18 139 381
service: total inventory turnovers 3.62 4888 434
service: total monetary turnovers 7.64 469 917
service: aggregate perf. data 0.96 15674 115
service: refresh index statistics 0.03 240195 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-06-2022 10:38:13 to 08-06-2022 10:38:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8112
TEST_TIME 2 5446
TEST_TIME 3 5584
TEST_TIME 4 5935
TEST_TIME 5 5568
TEST_TIME 6 5386
TEST_TIME 7 5490
TEST_TIME 8 5550
TEST_TIME 9 5812
TEST_TIME 10 6044
TEST_TIME 11 6116
TEST_TIME 12 6220
TEST_TIME 13 6243
TEST_TIME 14 6330
TEST_TIME 15 6307
TEST_TIME 16 6468
TEST_TIME 17 6702
TEST_TIME 18 6994
TEST_TIME 19 7038
TEST_TIME 20 7027
TEST_TIME 21 7103
TEST_TIME 22 7077
TEST_TIME 23 6990
TEST_TIME 24 7030
TEST_TIME 25 7340
TEST_TIME 26 7410
TEST_TIME 27 7462
TEST_TIME 28 7511
TEST_TIME 29 7988
TEST_TIME 30 8351
TEST_TIME 31 8621
TEST_TIME 32 8608
TEST_TIME 33 8841
TEST_TIME 34 9043
TEST_TIME 35 9451
TEST_TIME 36 9953
TEST_TIME 37 9960
TEST_TIME 38 10051
TEST_TIME 39 10313
TEST_TIME 40 10275
TEST_TIME 41 10168
TEST_TIME 42 9980
TEST_TIME 43 9888
TEST_TIME 44 9769
TEST_TIME 45 9775
TEST_TIME 46 9664
TEST_TIME 47 9666
TEST_TIME 48 9609
TEST_TIME 49 9470
TEST_TIME 50 9592
TEST_TIME 51 9531
TEST_TIME 52 9447
TEST_TIME 53 9306
TEST_TIME 54 9195
TEST_TIME 55 9114
TEST_TIME 56 8971
TEST_TIME 57 8900
TEST_TIME 58 8874
TEST_TIME 59 8802
TEST_TIME 60 8907
TEST_TIME 61 8882
TEST_TIME 62 8777
TEST_TIME 63 8702
TEST_TIME 64 8652
TEST_TIME 65 8662
TEST_TIME 66 8756
TEST_TIME 67 8825
TEST_TIME 68 8774
TEST_TIME 69 8776
TEST_TIME 70 8832
TEST_TIME 71 8817
TEST_TIME 72 8776
TEST_TIME 73 8842
TEST_TIME 74 8901
TEST_TIME 75 8859
TEST_TIME 76 8837
TEST_TIME 77 8815
TEST_TIME 78 8760
TEST_TIME 79 8671
TEST_TIME 80 8838
TEST_TIME 81 8751
TEST_TIME 82 8732
TEST_TIME 83 8684
TEST_TIME 84 8633
TEST_TIME 85 8581
TEST_TIME 86 8522
TEST_TIME 87 8522
TEST_TIME 88 8501
TEST_TIME 89 8505
TEST_TIME 90 8539
TEST_TIME 91 8546
TEST_TIME 92 8576
TEST_TIME 93 8564
TEST_TIME 94 8606
TEST_TIME 95 8652
TEST_TIME 96 8645
TEST_TIME 97 8646
TEST_TIME 98 8679
TEST_TIME 99 8737
TEST_TIME 100 8716
TEST_TIME 101 8718
TEST_TIME 102 8750
TEST_TIME 103 8706
TEST_TIME 104 8647
TEST_TIME 105 8598
TEST_TIME 106 8593
TEST_TIME 107 8562
TEST_TIME 108 8503
TEST_TIME 109 8509
TEST_TIME 110 8481
TEST_TIME 111 8466
TEST_TIME 112 8434
TEST_TIME 113 8475
TEST_TIME 114 8571
TEST_TIME 115 8572
TEST_TIME 116 8573
TEST_TIME 117 8617
TEST_TIME 118 8585
TEST_TIME 119 8610
TEST_TIME 120 8546

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-06-2022 10:38:16 to 08-06-2022 10:38:19.

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 1419291 1419291 0 0.00 0 17852 16 0 0
doc_list_biud 1419286 1419286 0 0.00 0 28818 47 0 0
sp_add_invoice_to_stock 4843 4713 130 2.68 64 271333 30051 0 130
sp_cancel_adding_invoice 5219 5219 0 0.00 7 81933 1378 0 0
sp_cancel_client_order 1679 1679 0 0.00 5 10873 468 0 0
sp_cancel_customer_reserve 510376 510376 0 0.00 1 28821 114 0 0
sp_cancel_pay_from_customer 617 617 0 0.00 4 2954 136 0 0
sp_cancel_pay_to_supplier 381 381 0 0.00 4 2865 140 0 0
sp_cancel_supplier_invoice 5812 5800 12 0.20 2 13357 1102 0 12
sp_cancel_supplier_order 398 398 0 0.00 6 1579281 187934 0 0
sp_cancel_write_off 9982 9982 0 0.00 2 16013 186 0 0
sp_client_order 19618 19618 0 0.00 6 22054 521 0 0
sp_customer_reserve 431033 431033 0 0.00 1 81561 292 0 0
sp_fill_shopping_cart 29928 29928 0 0.00 2 38615 99 0 0
sp_fill_shopping_cart_clo_res 430995 430995 0 0.00 0 19788 21 0 0
sp_get_clo_for_invoice 4667 4667 0 0.00 0 24333 313 0 0
sp_kill_cost_storno 16199 16199 0 0.00 0 12161 85 0 0
sp_kill_qstorno_ret_qs2qd 1036932 1036932 0 0.00 0 28810 49 0 0
sp_kill_qty_storno 547550 547550 0 0.00 0 28818 113 0 0
sp_lock_selected_doc 19504 19504 0 0.00 0 7680 8 0 0
sp_make_cost_storno 26434 26434 0 0.00 0 17846 90 0 0
sp_multiply_rows_for_pdistr 26434 26434 0 0.00 0 11360 42 0 0
sp_multiply_rows_for_qdistr 19618 19618 0 0.00 0 15338 180 0 0
sp_pay_from_customer 6787 6787 0 0.00 5 15840 193 0 0
sp_pay_to_supplier 5550 5550 0 0.00 6 17690 223 0 0
sp_payment_common 12337 12337 0 0.00 2 6321 50 0 0
sp_qd_handle_on_cancel_clo 1679 1679 0 0.00 0 10862 383 0 0
sp_qd_handle_on_invoice_upd_sts 9882 9882 0 0.00 0 27346 602 0 0
sp_qd_handle_on_reserve_upd_sts 9433 9433 0 0.00 0 21726 76 0 0
sp_reserve_write_off 9433 9433 0 0.00 9 21746 327 0 0
sp_supplier_invoice 5227 5227 0 0.00 12 97830 3643 0 0
sp_supplier_order 5097 5097 0 0.00 8 49984 1495 0 0
srv_aggregate_perf_data 115 115 0 0.00 2663 132214 15675 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 798 170372 32235 0 0
srv_find_qd_qs_mism 976774 976774 0 0.00 0 16835 13 0 0
srv_make_invnt_saldo 434 434 0 0.00 70 75565 4889 0 0
srv_make_money_saldo 917 917 0 0.00 2 27659 469 0 0
srv_recalc_idx_stat 4 4 0 0.00 65599 421409 240196 0 0
t$perf-abend:sp_add_invoice_to_stock 130 130 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4713 4713 0 0.00 1 21823 1074 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 2 32219 585 0 0
t$perf-norm:sp_cancel_client_order 1679 1679 0 0.00 0 1342 30 0 0
t$perf-norm:sp_cancel_customer_reserve 659 659 0 0.00 0 19401 60 0 0
t$perf-norm:sp_cancel_pay_from_customer 617 617 0 0.00 0 1522 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 381 381 0 0.00 0 8738 38 0 0
t$perf-norm:sp_cancel_supplier_invoice 359 359 0 0.00 0 1671 57 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 92800 7966 0 0
t$perf-norm:sp_cancel_write_off 918 918 0 0.00 0 3003 27 0 0
t$perf-norm:sp_client_order 19618 19618 0 0.00 0 7375 41 0 0
t$perf-norm:sp_customer_reserve 952 952 0 0.00 0 4863 20 0 0
t$perf-norm:sp_pay_from_customer 6787 6787 0 0.00 0 12930 25 0 0
t$perf-norm:sp_pay_to_supplier 5550 5550 0 0.00 0 13590 25 0 0
t$perf-norm:sp_reserve_write_off 9433 9433 0 0.00 0 8478 26 0 0
t$perf-norm:sp_supplier_invoice 5227 5227 0 0.00 0 5541 37 0 0
t$perf-norm:sp_supplier_order 5097 5097 0 0.00 0 2842 33 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 664 32 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 44 5 0 0
t$perf-norm:srv_make_invnt_saldo 434 434 0 0.00 0 851 18 0 0
t$perf-norm:srv_make_money_saldo 917 917 0 0.00 0 578 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 33 194 88 0 0
v_add_invoice_to_stock 9584 9324 260 2.71 0 8715 32 0 260
v_all_customers 36160 36160 0 0.00 0 57 4 0 0
v_all_suppliers 28818 28818 0 0.00 0 84 5 0 0
v_all_wares 39230 39230 0 0.00 0 81 4 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 2440 16 0 0
v_cancel_client_order 3358 3358 0 0.00 0 5306 14 0 0
v_cancel_customer_prepayment 1234 1234 0 0.00 0 2905 14 0 0
v_cancel_customer_reserve 1318 1318 0 0.00 0 10094 19 0 0
v_cancel_payment_to_supplier 762 762 0 0.00 0 899 14 0 0
v_cancel_supplier_invoice 742 718 24 3.23 0 1551 26 0 24
v_cancel_supplier_order 796 796 0 0.00 0 2953 14 0 0
v_cancel_write_off 1836 1836 0 0.00 0 5240 27 0 0
v_max_id_clo_ord 5094 5094 0 0.00 0 3030 6 0 0
v_max_id_clo_res 952 952 0 0.00 0 1481 12 0 0
v_max_id_ord_sup 5219 5219 0 0.00 0 5487 9 0 0
v_max_non_paid_invoice 1454 1454 0 0.00 0 760 9 0 0
v_max_non_paid_realizn 3471 3471 0 0.00 0 963 7 0 0
v_min_id_clo_ord 5094 5094 0 0.00 0 5257 11 0 0
v_min_id_clo_res 952 952 0 0.00 0 8404 24 0 0
v_min_id_ord_sup 5219 5219 0 0.00 0 6426 18 0 0
v_min_non_paid_invoice 5550 5550 0 0.00 0 6317 21 0 0
v_min_non_paid_realizn 6787 6787 0 0.00 0 6016 19 0 0
v_our_firm 9702 9702 0 0.00 0 62 5 0 0
v_reserve_write_off 18866 18866 0 0.00 0 11586 14 0 0
x_lock_depdocs_on_canc_invoice 10660 10660 0 0.00 0 19522 152 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 3400 382 0 0
x_make_qstorno_1000_1200 5097 5097 0 0.00 3 49974 1301 0 0
x_make_qstorno_1200_2000 5227 5227 0 0.00 5 90154 3357 0 0
x_make_qstorno_2100_3300 431033 431033 0 0.00 0 81543 257 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 08-06-2022 10:38:20 to 08-06-2022 10:38:25.

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:21 31 8192000000 8810250800 8825987072 11478368 0.139 5 2 3 0 11666880 100704 87808
08:08:22 30623 8192000000 9733548720 10033381376 809080432 8.988 100 91 182 0 839243920 8126128 22037360
08:09:56 15485 8192000000 9764211408 10072502272 836806032 9.268 100 90 180 0 871195200 12735424 21653744
08:11:26 15195 8192000000 9810074320 10130853888 872788000 9.628 100 83 166 0 915943168 24517792 18637376
08:12:45 9580 8192000000 9867099712 10197811200 908831184 9.986 100 85 167 1 980536240 53128272 18576784
08:13:57 5446 8192000000 9906824160 10246275072 940759568 10.300 100 83 166 0 1020717376 61967536 17990272
08:15:05 4121 8192000000 9953450704 10307584000 989482576 10.776 100 90 174 1 1072581632 64751216 18347840
08:16:12 1884 8192000000 9997663568 10355367936 1008497616 10.961 100 95 186 1 1117498624 89425328 19575680
08:17:16 3100 8192000000 9994714880 10360668160 1029580400 11.164 100 89 167 0 1117996128 72232384 16183344
08:18:22 1367 8192000000 10034292160 10405314560 1051334688 11.373 100 96 186 1 1148476352 78967056 18174608
08:19:24 1187 8192000000 10047836032 10423508992 1067292576 11.526 100 91 175 2 1164327072 78931216 18103280
08:20:27 964 8192000000 10030141184 10411216896 1071218304 11.564 100 93 177 1 1147474592 58154384 18101904
08:21:31 1050 8192000000 10038177104 10421796864 1075111024 11.601 100 86 160 1 1161767984 69367152 17289808
08:22:34 1194 8192000000 10067376880 10451927040 1081163520 11.659 100 95 178 0 1185420544 86659088 17597936
08:23:36 1005 8192000000 10075160848 10461810688 1080838496 11.655 100 91 183 0 1196489472 96332752 19318224
08:24:40 1328 8192000000 10063964368 10447257600 1086990880 11.714 100 97 190 0 1184289952 77428720 19870352
08:25:42 1096 8192000000 10068240320 10454794240 1092766336 11.769 100 95 182 1 1191882480 79749760 19366384
08:26:44 1092 8192000000 10067108256 10457059328 1094974368 11.790 100 93 178 1 1187318176 73856768 18487040
08:27:46 1194 8192000000 10077942896 10470150144 1097980224 11.818 100 96 181 1 1204502640 87975152 18547264
08:28:49 1186 8192000000 10061783296 10455650304 1097322880 11.812 100 94 182 2 1185619232 70377840 17918512
08:29:52 1101 8192000000 10025691984 10406830080 1061914208 11.475 99 91 171 2 1147493264 67328272 18250784
08:30:55 1876 8192000000 10039176384 10421907456 1082246768 11.669 100 90 167 2 1154102592 56691648 15164176
08:31:57 1075 8192000000 10027827312 10410258432 1072328768 11.574 100 94 181 1 1150487264 59247520 18910976
08:32:59 1248 8192000000 9994600960 10374782976 1035449152 11.221 99 80 144 0 1114622304 63802976 15370176
08:34:02 1139 8192000000 9983386064 10355683328 1039872800 11.263 100 91 172 0 1106052096 48449280 17730016
08:35:08 1675 8192000000 10021314576 10402238464 1042110976 11.285 100 87 169 3 1144725072 85321552 17292544
08:36:11 873 8192000000 10001798544 10378313728 1050904544 11.369 100 93 180 2 1124746464 53771232 20070688
08:37:13 1184 8192000000 9986348432 10360016896 1043616672 11.299 100 94 179 2 1106737552 44651184 18469696
08:38:22 4887 8192000000 10000565648 10379329536 1059768016 11.454 100 67 125 1 1120819984 47776544 13275424
08:39:30 29568 8192000000 10036507040 10425610240 1081602064 11.663 100 71 137 1 1157485856 61611120 14272672
08:41:08 1334 8192000000 10073060608 10460418048 1095435440 11.794 100 82 156 0 1188521680 76496400 16589840
08:42:12 1496 8192000000 10065775968 10454220800 1093005168 11.771 99 88 163 1 1186316496 74749584 18561744
08:43:23 2879 8192000000 10118240944 10512629760 1113201424 11.963 100 67 133 1 1242057280 114738400 14117456
08:44:31 11004 8192000000 10136145584 10543067136 1118989568 12.017 100 71 137 1 1255420304 122104576 14326160
08:45:48 5458 8192000000 10162443152 10569482240 1125937088 12.083 100 75 142 1 1287256528 145441200 15878240
08:47:00 1382 8192000000 10181833840 10577440768 1138806272 12.204 100 84 154 0 1297772800 143544816 15421712
08:48:06 1225 8192000000 10217301376 10620575744 1148234864 12.293 100 92 184 1 1327558240 159453632 19869744
08:49:13 1294 8192000000 10233161760 10641252352 1159862816 12.402 100 87 164 1 1343842384 166405040 17574528
08:50:19 1349 8192000000 10288163360 10688524288 1177942304 12.571 100 95 188 1 1401908816 202328384 21638128
08:51:24 2297 8192000000 10303639904 10705055744 1185459920 12.641 100 87 167 3 1414482560 210697792 18324848
08:52:29 949 8192000000 10364664096 10768605184 1191887872 12.701 99 96 183 1 1476599248 262615264 22096112
08:53:33 4293 8192000000 10402136064 10820943872 1203013520 12.804 100 90 180 0 1513007440 288171712 21822208
08:54:41 1011 8192000000 10401453600 10827128832 1210226752 12.871 100 93 184 0 1510288464 278626096 21435616
08:55:45 855 8192000000 10428159632 10839261184 1220369664 12.965 100 96 184 1 1536572336 294661904 21540768
08:56:48 2223 8192000000 10453333488 10867998720 1210267392 12.872 100 91 172 2 1561975520 330876912 20831216
08:57:54 935 8192000000 10502752272 10917249024 1219039680 12.953 100 97 187 0 1610200384 369460528 21700176
08:58:58 908 8192000000 10519878352 10928119808 1208204656 12.852 100 98 190 0 1627693424 397841952 21646816
09:00:05 790 8192000000 10575045136 10985070592 1209244640 12.862 100 98 193 1 1678791008 446525568 23020800
09:01:10 869 8192000000 10625448368 11050041344 1210058288 12.870 100 97 194 2 1730103456 496312400 23732768
09:02:14 2987 8192000000 10627126064 11089092608 1211505040 12.883 100 99 197 0 1728815296 493336864 23973392
09:03:19 1427 8192000000 10652937312 11080781824 1198753472 12.765 100 96 188 0 1754622736 533344576 22524688
09:04:25 789 8192000000 10665996512 11101794304 1189834640 12.682 100 99 193 0 1768795072 555617776 23342656
09:05:28 1053 8192000000 10686111040 11099557888 1191043520 12.693 100 96 189 1 1791616832 575844048 24729264
09:06:31 1807 8192000000 10606414288 11121364992 1178929760 12.580 100 70 137 0 1712447408 516234336 17283312
09:07:36 7375 8192000000 10568851600 11043119104 1187584208 12.661 100 48 90 0 1661797088 463254624 10958256
09:08:51 8649 8192000000 10502297536 10965512192 1176269776 12.555 100 37 75 1 1604961712 419206624 9485312
09:10:07 21581 8192000000 10490252704 10973315072 1153511936 12.342 99 32 64 1 1602625216 440181008 8932272
09:11:35 5186 8192000000 10430584384 10912436224 1160041456 12.404 100 43 86 0 1535255952 364210976 11003520
09:12:44 7942 8192000000 10301879408 10834382848 1142913024 12.243 99 36 71 0 1393149264 241558080 8678160
09:13:53 27381 8192000000 10247965312 10700570624 1154327360 12.350 100 22 44 0 1336729664 177198912 5203392
09:15:26 33549 8192000000 10206166656 10699288576 1168791648 12.486 100 19 38 2 1353240064 180030576 4417840
09:17:48 25275 8192000000 10095872400 10560024576 1145286752 12.265 100 35 64 1 1196393856 44433168 6673936
09:19:14 26573 8192000000 10127907808 10562514944 1158466128 12.389 100 47 89 3 1215731568 48147376 9118064
09:21:56 71616 8192000000 10128442768 10611056640 1140710608 12.222 99 10 20 0 1215506160 72881168 1914384
09:24:09 50402 8192000000 10110972352 10567057408 1141961648 12.234 99 13 26 1 1212959616 68145280 2852688
09:26:01 64293 8192000000 10154790208 10610180096 1143527600 12.249 99 24 48 0 1238191184 89420496 5243088
09:28:06 170372 8192000000 10070002160 10534670336 1115138832 11.981 97 17 34 0 1137931840 19385360 3407648
09:32:10 96912 8192000000 10093404464 10535084032 1154143968 12.348 100 37 72 2 1194285936 32934368 7207600
09:34:50 6775 8192000000 10121391664 10561667072 1148300496 12.294 100 54 108 0 1229350928 70077456 10972976
09:35:59 6672 8192000000 10109117520 10545598464 1127668720 12.099 98 62 109 2 1207143888 67848416 11626752
09:37:08 63642 8192000000 10077779216 10571976704 1144171904 12.255 100 23 47 0 1232437312 83174048 5091360
09:39:13 60909 8192000000 10084112496 10551685120 1122809792 12.054 98 9 18 0 1176014304 51444752 1759760
09:41:16 67235 8192000000 10107658880 10620788736 1125397120 12.078 98 10 20 2 1216113088 88557376 2158592
09:43:31 39976 8192000000 10089086128 10535616512 1114130512 11.972 98 18 36 0 1168901696 51176000 3595184
09:45:11 9284 8192000000 10050814320 10480381952 1111968496 11.951 99 27 53 4 1153733472 36799504 4965472
09:46:24 7103 8192000000 10109452784 10540974080 1126751440 12.091 100 54 108 0 1198520016 60904576 10864000
09:47:38 31412 8192000000 10085126224 10522906624 1120760416 12.034 99 33 66 0 1175724928 48284496 6680016
09:49:15 13975 8192000000 10120878240 10550829056 1137909616 12.196 100 44 86 3 1212293440 65651184 8732640
09:50:34 55815 8192000000 10105166656 10581385216 1137022528 12.188 100 22 43 1 1223686928 81974400 4690000
09:52:30 14703 8192000000 10088508080 10525425664 1115773136 11.987 98 41 80 2 1174194864 50576992 7844736
09:53:46 37654 8192000000 10116619984 10569355264 1138881872 12.205 100 46 90 0 1222056096 74024160 9150064
09:56:02 110597 8192000000 10055324880 10582835200 1080858080 11.656 95 14 28 0 1182702672 98326592 3518000
10:00:02 70386 8192000000 10067436880 10517770240 1116178896 11.991 99 12 23 0 1154758192 35890256 2689040
10:02:13 115530 8192000000 10105135312 10562441216 1096607296 11.805 97 20 40 0 1172346512 71176016 4563200
10:05:29 84311 8192000000 10089253472 10564460544 1121935568 12.045 99 20 40 0 1200727648 74069776 4722304
10:07:53 25648 8192000000 10063231408 10513096704 1115728032 11.987 99 23 46 0 1170092928 49770128 4594768
10:09:21 45964 8192000000 10102417616 10553012224 1131550560 12.136 99 32 64 1 1198370768 60583136 6237072
10:11:07 48361 8192000000 10106851776 10574991360 1117983776 12.008 98 42 84 2 1216347824 90113968 8250080
10:12:58 16808 8192000000 10075403872 10521468928 1128199376 12.104 100 45 85 2 1186631824 49455984 8976464
10:14:16 47356 8192000000 10146817664 10577715200 1136598240 12.184 99 34 68 1 1224944640 81785040 6561360
10:16:05 74743 8192000000 10085379200 10523156480 1121870976 12.045 98 28 56 2 1196582192 69343840 5367376
10:18:29 61731 8192000000 10118912848 10593394688 1129373456 12.115 99 39 78 1 1185577936 47975280 8229200
10:20:34 71666 8192000000 10073172656 10503897088 1120295472 12.030 98 29 55 1 1144929312 19209520 5424320
10:22:47 29513 8192000000 10131716128 10572759040 1117349664 12.002 98 44 88 2 1197500176 71161408 8989104
10:24:38 56399 8192000000 10129604880 10573824000 1125235168 12.076 98 10 20 2 1225809008 98277952 2295888
10:26:37 48563 8192000000 10151738960 10636152832 1149307344 12.303 100 21 42 1 1276783568 122304912 5171312
10:28:37 111981 8192000000 10166746176 10647138304 1094348240 11.784 95 11 22 0 1209950400 113137392 2464768
10:31:36 101213 8192000000 10098936432 10594254848 1119145072 12.019 98 19 38 1 1235353392 111852496 4355824
10:34:18 58269 8192000000 10071551632 10544017408 1101032864 11.847 97 19 38 2 1176071744 70939376 4099504
10:36:30 24258 8192000000 10079544608 10511753216 1116792048 11.997 100 47 90 2 1169543232 44092544 8658640

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 08-06-2022 10:38:26 to 08-06-2022 10:38:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 130 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-06-2022 10:38:43 to 08-06-2022 10:38:45.

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          207184
MON$OLDEST_ACTIVE               210783
MON$OLDEST_SNAPSHOT             210783
MON$NEXT_TRANSACTION            210783
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                       2078560
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             758
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2774 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2774 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2774 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2774 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Wed Jun  8 10:37:19 2022
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Wed Jun  8 10:37:20 2022
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Wed Jun  8 10:37:24 2022
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Wed Jun  8 10:37:30 2022
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
--- end of diff output ---

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

08.06.22 10:38:46. 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-06-08 10:38:49 No severe PSQL-related problems occured
08.06.22 10:38:50. End of report.