Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.01.2023 16:39
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 15289600 kB
MemAvailable 30350900 kB
Buffers 0 kB
Cached 16406220 kB
SwapCached 4428 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-01-08 16:38:35
DTS_END                         2023-01-08 16:38:35.6090
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/37212
TRN_ID                          240566
ATT_ID                          654
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: 9248.24 22296 1109789
customer order: creation 167.92 378 20150
customer order: refuse 13.96 355 1675
order to supplier: creation 44.32 1225 5318
order to supplier: removal 3.41 104125 409
invoice (draft): creation 45.14 3344 5417
invoice (draft): removal 53.21 829 6385
invoice accept: apply 40.97 17917 4916
invoice accept: cancel 47.88 785 5745
customer reserve: creation 3788.18 178 454582
customer reserve: removal 4751.74 58 570209
realization accept: apply 82.48 186 9897
realization accept: cancel 83.68 102 10041
payment from customer: creation 58.38 106 7005
payment from customer: removal 5.47 65 656
payment to supplier: creation 47.15 134 5658
payment to supplier: removal 3.24 77 389
service: total inventory turnovers 4.04 1342 485
service: total monetary turnovers 6.30 32 756
service: aggregate perf. data 0.77 23993 92
service: refresh index statistics 0.03 290680 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-01-2023 16:39:51 to 08-01-2023 16:39:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2896
TEST_TIME 2 2874
TEST_TIME 3 3676
TEST_TIME 4 4151
TEST_TIME 5 4047
TEST_TIME 6 4341
TEST_TIME 7 4395
TEST_TIME 8 5069
TEST_TIME 9 5342
TEST_TIME 10 5728
TEST_TIME 11 5611
TEST_TIME 12 5809
TEST_TIME 13 6015
TEST_TIME 14 6071
TEST_TIME 15 6154
TEST_TIME 16 6552
TEST_TIME 17 6563
TEST_TIME 18 7008
TEST_TIME 19 7197
TEST_TIME 20 7613
TEST_TIME 21 8051
TEST_TIME 22 7881
TEST_TIME 23 8126
TEST_TIME 24 7960
TEST_TIME 25 8364
TEST_TIME 26 8346
TEST_TIME 27 8780
TEST_TIME 28 8796
TEST_TIME 29 8995
TEST_TIME 30 9346
TEST_TIME 31 10294
TEST_TIME 32 10319
TEST_TIME 33 10070
TEST_TIME 34 10138
TEST_TIME 35 9952
TEST_TIME 36 9853
TEST_TIME 37 9818
TEST_TIME 38 9697
TEST_TIME 39 9722
TEST_TIME 40 9606
TEST_TIME 41 9654
TEST_TIME 42 9545
TEST_TIME 43 9527
TEST_TIME 44 9471
TEST_TIME 45 9364
TEST_TIME 46 9217
TEST_TIME 47 9245
TEST_TIME 48 9127
TEST_TIME 49 9113
TEST_TIME 50 9130
TEST_TIME 51 8993
TEST_TIME 52 8936
TEST_TIME 53 9062
TEST_TIME 54 9010
TEST_TIME 55 9028
TEST_TIME 56 9062
TEST_TIME 57 9213
TEST_TIME 58 9206
TEST_TIME 59 9299
TEST_TIME 60 9282
TEST_TIME 61 9401
TEST_TIME 62 9392
TEST_TIME 63 9536
TEST_TIME 64 9599
TEST_TIME 65 9586
TEST_TIME 66 9511
TEST_TIME 67 9439
TEST_TIME 68 9385
TEST_TIME 69 9366
TEST_TIME 70 9335
TEST_TIME 71 9349
TEST_TIME 72 9309
TEST_TIME 73 9250
TEST_TIME 74 9167
TEST_TIME 75 9139
TEST_TIME 76 9155
TEST_TIME 77 9279
TEST_TIME 78 9250
TEST_TIME 79 9287
TEST_TIME 80 9331
TEST_TIME 81 9356
TEST_TIME 82 9323
TEST_TIME 83 9339
TEST_TIME 84 9343
TEST_TIME 85 9381
TEST_TIME 86 9363
TEST_TIME 87 9406
TEST_TIME 88 9493
TEST_TIME 89 9497
TEST_TIME 90 9448
TEST_TIME 91 9426
TEST_TIME 92 9343
TEST_TIME 93 9300
TEST_TIME 94 9251
TEST_TIME 95 9242
TEST_TIME 96 9253
TEST_TIME 97 9351
TEST_TIME 98 9338
TEST_TIME 99 9331
TEST_TIME 100 9331
TEST_TIME 101 9313
TEST_TIME 102 9294
TEST_TIME 103 9338
TEST_TIME 104 9439
TEST_TIME 105 9411
TEST_TIME 106 9451
TEST_TIME 107 9464
TEST_TIME 108 9472
TEST_TIME 109 9463
TEST_TIME 110 9426
TEST_TIME 111 9382
TEST_TIME 112 9374
TEST_TIME 113 9311
TEST_TIME 114 9323
TEST_TIME 115 9326
TEST_TIME 116 9302
TEST_TIME 117 9266
TEST_TIME 118 9237
TEST_TIME 119 9284
TEST_TIME 120 9252

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-01-2023 16:39:54 to 08-01-2023 16:39:58.

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 1531832 1531832 0 0.00 0 13055 6 0 0
doc_list_biud 1531826 1531826 0 0.00 0 31343 28 0 0
sp_add_invoice_to_stock 4981 4916 65 1.30 33 146998 17918 0 65
sp_cancel_adding_invoice 5745 5745 0 0.00 4 88108 786 0 0
sp_cancel_client_order 1675 1675 0 0.00 4 14200 355 0 0
sp_cancel_customer_reserve 570209 570209 0 0.00 0 31344 59 0 0
sp_cancel_pay_from_customer 656 656 0 0.00 3 2952 66 0 0
sp_cancel_pay_to_supplier 389 389 0 0.00 3 4010 78 0 0
sp_cancel_supplier_invoice 6389 6385 4 0.06 1 12883 829 0 4
sp_cancel_supplier_order 409 409 0 0.00 6 1040473 104126 0 0
sp_cancel_write_off 10041 10041 0 0.00 1 7943 102 0 0
sp_client_order 20150 20150 0 0.00 3 22484 379 0 0
sp_customer_reserve 454582 454582 0 0.00 0 81004 179 0 0
sp_fill_shopping_cart 30874 30874 0 0.00 1 23403 39 0 0
sp_fill_shopping_cart_clo_res 454575 454575 0 0.00 0 22243 16 0 0
sp_get_clo_for_invoice 4909 4909 0 0.00 0 33309 309 0 0
sp_kill_cost_storno 16831 16831 0 0.00 0 8122 51 0 0
sp_kill_qstorno_ret_qs2qd 1157253 1157253 0 0.00 0 31341 32 0 0
sp_kill_qty_storno 609269 609269 0 0.00 0 31343 69 0 0
sp_lock_selected_doc 20320 20320 0 0.00 0 5865 5 0 0
sp_make_cost_storno 27469 27469 0 0.00 0 13032 52 0 0
sp_multiply_rows_for_pdistr 27469 27469 0 0.00 0 6811 24 0 0
sp_multiply_rows_for_qdistr 20150 20150 0 0.00 0 13456 147 0 0
sp_pay_from_customer 7005 7005 0 0.00 4 13422 107 0 0
sp_pay_to_supplier 5658 5658 0 0.00 5 16186 134 0 0
sp_payment_common 12663 12663 0 0.00 1 8069 26 0 0
sp_qd_handle_on_cancel_clo 1675 1675 0 0.00 0 14194 325 0 0
sp_qd_handle_on_invoice_upd_sts 10653 10653 0 0.00 0 15436 323 0 0
sp_qd_handle_on_reserve_upd_sts 9897 9897 0 0.00 0 17621 65 0 0
sp_reserve_write_off 9897 9897 0 0.00 6 19336 187 0 0
sp_supplier_invoice 5417 5417 0 0.00 7 89515 3344 0 0
sp_supplier_order 5318 5318 0 0.00 4 34472 1225 0 0
srv_aggregate_perf_data 92 92 0 0.00 2628 379711 23993 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1985 138590 46679 0 0
srv_find_qd_qs_mism 1063701 1063701 0 0.00 0 11739 5 0 0
srv_make_invnt_saldo 485 485 0 0.00 35 72158 1343 0 0
srv_make_money_saldo 756 756 0 0.00 1 4308 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 104521 531217 290680 0 0
t$perf-abend:sp_add_invoice_to_stock 65 65 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4916 4916 0 0.00 0 12132 159 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 0 8980 113 0 0
t$perf-norm:sp_cancel_client_order 1675 1675 0 0.00 0 8290 13 0 0
t$perf-norm:sp_cancel_customer_reserve 635 635 0 0.00 0 2231 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 656 656 0 0.00 0 2227 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 389 389 0 0.00 0 122 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 356 356 0 0.00 0 1422 16 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 20322 1542 0 0
t$perf-norm:sp_cancel_write_off 997 997 0 0.00 0 7891 22 0 0
t$perf-norm:sp_client_order 20150 20150 0 0.00 0 28637 20 0 0
t$perf-norm:sp_customer_reserve 988 988 0 0.00 0 3438 5 0 0
t$perf-norm:sp_pay_from_customer 7005 7005 0 0.00 0 6457 11 0 0
t$perf-norm:sp_pay_to_supplier 5658 5658 0 0.00 0 5688 9 0 0
t$perf-norm:sp_reserve_write_off 9897 9897 0 0.00 0 15927 15 0 0
t$perf-norm:sp_supplier_invoice 5417 5417 0 0.00 0 8571 16 0 0
t$perf-norm:sp_supplier_order 5318 5318 0 0.00 0 3029 13 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 259 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 137 4 0 0
t$perf-norm:srv_make_invnt_saldo 485 485 0 0.00 0 1738 29 0 0
t$perf-norm:srv_make_money_saldo 756 756 0 0.00 0 963 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 176 47 0 0
v_add_invoice_to_stock 9944 9814 130 1.30 0 12106 20 0 130
v_all_customers 36830 36830 0 0.00 0 25 0 0 0
v_all_suppliers 29572 29572 0 0.00 0 25 1 0 0
v_all_wares 40300 40300 0 0.00 0 136 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 12087 22 0 0
v_cancel_client_order 3350 3350 0 0.00 0 3597 7 0 0
v_cancel_customer_prepayment 1312 1312 0 0.00 0 2436 5 0 0
v_cancel_customer_reserve 1270 1270 0 0.00 0 61 2 0 0
v_cancel_payment_to_supplier 778 778 0 0.00 0 3999 9 0 0
v_cancel_supplier_invoice 720 712 8 1.11 0 1491 17 0 8
v_cancel_supplier_order 818 818 0 0.00 0 443 3 0 0
v_cancel_write_off 1994 1994 0 0.00 0 5482 16 0 0
v_max_id_clo_ord 5317 5317 0 0.00 0 485 1 0 0
v_max_id_clo_res 988 988 0 0.00 0 7251 27 0 0
v_max_id_ord_sup 5407 5407 0 0.00 0 6256 8 0 0
v_max_non_paid_invoice 1596 1596 0 0.00 0 207 2 0 0
v_max_non_paid_realizn 3695 3695 0 0.00 0 3486 5 0 0
v_min_id_clo_ord 5317 5317 0 0.00 0 3242 6 0 0
v_min_id_clo_res 988 988 0 0.00 0 5575 26 0 0
v_min_id_ord_sup 5407 5407 0 0.00 0 6842 11 0 0
v_min_non_paid_invoice 5658 5658 0 0.00 0 3611 18 0 0
v_min_non_paid_realizn 7005 7005 0 0.00 0 6484 19 0 0
v_our_firm 10090 10090 0 0.00 0 19 1 0 0
v_reserve_write_off 19794 19794 0 0.00 0 18739 10 0 0
x_lock_depdocs_on_canc_invoice 11774 11774 0 0.00 0 18027 116 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 8028 345 0 0
x_make_qstorno_1000_1200 5318 5318 0 0.00 1 34433 1160 0 0
x_make_qstorno_1200_2000 5417 5417 0 0.00 3 77869 3191 0 0
x_make_qstorno_2100_3300 454582 454582 0 0.00 0 80999 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-01-2023 16:39:59 to 08-01-2023 16:40:03.

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
14:08:39 37 8192000000 8813219600 8829444096 16734624 0.203 5 3 4 0 16921120 79376 107120
14:09:40 30477 8192000000 9920513200 10237116416 1006770592 10.944 100 85 170 0 1034704784 6249360 21684832
14:11:17 17851 8192000000 9973550880 10298683392 1056109696 11.419 100 94 188 0 1088546400 9037312 23399392
14:12:41 17260 8192000000 9994902528 10331377664 1083091168 11.677 100 85 168 0 1113036976 9379072 20566736
14:14:01 10927 8192000000 10045979632 10395824128 1133646464 12.156 100 77 154 0 1163175120 11762352 17766304
14:15:18 4439 8192000000 10095160480 10452426752 1177140272 12.564 100 90 180 0 1212222320 14385296 20696752
14:16:26 2957 8192000000 10127703552 10493628416 1212063840 12.888 100 78 156 0 1241531264 11886656 17580768
14:17:30 5244 8192000000 10154075552 10531897344 1245452416 13.196 100 72 144 0 1274025488 12017552 16555520
14:18:37 1436 8192000000 10187893888 10570334208 1275359040 13.471 100 83 164 1 1306477984 13230576 17888368
14:19:40 1466 8192000000 10203116176 10588917760 1292604784 13.628 100 80 161 0 1322512480 12502080 17405616
14:20:42 7428 8192000000 10187200016 10597113856 1293744432 13.638 100 44 88 0 1313633920 10528288 9361200
14:21:53 3840 8192000000 10197503728 10604736512 1307634960 13.765 100 31 62 0 1322484112 8339136 6510016
14:22:58 13376 8192000000 10196717184 10616627200 1316695424 13.847 100 18 36 0 1326670960 5928256 4047280
14:24:12 10657 8192000000 10207632592 10625576960 1324860432 13.921 100 16 32 0 1334246752 5274080 4112240
14:25:23 13966 8192000000 10228189552 10638716928 1326311824 13.934 100 35 71 0 1340339504 6092688 7934992
14:26:37 4682 8192000000 10225647696 10648653824 1336265200 14.024 100 27 54 0 1353800048 11160720 6374128
14:27:43 7286 8192000000 10229623984 10652311552 1338102816 14.040 100 24 48 1 1350053904 6886736 5064352
14:28:55 12679 8192000000 10176997088 10584772608 1241816352 13.163 94 18 34 0 1254865648 9119920 3929376
14:30:12 20908 8192000000 10156095328 10571923456 1211437696 12.882 93 24 50 0 1222137344 4428432 6271216
14:31:33 6156 8192000000 10145847200 10549698560 1246548784 13.206 99 26 52 0 1258661856 6523472 5589600
14:32:40 9154 8192000000 10174854752 10570399744 1262016048 13.348 99 28 56 0 1274042880 5600384 6426448
14:33:50 19049 8192000000 10167753312 10582712320 1285819088 13.566 100 23 46 0 1296081040 4738880 5523072
14:35:54 5699 8192000000 10199632368 10613624832 1306063856 13.750 100 18 36 0 1316322432 5787616 4470960
14:37:00 12688 8192000000 10207612496 10624622592 1315499248 13.836 100 28 54 1 1327797920 5388816 6909856
14:38:17 9050 8192000000 10232052736 10650963968 1337560736 14.035 100 11 23 0 1344973424 4825712 2586976
14:39:30 72302 8192000000 10261215520 10678870016 1354921008 14.192 100 35 67 0 1369851392 7328016 7602368
14:41:51 22255 8192000000 10277540288 10701271040 1373650960 14.360 100 39 77 1 1391993408 9743648 8598800
14:43:26 32824 8192000000 10290411312 10715582464 1375154576 14.373 99 35 62 1 1395238048 11484400 8599072
14:45:07 8876 8192000000 10311810496 10736443392 1400119408 14.596 100 50 99 1 1426289472 14164800 12005264
14:46:20 28439 8192000000 10330449856 10757648384 1408192608 14.668 100 51 101 0 1442606128 22052384 12361136
14:47:53 5366 8192000000 10325345088 10752225280 1407477840 14.662 100 48 96 0 1438331936 19733520 11120576
14:49:02 8978 8192000000 10333256832 10757152768 1408172368 14.668 100 48 86 0 1439068480 20587488 10308624
14:50:15 9754 8192000000 10333434544 10757976064 1400059744 14.596 100 52 103 0 1441576848 28800592 12716512
14:51:31 10965 8192000000 10330797120 10750214144 1394032272 14.542 100 53 103 0 1434796640 28076432 12687936
14:52:53 7337 8192000000 10323257504 10735927296 1381640736 14.431 100 72 144 1 1431771152 32157104 17973312
14:54:12 3115 8192000000 10311122624 10722578432 1355927184 14.201 98 59 116 0 1411297424 40025616 15344624
14:55:20 4660 8192000000 10309584880 10718408704 1343649184 14.090 98 62 122 1 1398004800 39614864 14740752
14:56:29 2617 8192000000 10330558336 10736701440 1374533184 14.368 100 72 141 0 1433898720 42133552 17231984
14:57:35 4588 8192000000 10341186592 10753748992 1385702080 14.468 100 60 118 1 1443481504 42650720 15128704
14:58:58 1980 8192000000 10347024848 10750291968 1379401312 14.411 100 86 173 1 1448738928 47206976 22130640
15:00:05 17891 8192000000 10321275056 10742517760 1376876880 14.389 100 37 74 2 1428504192 42135296 9492016
15:01:27 14714 8192000000 10312240768 10737496064 1366384112 14.295 99 44 88 0 1414256272 36499248 11372912
15:02:45 17211 8192000000 10338110752 10763964416 1381731456 14.432 100 43 86 1 1441058720 47588688 11738576
15:04:28 16389 8192000000 10320559328 10748739584 1367288064 14.303 100 28 54 1 1424311488 49075216 7948208
15:05:58 41207 8192000000 10317184336 10759065600 1373630192 14.360 100 22 44 2 1426882208 47093760 6158256
15:08:08 9650 8192000000 10323446848 10770829312 1372269760 14.347 100 24 48 0 1429101552 49637136 7194656
15:09:19 52870 8192000000 10296130032 10736271360 1380908512 14.425 100 22 41 0 1409020528 23388208 4723808
15:11:15 75540 8192000000 10279930336 10734170112 1370977744 14.336 99 30 59 0 1386079616 7984032 7117840
15:14:05 45544 8192000000 10285012352 10731630592 1374308832 14.366 99 29 58 0 1393585152 12876448 6399872
15:15:53 24436 8192000000 10305440224 10747219968 1389743264 14.504 100 32 62 0 1407114416 10384688 6986464
15:17:22 32027 8192000000 10286317808 10742530048 1385622368 14.467 100 16 32 0 1399652640 10495424 3534848
15:18:56 51895 8192000000 10274681280 10733780992 1366738528 14.298 99 12 24 0 1383065648 13415696 2911424
15:21:08 35464 8192000000 10275433712 10735878144 1378362688 14.402 100 4 8 0 1390290896 10853328 1074880
15:22:45 101629 8192000000 10288574768 10741194752 1385540432 14.466 100 16 32 0 1396665968 7294224 3831312
15:25:46 83819 8192000000 10301637728 10756755456 1399300000 14.589 100 23 47 0 1414212224 9738288 5173936
15:28:58 81331 8192000000 10309486368 10777296896 1409525952 14.680 100 20 40 0 1427375792 13126256 4723584
15:31:20 57682 8192000000 10301136368 10755981312 1386467856 14.474 99 9 18 0 1406081744 17481872 2132016
15:33:20 35771 8192000000 10315200304 10764406784 1365942976 14.291 97 31 62 0 1391879936 18562144 7374816
15:35:06 49042 8192000000 10301587488 10769870848 1396560016 14.564 100 24 49 0 1424988864 21729840 6699008
15:36:57 64727 8192000000 10298293808 10749583360 1350843632 14.155 96 11 22 0 1368224864 15090576 2290656
15:39:06 28491 8192000000 10286877200 10722582528 1335765584 14.019 96 41 81 0 1355597200 10729824 9101792
15:40:40 86415 8192000000 10300344096 10768195584 1300080912 13.696 93 11 22 0 1325392880 22781344 2530624
15:43:23 67228 8192000000 10287251904 10714058752 1277755648 13.493 93 40 82 0 1296470464 9659424 9055392
15:45:35 51133 8192000000 10257514080 10705330176 1324676736 13.919 97 40 79 2 1340655312 6515632 9462944
15:47:36 59947 8192000000 10273866336 10723360768 1360453216 14.241 100 28 56 0 1380574096 14104656 6016224
15:49:39 41303 8192000000 10260812688 10702733312 1306513024 13.754 96 16 32 0 1323211856 13407424 3291408
15:51:22 87913 8192000000 10267638256 10712256512 1339559632 14.053 99 16 30 1 1356999152 13772736 3666784
15:53:55 60356 8192000000 10252883792 10704252928 1316400864 13.844 97 15 31 0 1335786608 15806064 3579680
15:56:38 67330 8192000000 10259502624 10708594688 1338510448 14.044 99 18 36 0 1351751056 9233264 4007344
15:58:46 42548 8192000000 10249964064 10710171648 1340299312 14.060 99 18 36 0 1355255136 10812640 4143184
16:00:32 56750 8192000000 10263652736 10716512256 1341682256 14.073 98 18 36 0 1354408848 8708960 4017632
16:02:30 67869 8192000000 10272155744 10714554368 1324313904 13.916 97 12 24 0 1337866448 10884208 2668336
16:04:39 25743 8192000000 10280808080 10722168832 1353101984 14.175 99 36 72 0 1373886208 12947456 7836768
16:06:12 97746 8192000000 10277602208 10728640512 1351972720 14.165 99 10 20 0 1372946816 18852608 2121488
16:08:51 138589 8192000000 10289752912 10754949120 1380087664 14.417 100 20 40 1 1395866928 11118720 4660544
16:12:10 66544 8192000000 10277952608 10734755840 1376950464 14.389 100 14 28 0 1394238096 14239536 3048096
16:15:26 65014 8192000000 10285835648 10739314688 1371276752 14.338 99 11 22 0 1389854912 16087952 2490208
16:18:12 55497 8192000000 10295817296 10745028608 1362639408 14.261 98 27 54 0 1379022752 10000848 6382496
16:20:13 68874 8192000000 10303696480 10776662016 1395574320 14.556 100 14 28 0 1419597744 20321456 3701968
16:22:22 86676 8192000000 10302302960 10752155648 1357306592 14.213 97 8 16 0 1370565952 11510448 1748912
16:24:50 75436 8192000000 10297789280 10750603264 1334703152 14.010 96 18 36 0 1353402640 14970384 3729104
16:27:08 34548 8192000000 10272645264 10717614080 1328933664 13.958 97 16 32 1 1341564528 8846928 3783936
16:28:48 99198 8192000000 10271037024 10718593024 1339672960 14.054 98 33 67 1 1356164288 9015248 7476080
16:31:27 43415 8192000000 10282077136 10730127360 1374936256 14.371 100 34 69 0 1396557248 13308176 8312816
16:33:16 43974 8192000000 10269697792 10738057216 1371612768 14.341 100 13 26 0 1384417072 9980736 2823568
16:35:00 81446 8192000000 10264031104 10719825920 1332838896 13.993 97 14 28 1 1347410832 11170608 3401328
16:37:24 61046 8192000000 10260832336 10717556736 1332722400 13.992 97 14 28 0 1346401568 10479536 3199632

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-01-2023 16:40:05 to 08-01-2023 16:40:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 65 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-01-2023 16:40:21 to 08-01-2023 16:40:23.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          240479
MON$OLDEST_ACTIVE               240758
MON$OLDEST_SNAPSHOT             240758
MON$NEXT_TRANSACTION            240758
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-03 16:41:55.3470
MON$PAGES                       2030624
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33652 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33652 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33652 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33652 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:110: 
:111: mscs-test-161.budzdorov.ru    Sun Jan  8 16:38:39 2023
:112:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:113: 
:114: 
:115: mscs-test-161.budzdorov.ru    Sun Jan  8 16:38:39 2023
:116:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:117: 
:118: 
:119: mscs-test-161.budzdorov.ru    Sun Jan  8 16:38:40 2023
:120:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:121: 
:122: 
:123: mscs-test-161.budzdorov.ru    Sun Jan  8 16:38:40 2023
:124:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:125: 
:126: 
:127: mscs-test-161.budzdorov.ru    Sun Jan  8 16:38:40 2023
:128:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:129: 
--- end of diff output ---

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

08.01.23 16:40:24. There are 3 logs in /var/tmp/logs-oltp30 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-01-08 16:40:27 No severe PSQL-related problems occured
08.01.23 16:40:28. End of report.