Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.02.2023 01:40
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 15344404 kB
MemAvailable 30450844 kB
Buffers 0 kB
Cached 16385768 kB
SwapCached 5072 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-02-10 01:38:41
DTS_END                         2023-02-10 01:38:41.3540
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(57:17)
IP                              ::1/53590
TRN_ID                          244933
ATT_ID                          727
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: 9365.93 19006 1123912
customer order: creation 176.65 366 21198
customer order: refuse 14.59 340 1751
order to supplier: creation 46.96 1118 5635
order to supplier: removal 3.51 100009 421
invoice (draft): creation 46.65 3065 5598
invoice (draft): removal 53.58 735 6429
invoice accept: apply 41.90 18544 5028
invoice accept: cancel 48.75 743 5850
customer reserve: creation 3858.01 184 462961
customer reserve: removal 4770.72 58 572486
realization accept: apply 84.78 170 10173
realization accept: cancel 87.90 105 10548
payment from customer: creation 60.77 115 7292
payment from customer: removal 5.93 59 711
payment to supplier: creation 49.31 137 5917
payment to supplier: removal 3.70 76 444
service: total inventory turnovers 4.53 1297 543
service: total monetary turnovers 6.83 58 820
service: aggregate perf. data 0.86 22621 103
service: refresh index statistics 0.03 230312 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-02-2023 01:41:19 to 10-02-2023 01:41:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2308
TEST_TIME 2 2331
TEST_TIME 3 2725
TEST_TIME 4 3337
TEST_TIME 5 3539
TEST_TIME 6 3685
TEST_TIME 7 4638
TEST_TIME 8 4968
TEST_TIME 9 5351
TEST_TIME 10 5248
TEST_TIME 11 5301
TEST_TIME 12 5519
TEST_TIME 13 5853
TEST_TIME 14 6011
TEST_TIME 15 6375
TEST_TIME 16 6688
TEST_TIME 17 7051
TEST_TIME 18 7309
TEST_TIME 19 7401
TEST_TIME 20 7887
TEST_TIME 21 8251
TEST_TIME 22 8312
TEST_TIME 23 8845
TEST_TIME 24 9473
TEST_TIME 25 9641
TEST_TIME 26 9971
TEST_TIME 27 10320
TEST_TIME 28 10287
TEST_TIME 29 10701
TEST_TIME 30 10731
TEST_TIME 31 10394
TEST_TIME 32 10237
TEST_TIME 33 10160
TEST_TIME 34 10282
TEST_TIME 35 10163
TEST_TIME 36 10172
TEST_TIME 37 10030
TEST_TIME 38 9898
TEST_TIME 39 10115
TEST_TIME 40 10147
TEST_TIME 41 10015
TEST_TIME 42 9845
TEST_TIME 43 9834
TEST_TIME 44 9835
TEST_TIME 45 9776
TEST_TIME 46 9715
TEST_TIME 47 9718
TEST_TIME 48 9574
TEST_TIME 49 9516
TEST_TIME 50 9560
TEST_TIME 51 9584
TEST_TIME 52 9667
TEST_TIME 53 9629
TEST_TIME 54 9522
TEST_TIME 55 9416
TEST_TIME 56 9350
TEST_TIME 57 9326
TEST_TIME 58 9266
TEST_TIME 59 9311
TEST_TIME 60 9248
TEST_TIME 61 9258
TEST_TIME 62 9351
TEST_TIME 63 9338
TEST_TIME 64 9350
TEST_TIME 65 9365
TEST_TIME 66 9430
TEST_TIME 67 9438
TEST_TIME 68 9518
TEST_TIME 69 9514
TEST_TIME 70 9486
TEST_TIME 71 9538
TEST_TIME 72 9572
TEST_TIME 73 9525
TEST_TIME 74 9454
TEST_TIME 75 9494
TEST_TIME 76 9418
TEST_TIME 77 9398
TEST_TIME 78 9371
TEST_TIME 79 9300
TEST_TIME 80 9236
TEST_TIME 81 9246
TEST_TIME 82 9212
TEST_TIME 83 9229
TEST_TIME 84 9336
TEST_TIME 85 9359
TEST_TIME 86 9346
TEST_TIME 87 9437
TEST_TIME 88 9400
TEST_TIME 89 9450
TEST_TIME 90 9433
TEST_TIME 91 9474
TEST_TIME 92 9504
TEST_TIME 93 9522
TEST_TIME 94 9535
TEST_TIME 95 9493
TEST_TIME 96 9452
TEST_TIME 97 9406
TEST_TIME 98 9359
TEST_TIME 99 9390
TEST_TIME 100 9397
TEST_TIME 101 9356
TEST_TIME 102 9325
TEST_TIME 103 9298
TEST_TIME 104 9263
TEST_TIME 105 9287
TEST_TIME 106 9277
TEST_TIME 107 9256
TEST_TIME 108 9242
TEST_TIME 109 9235
TEST_TIME 110 9280
TEST_TIME 111 9350
TEST_TIME 112 9307
TEST_TIME 113 9377
TEST_TIME 114 9399
TEST_TIME 115 9369
TEST_TIME 116 9438
TEST_TIME 117 9481
TEST_TIME 118 9446
TEST_TIME 119 9427
TEST_TIME 120 9370

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-02-2023 01:41:23 to 10-02-2023 01:41:26.

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 1553197 1553197 0 0.00 0 32624 6 0 0
doc_list_biud 1553183 1553183 0 0.00 0 38184 27 0 0
sp_add_invoice_to_stock 5178 5028 150 2.89 55 181426 18544 0 150
sp_cancel_adding_invoice 5850 5850 0 0.00 4 53227 744 0 0
sp_cancel_client_order 1751 1751 0 0.00 4 10501 340 0 0
sp_cancel_customer_reserve 572486 572486 0 0.00 0 30146 59 0 0
sp_cancel_pay_from_customer 711 711 0 0.00 2 6372 59 0 0
sp_cancel_pay_to_supplier 444 444 0 0.00 3 3864 77 0 0
sp_cancel_supplier_invoice 6434 6429 5 0.07 2 38252 736 0 5
sp_cancel_supplier_order 421 421 0 0.00 7 1042796 100009 0 0
sp_cancel_write_off 10548 10548 0 0.00 1 15246 105 0 0
sp_client_order 21198 21198 0 0.00 3 20361 367 0 0
sp_customer_reserve 462961 462961 0 0.00 0 39518 185 0 0
sp_fill_shopping_cart 32417 32417 0 0.00 1 15274 36 0 0
sp_fill_shopping_cart_clo_res 462957 462957 0 0.00 0 12646 15 0 0
sp_get_clo_for_invoice 5024 5024 0 0.00 0 36276 334 0 0
sp_kill_cost_storno 17553 17553 0 0.00 0 6055 49 0 0
sp_kill_qstorno_ret_qs2qd 1162370 1162370 0 0.00 0 38182 32 0 0
sp_kill_qty_storno 612672 612672 0 0.00 0 38183 68 0 0
sp_lock_selected_doc 21119 21119 0 0.00 0 8731 7 0 0
sp_make_cost_storno 28406 28406 0 0.00 0 32614 52 0 0
sp_multiply_rows_for_pdistr 28393 28393 0 0.00 0 7907 26 0 0
sp_multiply_rows_for_qdistr 21198 21198 0 0.00 0 17320 167 0 0
sp_pay_from_customer 7292 7292 0 0.00 4 12205 115 0 0
sp_pay_to_supplier 5917 5917 0 0.00 4 12286 138 0 0
sp_payment_common 13206 13206 0 0.00 2 10585 32 0 0
sp_qd_handle_on_cancel_clo 1751 1751 0 0.00 0 10495 310 0 0
sp_qd_handle_on_invoice_upd_sts 10866 10866 0 0.00 0 16768 308 0 0
sp_qd_handle_on_reserve_upd_sts 10171 10171 0 0.00 0 21060 59 0 0
sp_reserve_write_off 10173 10173 0 0.00 6 21103 170 0 0
sp_supplier_invoice 5598 5598 0 0.00 5 85858 3066 0 0
sp_supplier_order 5635 5635 0 0.00 5 42880 1118 0 0
srv_aggregate_perf_data 103 103 0 0.00 2358 486439 22622 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1580 109589 43315 0 0
srv_find_qd_qs_mism 1075755 1075755 0 0.00 0 21978 6 0 0
srv_make_invnt_saldo 543 543 0 0.00 47 34620 1297 0 0
srv_make_money_saldo 820 820 0 0.00 0 18690 58 0 0
srv_recalc_idx_stat 4 4 0 0.00 142213 412859 230312 0 0
t$perf-abend:sp_add_invoice_to_stock 150 150 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5028 5028 0 0.00 0 7454 147 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 0 5536 117 0 0
t$perf-norm:sp_cancel_client_order 1751 1751 0 0.00 0 3641 12 0 0
t$perf-norm:sp_cancel_customer_reserve 728 728 0 0.00 0 4629 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 711 711 0 0.00 0 4640 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 444 444 0 0.00 0 3894 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 5886 27 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 22577 1468 0 0
t$perf-norm:sp_cancel_write_off 1068 1068 0 0.00 0 4588 11 0 0
t$perf-norm:sp_client_order 21198 21198 0 0.00 0 34488 16 0 0
t$perf-norm:sp_customer_reserve 1023 1023 0 0.00 0 1655 5 0 0
t$perf-norm:sp_pay_from_customer 7292 7292 0 0.00 0 4188 10 0 0
t$perf-norm:sp_pay_to_supplier 5917 5917 0 0.00 0 3844 9 0 0
t$perf-norm:sp_reserve_write_off 10173 10173 0 0.00 0 20828 12 0 0
t$perf-norm:sp_supplier_invoice 5598 5598 0 0.00 0 5489 19 0 0
t$perf-norm:sp_supplier_order 5635 5635 0 0.00 0 1671 9 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 261 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 257 5 0 0
t$perf-norm:srv_make_invnt_saldo 543 543 0 0.00 0 5215 32 0 0
t$perf-norm:srv_make_money_saldo 820 820 0 0.00 0 6303 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 10 5 0 0
v_add_invoice_to_stock 10332 10032 300 2.90 0 9897 19 0 300
v_all_customers 38806 38806 0 0.00 0 23 1 0 0
v_all_suppliers 31038 31038 0 0.00 0 99 1 0 0
v_all_wares 42386 42386 0 0.00 0 65 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 4358 11 0 0
v_cancel_client_order 3502 3502 0 0.00 0 6348 8 0 0
v_cancel_customer_prepayment 1422 1422 0 0.00 0 6348 7 0 0
v_cancel_customer_reserve 1456 1456 0 0.00 0 2882 11 0 0
v_cancel_payment_to_supplier 888 888 0 0.00 0 3684 14 0 0
v_cancel_supplier_invoice 818 808 10 1.22 0 2664 17 0 10
v_cancel_supplier_order 842 842 0 0.00 0 1237 6 0 0
v_cancel_write_off 2136 2136 0 0.00 0 5717 18 0 0
v_max_id_clo_ord 5628 5628 0 0.00 0 2419 2 0 0
v_max_id_clo_res 1023 1023 0 0.00 0 6676 19 0 0
v_max_id_ord_sup 5596 5596 0 0.00 0 4775 4 0 0
v_max_non_paid_invoice 1617 1617 0 0.00 0 5756 6 0 0
v_max_non_paid_realizn 3773 3773 0 0.00 0 4775 6 0 0
v_min_id_clo_ord 5628 5628 0 0.00 0 5020 7 0 0
v_min_id_clo_res 1023 1023 0 0.00 0 1291 4 0 0
v_min_id_ord_sup 5596 5596 0 0.00 0 8781 7 0 0
v_min_non_paid_invoice 5914 5914 0 0.00 0 10415 18 0 0
v_min_non_paid_realizn 7292 7292 0 0.00 0 8474 22 0 0
v_our_firm 10618 10618 0 0.00 0 17 1 0 0
v_reserve_write_off 20342 20342 0 0.00 0 8665 6 0 0
x_lock_depdocs_on_canc_invoice 11875 11875 0 0.00 0 19348 116 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 3243 307 0 0
x_make_qstorno_1000_1200 5635 5635 0 0.00 2 42865 1047 0 0
x_make_qstorno_1200_2000 5598 5598 0 0.00 2 80303 2932 0 0
x_make_qstorno_2100_3300 462961 462961 0 0.00 0 36994 167 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 10-02-2023 01:41:27 to 10-02-2023 01:41:32.

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
23:08:45 3 8192000000 8800904176 8812855296 6483824 0.079 2 1 2 0 6583456 56848 42784
23:09:45 39094 8192000000 9912020432 10224672768 990988272 10.791 100 90 180 0 1021097184 6628192 23480720
23:11:30 21583 8192000000 9948406928 10270490624 1025685920 11.127 100 82 164 0 1055381136 8529136 21166080
23:12:58 20533 8192000000 9985225808 10321178624 1068804272 11.541 100 82 163 1 1098762112 9772640 20185200
23:14:26 11164 8192000000 10044714560 10393657344 1129479040 12.116 100 78 156 0 1158774640 10361104 18934496
23:15:40 4473 8192000000 10098880624 10458902528 1182708368 12.615 100 73 146 0 1210757392 11254528 16794496
23:16:46 3137 8192000000 10147845936 10517925888 1230767056 13.061 100 79 156 0 1261015392 13174960 17073376
23:17:52 1393 8192000000 10164077008 10540306432 1254023840 13.275 100 91 181 0 1287030560 13576160 19430560
23:18:55 1308 8192000000 10193205120 10576433152 1279699664 13.510 100 89 178 0 1313615440 14620512 19295264
23:19:58 967 8192000000 10209276976 10599657472 1297531824 13.673 100 75 149 3 1326978592 12752000 16694768
23:21:00 843 8192000000 10219103840 10609475584 1305306624 13.743 100 82 166 0 1333922400 10519520 18096256
23:22:01 2427 8192000000 10205288624 10616672256 1315357008 13.835 100 38 77 2 1331149200 7170208 8621984
23:23:06 5987 8192000000 10208695392 10625953792 1325778064 13.929 100 31 62 0 1338900688 5493728 7628896
23:24:13 10333 8192000000 10208810608 10631725056 1328629584 13.955 100 11 22 0 1336890560 5986320 2274656
23:25:24 39186 8192000000 10242042000 10652155904 1333519984 13.999 100 49 96 1 1354400560 9779568 11101008
23:27:04 3960 8192000000 10231872112 10652504064 1340527120 14.062 100 35 71 1 1355226400 7041824 7657456
23:28:08 16309 8192000000 10199686640 10620678144 1288946928 13.595 97 9 18 0 1296325920 5293952 2085040
23:29:28 51218 8192000000 10174536704 10590552064 1242077392 13.165 95 22 44 0 1252433328 4733984 5621952
23:31:23 5523 8192000000 10131410320 10533289984 1217988960 12.943 96 31 60 2 1229629872 4701696 6939216
23:32:31 7690 8192000000 10135804880 10530533376 1241323488 13.158 99 35 69 0 1255344752 5351984 8669280
23:33:41 12869 8192000000 10166283264 10553708544 1263238032 13.360 100 15 29 0 1271828944 5000112 3590800
23:34:56 24383 8192000000 10164473648 10575761408 1273768976 13.456 100 24 46 1 1284230384 5702432 4758976
23:36:23 5590 8192000000 10183327888 10591248384 1289666432 13.601 100 26 54 1 1301584048 6300864 5616752
23:37:31 11389 8192000000 10184672112 10598785024 1296057600 13.659 100 22 45 0 1306734048 5242128 5434320
23:38:43 84451 8192000000 10236653904 10646368256 1323925552 13.912 100 37 75 0 1336874576 5331824 7617200
23:41:13 15865 8192000000 10259408608 10667360256 1349856112 14.146 100 47 94 0 1370933888 10331616 10746160
23:42:34 25095 8192000000 10275966640 10692042752 1367280752 14.303 100 47 92 0 1392384032 14148896 10954384
23:44:05 10445 8192000000 10293078736 10708402176 1376206864 14.383 100 50 100 0 1403310576 15894608 11209104
23:45:18 4117 8192000000 10292621952 10712481792 1377706928 14.396 100 48 96 1 1407792896 18633744 11452224
23:46:28 16348 8192000000 10307897984 10725392384 1383640208 14.449 100 50 100 0 1415904784 20264480 12000096
23:47:52 14898 8192000000 10308102512 10736340992 1384068048 14.453 99 34 68 1 1416062256 23624304 8369904
23:49:13 17360 8192000000 10314397456 10732044288 1358840320 14.227 97 51 102 1 1399061440 28061344 12159776
23:50:34 9201 8192000000 10331081680 10750832640 1387811008 14.486 99 55 110 0 1431615456 30104864 13699584
23:51:51 2645 8192000000 10320738736 10726600704 1372897984 14.353 99 76 149 0 1425954624 35083856 17972784
23:52:58 3539 8192000000 10320638000 10732290048 1376383248 14.384 100 64 128 0 1429293664 36976848 15933568
23:54:07 1578 8192000000 10298694144 10698788864 1340438384 14.061 98 68 138 2 1400319120 43236448 16644288
23:55:12 5724 8192000000 10305436784 10718961664 1356647264 14.207 99 45 87 1 1409611248 41780640 11183344
23:56:24 12648 8192000000 10283768160 10702008320 1331207808 13.978 97 32 64 0 1379272704 39603776 8461120
23:57:40 13882 8192000000 10305069424 10725367808 1360045568 14.238 100 29 59 0 1413952000 45077328 8829104
23:59:04 9749 8192000000 10308634848 10740985856 1363510544 14.269 100 34 68 0 1418610384 46044992 9054848
00:00:33 7753 8192000000 10309366560 10729684992 1349012144 14.139 99 45 87 0 1406329344 44995152 12322048
00:01:51 17126 8192000000 10317206176 10749288448 1372838496 14.352 100 35 70 0 1422712912 40219408 9655008
00:03:48 19786 8192000000 10302993472 10742521856 1364330144 14.276 99 18 34 0 1399103248 30236064 4537040
00:05:13 28021 8192000000 10283233600 10727620608 1339600800 14.054 98 22 44 0 1373171424 28068672 5501952
00:07:18 21595 8192000000 10258511344 10698342400 1313408176 13.817 97 13 26 0 1335312864 18393280 3511408
00:08:41 84441 8192000000 10264338560 10695266304 1346011728 14.112 99 15 30 0 1365803888 16377648 3414512
00:11:07 17447 8192000000 10323644176 10748198912 1391154208 14.516 100 53 102 0 1417848192 14160464 12533520
00:12:50 79810 8192000000 10307824176 10758660096 1381257744 14.428 99 11 22 0 1406856688 23031072 2567872
00:15:37 29076 8192000000 10322822160 10758688768 1397979168 14.577 100 38 74 1 1422441104 14843984 9617952
00:17:17 58361 8192000000 10305243232 10779742208 1397161920 14.570 100 12 26 0 1418576416 18597856 2816640
00:19:21 58034 8192000000 10299248688 10754822144 1399654720 14.592 100 9 18 0 1411854784 9741696 2458368
00:21:19 41273 8192000000 10291442224 10752057344 1375473856 14.376 98 17 34 0 1390214576 10395024 4345696
00:23:01 46978 8192000000 10319405536 10765365248 1397861664 14.576 100 19 38 0 1411192784 8926848 4404272
00:24:49 49723 8192000000 10300267488 10762297344 1397042160 14.569 100 21 42 0 1415668800 13158224 5468416
00:26:50 5776 8192000000 10341169696 10778861568 1416665936 14.743 100 65 124 2 1442371440 10928016 14777488
00:27:56 66565 8192000000 10308669408 10779459584 1404981456 14.639 99 23 46 0 1429497344 18331648 6184240
00:31:25 109589 8192000000 10294523104 10758582272 1392042848 14.524 99 17 34 0 1404085568 8506048 3536672
00:34:21 73132 8192000000 10293500272 10757115904 1379908688 14.416 98 17 34 0 1395386608 11506784 3971136
00:36:35 65897 8192000000 10294821232 10762317824 1378210528 14.401 98 12 24 1 1391887488 10737536 2939424
00:38:43 49169 8192000000 10288463456 10748321792 1375751456 14.379 98 21 42 0 1390086576 10006944 4328176
00:40:34 72339 8192000000 10295601664 10748547072 1358086448 14.220 97 19 38 0 1371253056 9119712 4046896
00:42:57 20446 8192000000 10282716752 10722766848 1347212352 14.122 98 36 72 0 1363567984 8769312 7586320
00:44:18 71847 8192000000 10263786048 10716798976 1311842560 13.803 96 29 58 0 1334946368 16726832 6376976
00:46:34 44350 8192000000 10249214080 10706018304 1327332256 13.943 98 20 39 0 1341966128 10693248 3940624
00:48:18 37615 8192000000 10273739168 10716160000 1349704896 14.145 99 36 71 0 1369796784 12271872 7820016
00:50:00 71626 8192000000 10257015152 10710953984 1308322288 13.771 96 7 14 0 1321749072 11756224 1670560
00:52:18 91152 8192000000 10271851712 10724151296 1363437904 14.268 100 41 80 0 1385599200 13073808 9087488
00:55:01 24480 8192000000 10281047600 10724790272 1371198608 14.338 100 32 63 1 1387466896 9638960 6629328
00:56:25 52909 8192000000 10277751120 10738855936 1379176496 14.409 100 18 36 0 1392719456 9389440 4153520
00:58:19 61445 8192000000 10277398976 10721673216 1331023232 13.976 96 19 38 0 1351461728 15670464 4768032
01:00:25 32315 8192000000 10287295616 10738249728 1360412176 14.241 99 34 66 2 1382879632 13350592 9116864
01:03:00 53065 8192000000 10286797056 10735755264 1367054080 14.301 99 24 47 0 1388747152 16613456 5079616
01:05:03 49477 8192000000 10298348544 10746941440 1389154304 14.498 100 26 50 2 1405860032 10046672 6659056
01:06:57 24811 8192000000 10294263920 10749100032 1375869232 14.380 99 30 60 1 1399189152 16526608 6793312
01:08:31 28985 8192000000 10316975504 10763157504 1390563184 14.511 99 43 85 0 1411324352 11440560 9320608
01:10:00 54814 8192000000 10304710192 10757160960 1368726864 14.316 98 24 48 0 1388103248 14032144 5344240
01:12:07 76705 8192000000 10305121488 10750947328 1356882464 14.209 97 15 30 0 1369405888 9341008 3182416
01:14:24 61384 8192000000 10288648848 10740264960 1375848592 14.379 99 28 56 2 1392992432 10974432 6169408
01:16:37 38722 8192000000 10296147632 10739003392 1365015776 14.282 98 29 56 0 1383672608 12134032 6522800
01:18:16 45302 8192000000 10287052016 10758496256 1394690064 14.548 100 8 16 0 1406723520 10334464 1698992
01:20:02 66441 8192000000 10297040624 10759299072 1380786176 14.424 98 16 32 0 1393003424 8491088 3726160
01:22:09 91738 8192000000 10306981792 10766987264 1385270240 14.464 99 18 36 0 1403993776 14678768 4044768
01:25:00 44044 8192000000 10289356608 10740854784 1359164160 14.230 98 22 42 1 1374772080 10873328 4734592
01:26:56 60245 8192000000 10276958048 10726326272 1322545088 13.900 96 20 39 0 1342790896 15691728 4554080
01:28:57 74759 8192000000 10266162656 10715013120 1334220592 14.005 97 21 42 0 1352957280 13548592 5188096
01:31:12 37111 8192000000 10293042272 10734891008 1373410960 14.358 100 23 46 0 1390716864 11764656 5541248
01:32:54 63324 8192000000 10268426272 10710376448 1306176576 13.751 96 17 34 0 1324034752 14037440 3820736
01:35:00 96804 8192000000 10258463952 10696073216 1269812768 13.420 93 19 38 0 1288097328 14228928 4055632
01:37:38 90087 8192000000 9612898688 9957466112 747123328 8.357 55 13 26 0 761995920 11934816 2937776

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 10-02-2023 01:41:33 to 10-02-2023 01:41:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 150 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-02-2023 01:41:50 to 10-02-2023 01:41:52.

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          244932
MON$OLDEST_ACTIVE               245332
MON$OLDEST_SNAPSHOT             245332
MON$NEXT_TRANSACTION            245332
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                       2027680
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.33662 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33662 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33662 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33662 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 ---
--- end of diff output ---

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

10.02.23 01:41:53. 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-02-10 01:41:55 No severe PSQL-related problems occured
10.02.23 01:41:57. End of report.