Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.02.2023 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 15261404 kB
MemAvailable 30387396 kB
Buffers 0 kB
Cached 16424988 kB
SwapCached 3620 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.3
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 2023-02-03 10:37:25
DTS_END                         2023-02-03 10:37:25.8010
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/50722
TRN_ID                          212258
ATT_ID                          597
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: 8928.63 27401 1071435
customer order: creation 161.79 507 19415
customer order: refuse 14.03 430 1683
order to supplier: creation 43.14 1392 5177
order to supplier: removal 3.35 187554 402
invoice (draft): creation 42.51 3271 5101
invoice (draft): removal 51.13 1086 6135
invoice accept: apply 40.07 26045 4808
invoice accept: cancel 46.18 1204 5541
customer reserve: creation 3721.63 249 446596
customer reserve: removal 4521.99 110 542639
realization accept: apply 77.93 252 9352
realization accept: cancel 84.42 181 10130
payment from customer: creation 54.58 166 6549
payment from customer: removal 5.33 102 640
payment to supplier: creation 45.34 175 5441
payment to supplier: removal 3.39 123 407
service: total inventory turnovers 4.05 3772 486
service: total monetary turnovers 6.98 374 837
service: aggregate perf. data 0.77 19901 92
service: refresh index statistics 0.03 301128 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-02-2023 10:38:06 to 03-02-2023 10:38:08.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4147
TEST_TIME 2 4130
TEST_TIME 3 4864
TEST_TIME 4 4815
TEST_TIME 5 5166
TEST_TIME 6 5318
TEST_TIME 7 5120
TEST_TIME 8 5026
TEST_TIME 9 5145
TEST_TIME 10 5414
TEST_TIME 11 5572
TEST_TIME 12 5695
TEST_TIME 13 5717
TEST_TIME 14 5640
TEST_TIME 15 5670
TEST_TIME 16 5754
TEST_TIME 17 5803
TEST_TIME 18 6470
TEST_TIME 19 6591
TEST_TIME 20 6628
TEST_TIME 21 6910
TEST_TIME 22 6933
TEST_TIME 23 7318
TEST_TIME 24 7914
TEST_TIME 25 7917
TEST_TIME 26 8067
TEST_TIME 27 7975
TEST_TIME 28 8429
TEST_TIME 29 9115
TEST_TIME 30 9357
TEST_TIME 31 9749
TEST_TIME 32 10006
TEST_TIME 33 10523
TEST_TIME 34 10614
TEST_TIME 35 10503
TEST_TIME 36 10267
TEST_TIME 37 10092
TEST_TIME 38 9979
TEST_TIME 39 9893
TEST_TIME 40 9934
TEST_TIME 41 9844
TEST_TIME 42 9773
TEST_TIME 43 9636
TEST_TIME 44 9620
TEST_TIME 45 9569
TEST_TIME 46 9438
TEST_TIME 47 9336
TEST_TIME 48 9265
TEST_TIME 49 9160
TEST_TIME 50 9153
TEST_TIME 51 9118
TEST_TIME 52 9006
TEST_TIME 53 8999
TEST_TIME 54 9062
TEST_TIME 55 8988
TEST_TIME 56 9024
TEST_TIME 57 9040
TEST_TIME 58 9106
TEST_TIME 59 9151
TEST_TIME 60 9065
TEST_TIME 61 9111
TEST_TIME 62 9054
TEST_TIME 63 9240
TEST_TIME 64 9125
TEST_TIME 65 9029
TEST_TIME 66 8935
TEST_TIME 67 8959
TEST_TIME 68 8882
TEST_TIME 69 8798
TEST_TIME 70 8789
TEST_TIME 71 8755
TEST_TIME 72 8809
TEST_TIME 73 8789
TEST_TIME 74 8743
TEST_TIME 75 8766
TEST_TIME 76 8792
TEST_TIME 77 8764
TEST_TIME 78 8865
TEST_TIME 79 8876
TEST_TIME 80 8850
TEST_TIME 81 8875
TEST_TIME 82 8859
TEST_TIME 83 8984
TEST_TIME 84 9071
TEST_TIME 85 9006
TEST_TIME 86 8913
TEST_TIME 87 8877
TEST_TIME 88 8858
TEST_TIME 89 8807
TEST_TIME 90 8819
TEST_TIME 91 8772
TEST_TIME 92 8755
TEST_TIME 93 8733
TEST_TIME 94 8739
TEST_TIME 95 8726
TEST_TIME 96 8705
TEST_TIME 97 8700
TEST_TIME 98 8682
TEST_TIME 99 8700
TEST_TIME 100 8699
TEST_TIME 101 8699
TEST_TIME 102 8649
TEST_TIME 103 8729
TEST_TIME 104 8930
TEST_TIME 105 8884
TEST_TIME 106 8912
TEST_TIME 107 8880
TEST_TIME 108 8870
TEST_TIME 109 8829
TEST_TIME 110 8813
TEST_TIME 111 8852
TEST_TIME 112 8832
TEST_TIME 113 8830
TEST_TIME 114 8829
TEST_TIME 115 8867
TEST_TIME 116 8865
TEST_TIME 117 8911
TEST_TIME 118 8918
TEST_TIME 119 8982
TEST_TIME 120 8955

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 03-02-2023 10:38:09 to 03-02-2023 10:38:13.

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 1482171 1482171 0 0.00 0 23820 13 0 0
doc_list_biud 1482160 1482160 0 0.00 0 33411 46 0 0
sp_add_invoice_to_stock 4897 4808 89 1.81 39 197737 26045 0 89
sp_cancel_adding_invoice 5541 5541 0 0.00 6 100893 1205 0 0
sp_cancel_client_order 1683 1683 0 0.00 5 20212 430 0 0
sp_cancel_customer_reserve 542639 542639 0 0.00 1 33443 110 0 0
sp_cancel_pay_from_customer 640 640 0 0.00 4 4849 103 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 4 3640 123 0 0
sp_cancel_supplier_invoice 6137 6135 2 0.03 2 26916 1087 0 2
sp_cancel_supplier_order 402 402 0 0.00 15 1598849 187554 0 0
sp_cancel_write_off 10130 10130 0 0.00 2 16163 181 0 0
sp_client_order 19415 19415 0 0.00 5 35608 508 0 0
sp_customer_reserve 446596 446596 0 0.00 0 90716 250 0 0
sp_fill_shopping_cart 29678 29678 0 0.00 1 15992 77 0 0
sp_fill_shopping_cart_clo_res 446570 446570 0 0.00 0 18432 20 0 0
sp_get_clo_for_invoice 4763 4763 0 0.00 0 29524 352 0 0
sp_kill_cost_storno 16718 16718 0 0.00 0 16338 81 0 0
sp_kill_qstorno_ret_qs2qd 1101945 1101945 0 0.00 0 33377 49 0 0
sp_kill_qty_storno 580643 580643 0 0.00 0 33407 110 0 0
sp_lock_selected_doc 19569 19569 0 0.00 0 9981 5 0 0
sp_make_cost_storno 26104 26104 0 0.00 0 18482 74 0 0
sp_multiply_rows_for_pdistr 26103 26103 0 0.00 0 23259 38 0 0
sp_multiply_rows_for_qdistr 19413 19413 0 0.00 0 13492 195 0 0
sp_pay_from_customer 6549 6549 0 0.00 5 23292 167 0 0
sp_pay_to_supplier 5441 5441 0 0.00 7 15515 176 0 0
sp_payment_common 11990 11990 0 0.00 2 14359 39 0 0
sp_qd_handle_on_cancel_clo 1683 1683 0 0.00 0 20206 367 0 0
sp_qd_handle_on_invoice_upd_sts 10303 10303 0 0.00 0 29131 507 0 0
sp_qd_handle_on_reserve_upd_sts 9351 9351 0 0.00 0 19611 62 0 0
sp_reserve_write_off 9352 9352 0 0.00 8 19658 253 0 0
sp_supplier_invoice 5101 5101 0 0.00 7 92034 3271 0 0
sp_supplier_order 5177 5177 0 0.00 10 48160 1393 0 0
srv_aggregate_perf_data 93 92 1 1.07 2813 220490 19901 0 1
srv_fill_mon_cache_memory 70 70 0 0.00 826 118619 34270 0 0
srv_find_qd_qs_mism 1024900 1024900 0 0.00 0 17369 11 0 0
srv_make_invnt_saldo 486 486 0 0.00 59 110828 3773 0 0
srv_make_money_saldo 837 837 0 0.00 1 18707 374 0 0
srv_recalc_idx_stat 4 4 0 0.00 116330 495954 301128 0 0
t$perf-abend:sp_add_invoice_to_stock 89 89 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 2 2 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4815 4815 0 0.00 1 20750 814 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 2 9204 398 0 0
t$perf-norm:sp_cancel_client_order 1683 1683 0 0.00 0 1878 23 0 0
t$perf-norm:sp_cancel_customer_reserve 611 611 0 0.00 0 1099 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 640 640 0 0.00 0 1839 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 902 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 4798 52 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 1 94390 7018 0 0
t$perf-norm:sp_cancel_write_off 921 921 0 0.00 0 1837 18 0 0
t$perf-norm:sp_client_order 19415 19415 0 0.00 0 11859 33 0 0
t$perf-norm:sp_customer_reserve 958 958 0 0.00 0 1874 15 0 0
t$perf-norm:sp_pay_from_customer 6549 6549 0 0.00 0 4293 17 0 0
t$perf-norm:sp_pay_to_supplier 5441 5441 0 0.00 0 14545 20 0 0
t$perf-norm:sp_reserve_write_off 9352 9352 0 0.00 0 4957 21 0 0
t$perf-norm:sp_supplier_invoice 5101 5101 0 0.00 0 18743 34 0 0
t$perf-norm:sp_supplier_order 5177 5177 0 0.00 0 6710 29 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 596 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 29 3 0 0
t$perf-norm:srv_make_invnt_saldo 486 486 0 0.00 0 726 17 0 0
t$perf-norm:srv_make_money_saldo 837 837 0 0.00 0 4907 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 205 59 0 0
v_add_invoice_to_stock 9686 9508 178 1.83 0 4663 23 0 178
v_all_customers 35620 35620 0 0.00 0 187 3 0 0
v_all_suppliers 28800 28800 0 0.00 0 189 4 0 0
v_all_wares 38816 38816 0 0.00 0 84 3 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 268 12 0 0
v_cancel_client_order 3366 3366 0 0.00 0 1997 9 0 0
v_cancel_customer_prepayment 1280 1280 0 0.00 0 4838 15 0 0
v_cancel_customer_reserve 1222 1222 0 0.00 0 1885 13 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 3568 21 0 0
v_cancel_supplier_invoice 796 792 4 0.50 0 2180 28 0 4
v_cancel_supplier_order 804 804 0 0.00 0 2579 15 0 0
v_cancel_write_off 1842 1842 0 0.00 0 4446 31 0 0
v_max_id_clo_ord 5172 5172 0 0.00 0 337 4 0 0
v_max_id_clo_res 958 958 0 0.00 0 1786 17 0 0
v_max_id_ord_sup 5094 5094 0 0.00 0 6509 11 0 0
v_max_non_paid_invoice 1307 1307 0 0.00 0 149 7 0 0
v_max_non_paid_realizn 3224 3224 0 0.00 0 386 6 0 0
v_min_id_clo_ord 5172 5172 0 0.00 0 4272 9 0 0
v_min_id_clo_res 958 958 0 0.00 0 1042 7 0 0
v_min_id_ord_sup 5094 5094 0 0.00 0 4468 10 0 0
v_min_non_paid_invoice 5441 5441 0 0.00 0 14352 14 0 0
v_min_non_paid_realizn 6548 6548 0 0.00 0 8160 17 0 0
v_our_firm 9842 9842 0 0.00 0 55 4 0 0
v_reserve_write_off 18698 18698 0 0.00 0 4670 12 0 0
x_lock_depdocs_on_canc_invoice 11280 11280 0 0.00 0 13498 120 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 3686 388 0 0
x_make_qstorno_1000_1200 5177 5177 0 0.00 2 48146 1236 0 0
x_make_qstorno_1200_2000 5101 5101 0 0.00 3 89135 3048 0 0
x_make_qstorno_2100_3300 446595 446595 0 0.00 0 90644 219 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-02-2023 10:38:14 to 03-02-2023 10:38:18.

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:29 9 8192000000 8807561568 8822120448 10809072 0.131 5 2 3 0 10979696 109264 61360
08:08:30 25698 8192000000 9716901968 10014961664 798421680 8.880 100 85 170 0 827532432 8115136 20995616
08:10:00 22750 8192000000 9750623232 10059980800 832459904 9.224 100 90 180 0 866790816 12437168 21893744
08:11:28 10583 8192000000 9789621568 10102968320 852635936 9.426 100 88 175 0 898233424 24646032 20951456
08:12:49 10932 8192000000 9826224272 10157158400 892938240 9.828 100 79 156 1 944965280 34562928 17464112
08:14:04 5573 8192000000 9903727712 10245926912 941089104 10.304 100 81 162 0 1017072016 58475248 17507664
08:15:13 2789 8192000000 9943202704 10290995200 978625056 10.671 100 84 163 0 1061338640 65887664 16825920
08:16:19 2370 8192000000 9982382624 10337431552 1003420192 10.912 100 89 177 0 1098069200 76935184 17713824
08:17:26 1598 8192000000 9998792816 10367987712 1028305904 11.152 100 82 160 0 1127421040 83970880 15144256
08:18:30 1173 8192000000 10026939184 10397245440 1041635552 11.280 100 93 179 1 1143724928 84680880 17408496
08:19:33 1040 8192000000 10034790896 10409508864 1054960656 11.408 100 95 184 0 1158373712 85126016 18287040
08:20:35 1046 8192000000 10043944416 10423672832 1057320544 11.431 100 95 184 0 1167803728 92881456 17601728
08:21:36 985 8192000000 10042578688 10422390784 1070015600 11.552 100 91 177 0 1164886240 77883936 16986704
08:22:38 1155 8192000000 10034877904 10426658816 1072838976 11.579 100 86 162 3 1156221200 66456992 16925232
08:23:41 1013 8192000000 10054896112 10439766016 1082837520 11.675 100 94 180 2 1176616992 75341824 18437648
08:24:43 1276 8192000000 10036743280 10425147392 1088861616 11.732 100 88 166 1 1164985248 58621120 17502512
08:25:45 3527 8192000000 10036642576 10426855424 1087493088 11.719 100 75 146 0 1159434992 57096160 14845744
08:26:50 1172 8192000000 10061518368 10449436672 1096858400 11.808 100 89 172 1 1181589312 66464896 18266016
08:27:51 1110 8192000000 10051897248 10442272768 1092871504 11.770 100 93 177 0 1174478768 64436688 17170576
08:28:53 1137 8192000000 10032554304 10422226944 1094574848 11.786 100 93 171 2 1157389184 45874272 16940064
08:29:55 1089 8192000000 10050947504 10439127040 1090651328 11.749 100 81 153 0 1166076208 60831648 14593232
08:30:57 2489 8192000000 9993465376 10379583488 1064397584 11.499 99 65 120 3 1120025808 42970192 12658032
08:32:01 941 8192000000 10021332672 10400104448 1069401792 11.546 100 91 164 1 1136010448 48755232 17853424
08:33:03 881 8192000000 10014385008 10399272960 1071908880 11.570 100 85 164 2 1135849392 46569648 17370864
08:34:05 1372 8192000000 9990206496 10364735488 1038227936 11.248 100 97 177 3 1112737424 56089776 18419712
08:35:07 1294 8192000000 10011515280 10385158144 1047565152 11.337 100 96 191 3 1129332736 62770544 18997040
08:36:09 788 8192000000 9995403088 10371108864 1039440048 11.259 100 88 168 1 1115689536 60017152 16232336
08:37:17 913 8192000000 9985289792 10365366272 1043438448 11.298 100 88 159 2 1109724112 49332624 16953040
08:38:26 27699 8192000000 10018286304 10403201024 1048172496 11.343 100 63 118 0 1123354048 63674448 11507104
08:40:04 18519 8192000000 9994538240 10381979648 1044020544 11.303 98 63 122 1 1121565040 65439568 12104928
08:41:26 6249 8192000000 10064263680 10447470592 1067197248 11.525 99 81 159 0 1171350544 86975056 17178240
08:42:36 1342 8192000000 10117653168 10498551808 1092030080 11.762 100 96 179 0 1234258752 123288928 18939744
08:43:43 5518 8192000000 10130925312 10523385856 1091071152 11.753 99 71 126 1 1243906688 138971600 13863936
08:44:51 9410 8192000000 10172329056 10578227200 1114891200 11.979 100 59 118 0 1280965984 153492384 12582400
08:46:08 8425 8192000000 10214396144 10620891136 1132676768 12.147 100 65 124 1 1329534944 182789072 14069104
08:47:20 843 8192000000 10253855760 10650877952 1149375968 12.304 100 96 186 1 1369457536 199216080 20865488
08:48:27 820 8192000000 10282953488 10674638848 1156549264 12.371 100 88 171 0 1394712064 219516912 18645888
08:49:31 2678 8192000000 10351858688 10755403776 1171535472 12.511 100 72 144 0 1462866784 274907072 16424240
08:50:38 6006 8192000000 10360402320 10765967360 1170232240 12.499 100 68 133 0 1471230800 285412128 15586432
08:51:50 2896 8192000000 10442536464 10844651520 1185422112 12.641 100 85 168 0 1554769312 349800720 19546480
08:52:58 1213 8192000000 10473830400 10886471680 1198127152 12.759 100 92 174 1 1585943952 366947920 20868880
08:54:02 977 8192000000 10536507808 10959384576 1200067296 12.777 100 98 196 0 1643418560 419888768 23462496
08:55:07 831 8192000000 10536596960 11006255104 1208027104 12.851 100 97 187 0 1647291328 415302192 23962032
08:56:12 1956 8192000000 10548397552 10983251968 1211277776 12.881 100 69 135 1 1665061456 436356896 17426784
08:57:19 3880 8192000000 10566974240 11000401920 1203464912 12.808 100 75 147 0 1680440240 457672112 19303216
08:58:27 2827 8192000000 10574240848 11047587840 1205272592 12.825 100 65 128 1 1692728720 470126352 17329776
08:59:44 1116 8192000000 10580000480 11031445504 1190397872 12.687 100 94 179 0 1685234352 472917232 21919248
09:00:49 3130 8192000000 10562729920 11055153152 1194905920 12.729 100 67 132 0 1675340032 463496480 16937632
09:02:00 6381 8192000000 10499772944 10934992896 1159208544 12.396 99 64 120 3 1592890096 418831728 14849824
09:03:13 5326 8192000000 10540749904 10985504768 1182855968 12.617 100 56 108 0 1642702912 446234000 13612944
09:04:40 5516 8192000000 10573717120 11009302528 1164231504 12.443 99 49 98 0 1676293168 499415040 12646624
09:05:56 6048 8192000000 10508918528 10989518848 1177952864 12.571 100 45 90 0 1619106480 429601680 11551936
09:07:09 11519 8192000000 10380569328 10857517056 1167809728 12.476 100 34 64 0 1482032528 305932416 8290384
09:08:45 26369 8192000000 10233480688 10707283968 1146795488 12.279 97 33 64 0 1365654912 211479760 7379664
09:10:51 106112 8192000000 10099980272 10596921344 1138707616 12.203 98 21 41 0 1216909328 73725472 4476240
09:13:54 76188 8192000000 10122239968 10567577600 1121482144 12.041 97 43 79 1 1186861712 57225312 8154256
09:16:13 3059 8192000000 10111955040 10562764800 1128918528 12.111 99 69 133 0 1204008384 60043504 15046352
09:17:22 65508 8192000000 10114746192 10595106816 1139502768 12.211 99 20 40 0 1224534592 80781312 4250512
09:19:28 54188 8192000000 10077742992 10530611200 1110398928 11.936 97 24 42 0 1164057440 48470640 5187872
09:21:23 25220 8192000000 10081266224 10529873920 1134349904 12.162 99 21 41 0 1179400256 41024544 4025808
09:22:49 77684 8192000000 10129117424 10603630592 1107630496 11.910 97 16 32 1 1193647200 82700720 3315984
09:25:08 88555 8192000000 10090466992 10590916608 1131461968 12.135 100 12 23 0 1188642992 55079184 2101840
09:28:28 60897 8192000000 10106645136 10575466496 1129400384 12.116 99 12 24 1 1223077344 90560592 3116368
09:30:33 60915 8192000000 10118231600 10591588352 1097597648 11.815 96 19 38 0 1191044464 89243984 4202832
09:32:35 74666 8192000000 10102050816 10549022720 1091740000 11.759 96 22 44 0 1184387280 87464672 5182608
09:35:26 36233 8192000000 10096735152 10532417536 1127857856 12.101 100 27 54 0 1194716944 60974544 5884544
09:37:07 8598 8192000000 10084520208 10503389184 1122115376 12.047 99 65 124 0 1168766976 34018048 12633552
09:38:19 11995 8192000000 10155790880 10586898432 1134368160 12.163 100 59 118 0 1247170720 101103360 11699200
09:39:34 56108 8192000000 10114120400 10596433920 1098264160 11.821 97 30 60 0 1225032080 120051008 6716912
09:41:31 74738 8192000000 10065496320 10504564736 1108846800 11.921 98 34 63 2 1137589904 21984848 6758256
09:43:48 1509 8192000000 10088537696 10516504576 1125046144 12.075 99 52 99 0 1191415424 55815312 10553968
09:44:50 64689 8192000000 10097078960 10567516160 1097546928 11.814 97 13 25 1 1176770208 76970384 2252896
09:46:55 74174 8192000000 10091953488 10582020096 1092159312 11.763 97 11 20 0 1164710816 69876384 2675120
09:49:11 53573 8192000000 10036010704 10473689088 1080678400 11.654 96 14 28 0 1108167952 24650608 2838944
09:51:06 16420 8192000000 10064884672 10496655360 1122045504 12.046 100 42 84 1 1163675568 32594720 9035344
09:52:24 49266 8192000000 10115115664 10557313024 1136055776 12.178 100 27 54 0 1202975520 61628624 5291120
09:54:24 81054 8192000000 10080822224 10567499776 1098564416 11.824 97 24 47 0 1204689072 101337840 4786816
09:56:47 4814 8192000000 10061321504 10482462720 1116015104 11.989 99 45 88 1 1151808752 25926672 9866976
09:57:54 29993 8192000000 10168088992 10599288832 1138305936 12.200 100 53 99 1 1241209456 92500112 10403408
10:00:17 118618 8192000000 10058061264 10520043520 1089011152 11.733 96 8 16 0 1147911296 57219104 1681040
10:03:19 81263 8192000000 10095295152 10544766976 1132961024 12.149 100 51 99 1 1198206448 54908880 10336544
10:05:40 66067 8192000000 10100497536 10559967232 1130440544 12.126 99 19 37 0 1203759728 69353328 3965856
10:07:50 50952 8192000000 10068979600 10519834624 1123646544 12.061 99 13 25 0 1160476464 33895024 2934896
10:10:02 55826 8192000000 10098046720 10545045504 1102627760 11.863 97 18 36 0 1176579344 69975040 3976544
10:11:59 24154 8192000000 10116998432 10555514880 1113867424 11.969 98 48 95 0 1193945632 69514304 10563904
10:13:27 15229 8192000000 10160190416 10590871552 1129535472 12.117 99 40 80 0 1242568848 104208176 8825200
10:14:47 18064 8192000000 10177191376 10608766976 1134779104 12.166 99 38 76 0 1261519984 118093376 8647504
10:16:06 17875 8192000000 10235194240 10675359744 1155228352 12.359 100 43 84 0 1314754864 149919520 9606992
10:17:31 37725 8192000000 10202518128 10651308032 1133742480 12.157 98 36 72 0 1300044688 157993520 8308688
10:19:09 10846 8192000000 10220008928 10643783680 1136042848 12.178 98 46 92 0 1298485440 152603008 9839584
10:20:22 49420 8192000000 10248587072 10741121024 1152484528 12.333 100 27 54 0 1361699472 203165904 6049040
10:22:22 67164 8192000000 10105504768 10561511424 1133868640 12.158 99 33 66 0 1195805808 54987536 6949632
10:25:58 67663 8192000000 10072233328 10509590528 1110194224 11.934 98 25 48 0 1148026432 33685104 4147104
10:28:06 62575 8192000000 10094670496 10560126976 1123844944 12.063 99 20 39 0 1187848224 59154912 4848368
10:30:24 67767 8192000000 10116925792 10580193280 1128502832 12.107 99 22 44 0 1175813568 42762544 4548192
10:32:39 54642 8192000000 10124110048 10566418432 1144857056 12.261 100 21 42 0 1232797088 83471248 4468784
10:34:36 46021 8192000000 10077386096 10529669120 1090214992 11.745 96 18 36 0 1167227072 73012576 3999504
10:36:25 63138 8192000000 9901156752 10345455616 963887248 10.527 85 13 26 0 1001366384 34937008 2542128

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 03-02-2023 10:38:20 to 03-02-2023 10:38:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 89 v_add_invoice_to_stock 335544517
except 2 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:03, from 03-02-2023 10:38:37 to 03-02-2023 10:38:40.

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          212453
MON$OLDEST_ACTIVE               212454
MON$OLDEST_SNAPSHOT             212454
MON$NEXT_TRANSACTION            212454
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                       2037536
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             760
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2895 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2895 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2895 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2895 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 ---
:55: 
:56: mscs-test-161.budzdorov.ru    Fri Feb  3 10:37:32 2023
:57:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:58: 
:59: 
:60: mscs-test-161.budzdorov.ru    Fri Feb  3 10:37:36 2023
:61:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:62: 
--- end of diff output ---

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

03.02.23 10:38:41. 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
2023-02-03 10:38:43 No severe PSQL-related problems occured
03.02.23 10:38:45. End of report.