Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.07.2022 22: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 15944492 kB
MemAvailable 30986752 kB
Buffers 0 kB
Cached 15745512 kB
SwapCached 2512 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 2022-07-29 22:38:20
DTS_END                         2022-07-29 22:38:20.3780
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/37262
TRN_ID                          238397
ATT_ID                          670
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: 9315.39 19210 1117847
customer order: creation 170.78 347 20493
customer order: refuse 14.75 384 1770
order to supplier: creation 45.15 1282 5418
order to supplier: removal 3.50 86301 420
invoice (draft): creation 45.23 3542 5428
invoice (draft): removal 53.51 716 6421
invoice accept: apply 41.48 17307 4977
invoice accept: cancel 48.91 804 5869
customer reserve: creation 3813.58 172 457629
customer reserve: removal 4787.36 49 574483
realization accept: apply 82.43 180 9891
realization accept: cancel 82.27 112 9872
payment from customer: creation 56.37 100 6764
payment from customer: removal 5.78 76 694
payment to supplier: creation 49.52 126 5942
payment to supplier: removal 3.32 90 398
service: total inventory turnovers 4.70 936 564
service: total monetary turnovers 6.09 26 731
service: aggregate perf. data 0.66 22175 79
service: refresh index statistics 0.03 249468 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-07-2022 22:39:26 to 29-07-2022 22:39:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3351
TEST_TIME 2 4419
TEST_TIME 3 3148
TEST_TIME 4 3551
TEST_TIME 5 3406
TEST_TIME 6 3773
TEST_TIME 7 4933
TEST_TIME 8 5307
TEST_TIME 9 5746
TEST_TIME 10 6373
TEST_TIME 11 6632
TEST_TIME 12 6702
TEST_TIME 13 6835
TEST_TIME 14 7006
TEST_TIME 15 7334
TEST_TIME 16 7274
TEST_TIME 17 8027
TEST_TIME 18 8679
TEST_TIME 19 8623
TEST_TIME 20 9022
TEST_TIME 21 8959
TEST_TIME 22 9221
TEST_TIME 23 9326
TEST_TIME 24 9302
TEST_TIME 25 9644
TEST_TIME 26 10152
TEST_TIME 27 10433
TEST_TIME 28 10283
TEST_TIME 29 10304
TEST_TIME 30 10627
TEST_TIME 31 10617
TEST_TIME 32 10657
TEST_TIME 33 10371
TEST_TIME 34 10135
TEST_TIME 35 10203
TEST_TIME 36 10060
TEST_TIME 37 9956
TEST_TIME 38 9991
TEST_TIME 39 9955
TEST_TIME 40 9953
TEST_TIME 41 9919
TEST_TIME 42 9795
TEST_TIME 43 9721
TEST_TIME 44 9671
TEST_TIME 45 9651
TEST_TIME 46 9567
TEST_TIME 47 9422
TEST_TIME 48 9594
TEST_TIME 49 9484
TEST_TIME 50 9453
TEST_TIME 51 9457
TEST_TIME 52 9446
TEST_TIME 53 9427
TEST_TIME 54 9521
TEST_TIME 55 9517
TEST_TIME 56 9658
TEST_TIME 57 9670
TEST_TIME 58 9686
TEST_TIME 59 9772
TEST_TIME 60 9684
TEST_TIME 61 9725
TEST_TIME 62 9739
TEST_TIME 63 9757
TEST_TIME 64 9838
TEST_TIME 65 9756
TEST_TIME 66 9705
TEST_TIME 67 9724
TEST_TIME 68 9619
TEST_TIME 69 9621
TEST_TIME 70 9587
TEST_TIME 71 9523
TEST_TIME 72 9463
TEST_TIME 73 9429
TEST_TIME 74 9475
TEST_TIME 75 9468
TEST_TIME 76 9509
TEST_TIME 77 9457
TEST_TIME 78 9501
TEST_TIME 79 9559
TEST_TIME 80 9598
TEST_TIME 81 9568
TEST_TIME 82 9639
TEST_TIME 83 9600
TEST_TIME 84 9644
TEST_TIME 85 9626
TEST_TIME 86 9700
TEST_TIME 87 9706
TEST_TIME 88 9728
TEST_TIME 89 9630
TEST_TIME 90 9599
TEST_TIME 91 9557
TEST_TIME 92 9542
TEST_TIME 93 9496
TEST_TIME 94 9426
TEST_TIME 95 9378
TEST_TIME 96 9462
TEST_TIME 97 9428
TEST_TIME 98 9467
TEST_TIME 99 9478
TEST_TIME 100 9494
TEST_TIME 101 9489
TEST_TIME 102 9457
TEST_TIME 103 9442
TEST_TIME 104 9536
TEST_TIME 105 9571
TEST_TIME 106 9551
TEST_TIME 107 9584
TEST_TIME 108 9603
TEST_TIME 109 9522
TEST_TIME 110 9463
TEST_TIME 111 9435
TEST_TIME 112 9448
TEST_TIME 113 9424
TEST_TIME 114 9388
TEST_TIME 115 9354
TEST_TIME 116 9361
TEST_TIME 117 9371
TEST_TIME 118 9388
TEST_TIME 119 9353
TEST_TIME 120 9317

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-07-2022 22:39:30 to 29-07-2022 22:39:33.

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 1541352 1541352 0 0.00 0 24460 6 0 0
doc_list_biud 1541352 1541352 0 0.00 0 48463 24 0 0
sp_add_invoice_to_stock 5083 4977 106 2.08 29 176914 17308 0 106
sp_cancel_adding_invoice 5869 5869 0 0.00 4 60790 804 0 0
sp_cancel_client_order 1770 1770 0 0.00 4 10911 385 0 0
sp_cancel_customer_reserve 574483 574483 0 0.00 0 48464 49 0 0
sp_cancel_pay_from_customer 694 694 0 0.00 2 6033 77 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 2 5270 90 0 0
sp_cancel_supplier_invoice 6424 6421 3 0.04 1 27661 717 0 3
sp_cancel_supplier_order 420 420 0 0.00 5 824859 86301 0 0
sp_cancel_write_off 9872 9872 0 0.00 1 8687 112 0 0
sp_client_order 20493 20493 0 0.00 3 23219 348 0 0
sp_customer_reserve 457629 457629 0 0.00 0 57650 173 0 0
sp_fill_shopping_cart 31333 31333 0 0.00 1 15732 37 0 0
sp_fill_shopping_cart_clo_res 457626 457626 0 0.00 0 26117 15 0 0
sp_get_clo_for_invoice 4974 4974 0 0.00 0 28431 304 0 0
sp_kill_cost_storno 16833 16833 0 0.00 0 9530 52 0 0
sp_kill_qstorno_ret_qs2qd 1165679 1165679 0 0.00 0 48461 27 0 0
sp_kill_qty_storno 613700 613700 0 0.00 0 48462 59 0 0
sp_lock_selected_doc 20679 20679 0 0.00 0 7123 6 0 0
sp_make_cost_storno 27571 27571 0 0.00 0 24457 57 0 0
sp_multiply_rows_for_pdistr 27571 27571 0 0.00 0 8976 28 0 0
sp_multiply_rows_for_qdistr 20493 20493 0 0.00 0 18934 166 0 0
sp_pay_from_customer 6764 6764 0 0.00 4 9440 100 0 0
sp_pay_to_supplier 5942 5942 0 0.00 4 17604 126 0 0
sp_payment_common 12706 12706 0 0.00 1 10026 24 0 0
sp_qd_handle_on_cancel_clo 1770 1770 0 0.00 0 10903 353 0 0
sp_qd_handle_on_invoice_upd_sts 10843 10843 0 0.00 0 28170 305 0 0
sp_qd_handle_on_reserve_upd_sts 9891 9891 0 0.00 0 8384 49 0 0
sp_reserve_write_off 9891 9891 0 0.00 6 24493 181 0 0
sp_supplier_invoice 5428 5428 0 0.00 6 98501 3542 0 0
sp_supplier_order 5418 5418 0 0.00 5 40830 1283 0 0
srv_aggregate_perf_data 79 79 0 0.00 2336 128846 22176 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1467 172004 47083 0 0
srv_find_qd_qs_mism 1070629 1070629 0 0.00 0 12188 5 0 0
srv_make_invnt_saldo 564 564 0 0.00 37 28591 937 0 0
srv_make_money_saldo 731 731 0 0.00 1 1832 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 109140 451466 249469 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4977 4977 0 0.00 0 10924 171 0 0
t$perf-norm:sp_cancel_adding_invoice 446 446 0 0.00 0 2279 81 0 0
t$perf-norm:sp_cancel_client_order 1770 1770 0 0.00 0 4517 13 0 0
t$perf-norm:sp_cancel_customer_reserve 683 683 0 0.00 0 1864 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 694 694 0 0.00 0 1442 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 261 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 823 14 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 17901 1514 0 0
t$perf-norm:sp_cancel_write_off 1020 1020 0 0.00 0 1337 5 0 0
t$perf-norm:sp_client_order 20493 20493 0 0.00 0 7232 15 0 0
t$perf-norm:sp_customer_reserve 995 995 0 0.00 0 4936 15 0 0
t$perf-norm:sp_pay_from_customer 6764 6764 0 0.00 0 7711 9 0 0
t$perf-norm:sp_pay_to_supplier 5942 5942 0 0.00 0 4181 8 0 0
t$perf-norm:sp_reserve_write_off 9891 9891 0 0.00 0 14984 10 0 0
t$perf-norm:sp_supplier_invoice 5428 5428 0 0.00 0 9391 18 0 0
t$perf-norm:sp_supplier_order 5418 5418 0 0.00 0 4695 15 0 0
t$perf-norm:srv_aggregate_perf_data 79 79 0 0.00 0 641 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 375 7 0 0
t$perf-norm:srv_make_invnt_saldo 564 564 0 0.00 0 778 20 0 0
t$perf-norm:srv_make_money_saldo 731 731 0 0.00 0 3752 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10160 9948 212 2.08 0 7602 20 0 212
v_all_customers 36798 36798 0 0.00 0 29 1 0 0
v_all_suppliers 30196 30196 0 0.00 0 31 1 0 0
v_all_wares 40986 40986 0 0.00 0 31 1 0 0
v_cancel_adding_invoice 892 892 0 0.00 0 3254 17 0 0
v_cancel_client_order 3540 3540 0 0.00 0 4796 7 0 0
v_cancel_customer_prepayment 1388 1388 0 0.00 0 1065 5 0 0
v_cancel_customer_reserve 1366 1366 0 0.00 0 3239 6 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 1435 6 0 0
v_cancel_supplier_invoice 772 766 6 0.77 0 2200 17 0 6
v_cancel_supplier_order 840 840 0 0.00 0 346 3 0 0
v_cancel_write_off 2040 2040 0 0.00 0 2258 13 0 0
v_max_id_clo_ord 5415 5415 0 0.00 0 1708 2 0 0
v_max_id_clo_res 995 995 0 0.00 0 3139 21 0 0
v_max_id_ord_sup 5425 5425 0 0.00 0 8173 6 0 0
v_max_non_paid_invoice 1684 1684 0 0.00 0 440 3 0 0
v_max_non_paid_realizn 3848 3848 0 0.00 0 1942 4 0 0
v_min_id_clo_ord 5415 5415 0 0.00 0 7191 10 0 0
v_min_id_clo_res 995 995 0 0.00 0 2651 11 0 0
v_min_id_ord_sup 5425 5425 0 0.00 0 4509 4 0 0
v_min_non_paid_invoice 5942 5942 0 0.00 0 8795 18 0 0
v_min_non_paid_realizn 6764 6764 0 0.00 0 5916 15 0 0
v_our_firm 10020 10020 0 0.00 0 20 1 0 0
v_reserve_write_off 19782 19782 0 0.00 0 10228 6 0 0
x_lock_depdocs_on_canc_invoice 11907 11907 0 0.00 0 17150 129 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 27476 427 0 0
x_make_qstorno_1000_1200 5418 5418 0 0.00 1 40778 1203 0 0
x_make_qstorno_1200_2000 5428 5428 0 0.00 3 98374 3412 0 0
x_make_qstorno_2100_3300 457629 457629 0 0.00 0 56831 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-07-2022 22:39:34 to 29-07-2022 22:39:38.

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
20:08:24 2 8192000000 8798458512 8809709568 4941152 0.060 2 1 2 0 5040752 56816 42784
20:09:25 21722 8192000000 9918156096 10227638272 999530976 10.874 100 82 165 0 1026276976 5731152 21014848
20:10:49 19344 8192000000 9958884592 10279424000 1039495680 11.260 100 94 188 0 1071538816 8384672 23658464
20:12:12 18744 8192000000 9981649504 10312609792 1062104624 11.477 100 80 160 0 1091827584 9920224 19802736
20:13:39 9142 8192000000 10021280480 10364866560 1109920640 11.932 100 76 152 0 1138164304 10741296 17502368
20:14:53 6718 8192000000 10067959632 10424242176 1153778752 12.345 100 79 158 0 1184729520 12754816 18195952
20:16:04 3127 8192000000 10128137264 10489446400 1205026832 12.823 100 87 175 0 1238668864 13839504 19802528
20:17:09 3770 8192000000 10150852880 10523770880 1240379008 13.150 100 82 164 0 1271961056 13398880 18183168
20:18:15 1390 8192000000 10176018480 10555826176 1268480176 13.408 100 84 166 0 1300705376 14451808 17773392
20:19:18 964 8192000000 10213521632 10595827712 1295427376 13.654 100 89 178 1 1329282048 14122832 19731840
20:20:20 43566 8192000000 10200904496 10603151360 1305538560 13.746 100 34 68 0 1320592160 7601280 7452320
20:22:04 3639 8192000000 10215965088 10620411904 1318237392 13.861 100 46 92 1 1337133120 9018688 9877040
20:23:09 9029 8192000000 10203152752 10621349888 1321778304 13.893 100 9 18 0 1328571968 4870080 1923584
20:24:23 8396 8192000000 10217685824 10637811712 1332296096 13.988 100 16 32 1 1341757472 6068880 3392496
20:25:33 39389 8192000000 10225751024 10646401024 1336396464 14.025 100 37 74 0 1350562464 5567696 8598304
20:27:14 8013 8192000000 10230874848 10647687168 1338270704 14.042 100 28 56 0 1354903440 9615040 7017696
20:28:22 11257 8192000000 10212593328 10633609216 1308186576 13.770 98 16 32 0 1317322848 5417984 3718288
20:29:34 57642 8192000000 10201864320 10594807808 1256241792 13.296 96 70 136 1 1278078304 5821456 16015056
20:31:32 5122 8192000000 10140949200 10534256640 1224284784 13.001 98 28 57 1 1237404592 6201776 6918032
20:32:38 5964 8192000000 10125689088 10525138944 1217862112 12.942 97 19 38 0 1228084368 5658320 4563936
20:33:44 10390 8192000000 10162478864 10574659584 1278998096 13.504 100 14 26 0 1287161360 5136512 3026752
20:34:55 44564 8192000000 10158949456 10576297984 1268248432 13.406 99 20 41 0 1278296128 5336224 4711472
20:36:41 8266 8192000000 10184254608 10599706624 1295103200 13.651 100 21 41 2 1305521440 5551424 4866816
20:37:51 10706 8192000000 10196223520 10610540544 1306599200 13.755 100 27 54 0 1319326656 5865168 6862288
20:39:10 9773 8192000000 10239056016 10649522176 1338061680 14.040 100 44 87 2 1356817760 8735408 10020672
20:40:25 29097 8192000000 10263074624 10683944960 1358356304 14.223 100 41 80 0 1377744864 9869904 9518656
20:43:11 33786 8192000000 10268468912 10697990144 1370244912 14.329 100 25 49 1 1391173312 14024960 6903440
20:44:55 23640 8192000000 10297916736 10724806656 1380167184 14.418 100 30 60 0 1404705760 16927792 7610784
20:46:31 3984 8192000000 10313684080 10736025600 1392040800 14.524 100 64 127 2 1425710784 18674352 14995632
20:47:38 4621 8192000000 10328217904 10747215872 1400799696 14.602 100 72 139 0 1438233504 20613840 16819968
20:48:47 1865 8192000000 10332601488 10753953792 1408135808 14.667 100 68 137 1 1442070032 17334944 16599280
20:49:54 5032 8192000000 10334306336 10761973760 1412921584 14.710 100 66 126 1 1446374736 18292480 15160672
20:51:04 3687 8192000000 10331772240 10762031104 1413489472 14.715 100 54 107 1 1443150272 16186384 13474416
20:52:12 3522 8192000000 10332202160 10745749504 1395738576 14.557 99 77 152 2 1437459056 24053312 17667168
20:53:23 3102 8192000000 10330161728 10744553472 1378922144 14.407 99 70 139 2 1425726752 29207760 17596848
20:54:30 9651 8192000000 10289661856 10703126528 1351037584 14.157 100 60 120 1 1397303568 30498784 15767200
20:55:48 1464 8192000000 10303813776 10703745024 1357035088 14.211 99 85 165 0 1410660176 33049920 20575168
20:56:53 13070 8192000000 10288683584 10714079232 1333997728 14.003 97 37 74 0 1377352048 33250304 10104016
20:58:10 6893 8192000000 10314892912 10735173632 1375921984 14.380 100 54 108 1 1424045616 34904176 13219456
20:59:24 27895 8192000000 10312079504 10740690944 1375759008 14.379 100 37 72 1 1420974288 35688016 9527264
21:00:57 15384 8192000000 10284630096 10720972800 1359843600 14.236 100 32 60 0 1397635024 29886992 7904432
21:02:24 20536 8192000000 10284876432 10713706496 1335801904 14.020 98 34 68 1 1378070944 33447632 8821408
21:03:45 29365 8192000000 10277846528 10714271744 1348648048 14.135 100 17 34 0 1389646880 36095264 4903568
21:05:17 43461 8192000000 10275268336 10713337856 1360213680 14.239 100 15 30 0 1377972576 13796336 3962560
21:07:01 32018 8192000000 10295541920 10726645760 1370341600 14.330 100 28 53 1 1395388768 19061840 5985328
21:08:34 73712 8192000000 10289869152 10739507200 1342784064 14.083 97 6 12 0 1360739504 16824624 1130816
21:10:52 52132 8192000000 10307886656 10737582080 1383427456 14.447 100 33 66 2 1401744000 11625040 6691504
21:13:21 82645 8192000000 10280183968 10740305920 1358045152 14.220 98 26 52 0 1377504352 13812128 5647072
21:15:46 64728 8192000000 10298676752 10757312512 1396458960 14.563 100 22 42 0 1410896976 10237856 4200160
21:18:10 17384 8192000000 10278115568 10736652288 1373321376 14.357 99 18 36 0 1387239536 9705840 4212320
21:19:34 50672 8192000000 10282068544 10737790976 1373666016 14.360 99 13 26 0 1384210352 7492560 3051776
21:21:34 71381 8192000000 10303875184 10765889536 1396821120 14.567 100 14 28 0 1417755952 17445824 3489008
21:23:51 93770 8192000000 10300894000 10772865024 1402777632 14.620 100 15 30 1 1422581488 15535520 4268336
21:26:25 50513 8192000000 10299541536 10779095040 1397492064 14.573 100 8 16 0 1418874160 19500512 1881584
21:28:16 48562 8192000000 10292401760 10757230592 1398238976 14.579 100 18 36 1 1414524384 12006400 4279008
21:30:14 93265 8192000000 10302553824 10764124160 1379303008 14.410 98 15 30 0 1400738832 17794784 3641040
21:32:48 63536 8192000000 10306711216 10772140032 1390344336 14.509 99 17 33 1 1414705168 20600912 3759920
21:34:53 20488 8192000000 10306291312 10751352832 1382223760 14.436 99 37 74 0 1400390736 9963072 8203904
21:36:13 66625 8192000000 10276513408 10731294720 1295401888 13.653 93 23 46 1 1314308848 13325248 5581712
21:38:20 9925 8192000000 10281827568 10720317440 1364740880 14.280 99 39 78 0 1379944624 6615088 8588656
21:39:33 79276 8192000000 10256961472 10692644864 1273442512 13.453 92 21 42 0 1292045024 14090192 4512320
21:41:56 28207 8192000000 10268101152 10706472960 1353478528 14.179 100 21 42 0 1372283632 14226384 4578720
21:43:25 90018 8192000000 10261742352 10710810624 1360202560 14.239 100 16 32 0 1375925760 12031840 3691360
21:45:57 44157 8192000000 10249452304 10708148224 1343846304 14.092 99 6 12 0 1362000272 17022752 1131216
21:47:44 75324 8192000000 10238942544 10694385664 1319222512 13.870 97 14 28 0 1334330304 11902720 3205072
21:50:00 104585 8192000000 10233420496 10685517824 1337190944 14.032 99 19 38 0 1353080688 11046768 4842976
21:54:20 62489 8192000000 10252704416 10704101376 1311161584 13.797 97 9 18 0 1326135216 12686240 2287392
21:56:26 57577 8192000000 10251301296 10693750784 1308652928 13.774 97 22 44 1 1334107744 20018432 5436384
21:58:26 78317 8192000000 10250262496 10689269760 1291136160 13.615 96 15 29 0 1312527152 17933696 3457296
22:01:11 41427 8192000000 10265127888 10704457728 1354530288 14.188 100 25 50 0 1373594608 13740576 5323744
22:02:59 16746 8192000000 10269918128 10704392192 1360920544 14.246 100 39 78 0 1376469808 6989440 8559824
22:04:22 95394 8192000000 10289561808 10740469760 1370545600 14.332 100 13 26 0 1390568880 17254720 2768560
22:07:03 123529 8192000000 10265153456 10718568448 1366296432 14.294 100 18 34 1 1381829904 11517024 4016448
22:10:11 42984 8192000000 10267867200 10724335616 1370065328 14.328 99 27 52 1 1385920800 9957120 5898352
22:13:25 66458 8192000000 10278265504 10733899776 1362169696 14.257 98 15 30 0 1378143456 12646592 3327168
22:15:32 46239 8192000000 10283862960 10734587904 1364462576 14.277 99 27 54 0 1383294240 12331712 6499952
22:17:19 101962 8192000000 10289430960 10757914624 1386554928 14.475 100 7 14 0 1399007440 10481904 1970608
22:20:01 172004 8192000000 10313815504 10783363072 1374380144 14.366 98 11 22 0 1392141712 15134288 2627280
22:23:53 47570 8192000000 10297376400 10743791616 1373144464 14.355 99 22 44 1 1387849280 9832656 4872160
22:25:47 82727 8192000000 10293463584 10754166784 1389300336 14.500 100 11 22 0 1404270160 12473504 2496320
22:28:10 85132 8192000000 10322838560 10774487040 1396916816 14.568 100 26 52 1 1425665600 22957936 5790848
22:30:36 36020 8192000000 10288445488 10763231232 1384171168 14.454 100 7 14 0 1403664752 18122384 1371200
22:34:26 74712 8192000000 10267362752 10733539328 1336158784 14.023 97 14 28 0 1353781664 14324528 3298352
22:36:41 25486 8192000000 10258441008 10707034112 1330748432 13.974 97 42 84 0 1352475600 11992560 9734608
22:38:12 12411 8192000000 8996129504 9199882240 169935872 2.032 13 5 10 0 172215952 1370016 910064

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-07-2022 22:39:40 to 29-07-2022 22:39:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 106 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 29-07-2022 22:39:56 to 29-07-2022 22:39:57.

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          238726
MON$OLDEST_ACTIVE               238727
MON$OLDEST_SNAPSHOT             238727
MON$NEXT_TRANSACTION            238727
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                       2033696
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.33613 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33613 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33613 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33613 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 ---
:468: 
:469: mscs-test-161.budzdorov.ru    Fri Jul 29 22:38:27 2022
:470:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:471: 
--- end of diff output ---

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

29.07.22 22:39:58. 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
2022-07-29 22:40:01 No severe PSQL-related problems occured
29.07.22 22:40:02. End of report.