Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.12.2022 19: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 14858388 kB
MemAvailable 30093304 kB
Buffers 0 kB
Cached 16868280 kB
SwapCached 3544 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-12-17 19:38:33
DTS_END                         2022-12-17 19:38:33.8880
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/41940
TRN_ID                          247112
ATT_ID                          737
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: 9568.88 16237 1148265
customer order: creation 181.21 356 21745
customer order: refuse 15.53 360 1864
order to supplier: creation 46.92 1221 5630
order to supplier: removal 3.69 95015 443
invoice (draft): creation 47.67 3265 5720
invoice (draft): removal 54.93 745 6591
invoice accept: apply 43.93 17539 5272
invoice accept: cancel 49.97 794 5996
customer reserve: creation 3985.65 178 478278
customer reserve: removal 4829.37 57 579524
realization accept: apply 88.18 199 10581
realization accept: cancel 86.42 111 10370
payment from customer: creation 62.18 111 7462
payment from customer: removal 5.99 65 719
payment to supplier: creation 51.23 158 6148
payment to supplier: removal 3.68 109 441
service: total inventory turnovers 4.83 1025 580
service: total monetary turnovers 6.58 26 790
service: aggregate perf. data 0.89 20011 107
service: refresh index statistics 0.03 183388 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-12-2022 19:41:04 to 17-12-2022 19:41:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3241
TEST_TIME 2 3983
TEST_TIME 3 3470
TEST_TIME 4 4638
TEST_TIME 5 4971
TEST_TIME 6 4683
TEST_TIME 7 5152
TEST_TIME 8 5678
TEST_TIME 9 5789
TEST_TIME 10 5793
TEST_TIME 11 5938
TEST_TIME 12 6303
TEST_TIME 13 6371
TEST_TIME 14 6335
TEST_TIME 15 6545
TEST_TIME 16 6565
TEST_TIME 17 6678
TEST_TIME 18 6874
TEST_TIME 19 7276
TEST_TIME 20 7867
TEST_TIME 21 8171
TEST_TIME 22 8417
TEST_TIME 23 8658
TEST_TIME 24 8672
TEST_TIME 25 9154
TEST_TIME 26 9329
TEST_TIME 27 9734
TEST_TIME 28 9832
TEST_TIME 29 10508
TEST_TIME 30 10644
TEST_TIME 31 10515
TEST_TIME 32 10345
TEST_TIME 33 10417
TEST_TIME 34 10502
TEST_TIME 35 10386
TEST_TIME 36 10289
TEST_TIME 37 10069
TEST_TIME 38 10047
TEST_TIME 39 10214
TEST_TIME 40 10090
TEST_TIME 41 9969
TEST_TIME 42 9957
TEST_TIME 43 9877
TEST_TIME 44 9863
TEST_TIME 45 9923
TEST_TIME 46 9918
TEST_TIME 47 9854
TEST_TIME 48 9845
TEST_TIME 49 9755
TEST_TIME 50 9695
TEST_TIME 51 9701
TEST_TIME 52 9626
TEST_TIME 53 9605
TEST_TIME 54 9515
TEST_TIME 55 9440
TEST_TIME 56 9350
TEST_TIME 57 9412
TEST_TIME 58 9388
TEST_TIME 59 9436
TEST_TIME 60 9570
TEST_TIME 61 9591
TEST_TIME 62 9645
TEST_TIME 63 9746
TEST_TIME 64 9768
TEST_TIME 65 9738
TEST_TIME 66 9785
TEST_TIME 67 9826
TEST_TIME 68 9876
TEST_TIME 69 9873
TEST_TIME 70 9907
TEST_TIME 71 9970
TEST_TIME 72 9891
TEST_TIME 73 9791
TEST_TIME 74 9804
TEST_TIME 75 9738
TEST_TIME 76 9684
TEST_TIME 77 9645
TEST_TIME 78 9650
TEST_TIME 79 9685
TEST_TIME 80 9632
TEST_TIME 81 9603
TEST_TIME 82 9635
TEST_TIME 83 9664
TEST_TIME 84 9611
TEST_TIME 85 9581
TEST_TIME 86 9622
TEST_TIME 87 9644
TEST_TIME 88 9602
TEST_TIME 89 9698
TEST_TIME 90 9666
TEST_TIME 91 9711
TEST_TIME 92 9769
TEST_TIME 93 9757
TEST_TIME 94 9758
TEST_TIME 95 9876
TEST_TIME 96 9832
TEST_TIME 97 9747
TEST_TIME 98 9723
TEST_TIME 99 9689
TEST_TIME 100 9668
TEST_TIME 101 9654
TEST_TIME 102 9599
TEST_TIME 103 9592
TEST_TIME 104 9584
TEST_TIME 105 9562
TEST_TIME 106 9578
TEST_TIME 107 9581
TEST_TIME 108 9570
TEST_TIME 109 9581
TEST_TIME 110 9588
TEST_TIME 111 9619
TEST_TIME 112 9586
TEST_TIME 113 9618
TEST_TIME 114 9655
TEST_TIME 115 9646
TEST_TIME 116 9637
TEST_TIME 117 9672
TEST_TIME 118 9671
TEST_TIME 119 9636
TEST_TIME 120 9569

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-12-2022 19:41:08 to 17-12-2022 19:41:11.

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 1592225 1592225 0 0.00 0 24801 7 0 0
doc_list_biud 1592223 1592223 0 0.00 0 20438 26 0 0
sp_add_invoice_to_stock 5399 5272 127 2.35 18 168992 17540 0 127
sp_cancel_adding_invoice 5996 5996 0 0.00 4 52043 794 0 0
sp_cancel_client_order 1864 1864 0 0.00 4 14854 361 0 0
sp_cancel_customer_reserve 579524 579524 0 0.00 0 20447 58 0 0
sp_cancel_pay_from_customer 719 719 0 0.00 3 2814 66 0 0
sp_cancel_pay_to_supplier 441 441 0 0.00 2 7251 110 0 0
sp_cancel_supplier_invoice 6605 6591 14 0.21 2 14231 746 0 14
sp_cancel_supplier_order 443 443 0 0.00 10 1151106 95015 0 0
sp_cancel_write_off 10370 10370 0 0.00 1 11463 111 0 0
sp_client_order 21745 21745 0 0.00 3 32144 357 0 0
sp_customer_reserve 478278 478278 0 0.00 0 58295 178 0 0
sp_fill_shopping_cart 33088 33088 0 0.00 1 14339 35 0 0
sp_fill_shopping_cart_clo_res 478277 478277 0 0.00 0 12829 15 0 0
sp_get_clo_for_invoice 5271 5271 0 0.00 0 20808 293 0 0
sp_kill_cost_storno 17526 17526 0 0.00 0 11204 53 0 0
sp_kill_qstorno_ret_qs2qd 1176452 1176452 0 0.00 0 20435 31 0 0
sp_kill_qty_storno 620640 620640 0 0.00 0 20438 67 0 0
sp_lock_selected_doc 21957 21957 0 0.00 0 9514 5 0 0
sp_make_cost_storno 29462 29462 0 0.00 0 15471 66 0 0
sp_multiply_rows_for_pdistr 29462 29462 0 0.00 0 9384 30 0 0
sp_multiply_rows_for_qdistr 21744 21744 0 0.00 0 32138 161 0 0
sp_pay_from_customer 7462 7462 0 0.00 3 13267 111 0 0
sp_pay_to_supplier 6148 6148 0 0.00 5 18846 159 0 0
sp_payment_common 13610 13610 0 0.00 2 8971 35 0 0
sp_qd_handle_on_cancel_clo 1864 1864 0 0.00 0 14847 323 0 0
sp_qd_handle_on_invoice_upd_sts 11267 11267 0 0.00 0 18955 310 0 0
sp_qd_handle_on_reserve_upd_sts 10581 10581 0 0.00 0 13706 62 0 0
sp_reserve_write_off 10581 10581 0 0.00 6 19628 200 0 0
sp_supplier_invoice 5720 5720 0 0.00 6 76666 3265 0 0
sp_supplier_order 5630 5630 0 0.00 5 27162 1222 0 0
srv_aggregate_perf_data 107 107 0 0.00 2534 275058 20012 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 1120 96425 38482 0 0
srv_find_qd_qs_mism 1099051 1099051 0 0.00 0 8655 6 0 0
srv_make_invnt_saldo 580 580 0 0.00 42 26595 1025 0 0
srv_make_money_saldo 790 790 0 0.00 0 2033 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 94430 280590 183389 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5272 5272 0 0.00 0 9270 158 0 0
t$perf-norm:sp_cancel_adding_invoice 451 451 0 0.00 0 2951 96 0 0
t$perf-norm:sp_cancel_client_order 1864 1864 0 0.00 0 4114 12 0 0
t$perf-norm:sp_cancel_customer_reserve 681 681 0 0.00 0 2052 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 719 719 0 0.00 0 5564 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 441 441 0 0.00 0 1064 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 466 7 0 0
t$perf-norm:sp_cancel_supplier_order 443 443 0 0.00 0 24551 1549 0 0
t$perf-norm:sp_cancel_write_off 1086 1086 0 0.00 0 1078 5 0 0
t$perf-norm:sp_client_order 21745 21745 0 0.00 0 5799 17 0 0
t$perf-norm:sp_customer_reserve 1114 1114 0 0.00 0 2494 9 0 0
t$perf-norm:sp_pay_from_customer 7462 7462 0 0.00 0 7269 11 0 0
t$perf-norm:sp_pay_to_supplier 6148 6148 0 0.00 0 12030 12 0 0
t$perf-norm:sp_reserve_write_off 10581 10581 0 0.00 0 11292 15 0 0
t$perf-norm:sp_supplier_invoice 5720 5720 0 0.00 0 5852 18 0 0
t$perf-norm:sp_supplier_order 5630 5630 0 0.00 0 2689 14 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 458 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 357 6 0 0
t$perf-norm:srv_make_invnt_saldo 580 580 0 0.00 0 1334 18 0 0
t$perf-norm:srv_make_money_saldo 790 790 0 0.00 0 4028 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 65 18 0 0
v_add_invoice_to_stock 10796 10542 254 2.35 0 4790 19 0 254
v_all_customers 39844 39844 0 0.00 0 116 1 0 0
v_all_suppliers 31774 31774 0 0.00 0 22 1 0 0
v_all_wares 43488 43488 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 902 902 0 0.00 0 3561 16 0 0
v_cancel_client_order 3728 3728 0 0.00 0 2588 5 0 0
v_cancel_customer_prepayment 1438 1438 0 0.00 0 2389 5 0 0
v_cancel_customer_reserve 1362 1362 0 0.00 0 2839 8 0 0
v_cancel_payment_to_supplier 882 882 0 0.00 0 1906 6 0 0
v_cancel_supplier_invoice 868 840 28 3.22 0 4429 19 0 28
v_cancel_supplier_order 886 886 0 0.00 0 1857 7 0 0
v_cancel_write_off 2172 2172 0 0.00 0 1603 16 0 0
v_max_id_clo_ord 5630 5630 0 0.00 0 4345 2 0 0
v_max_id_clo_res 1114 1114 0 0.00 0 2529 13 0 0
v_max_id_ord_sup 5714 5714 0 0.00 0 2667 3 0 0
v_max_non_paid_invoice 1605 1605 0 0.00 0 7301 8 0 0
v_max_non_paid_realizn 3873 3873 0 0.00 0 1464 3 0 0
v_min_id_clo_ord 5630 5630 0 0.00 0 14297 15 0 0
v_min_id_clo_res 1114 1114 0 0.00 0 7752 25 0 0
v_min_id_ord_sup 5714 5714 0 0.00 0 9321 5 0 0
v_min_non_paid_invoice 6148 6148 0 0.00 0 8072 25 0 0
v_min_non_paid_realizn 7462 7462 0 0.00 0 8965 27 0 0
v_our_firm 10822 10822 0 0.00 0 17 1 0 0
v_reserve_write_off 21162 21162 0 0.00 0 5720 7 0 0
x_lock_depdocs_on_canc_invoice 12167 12167 0 0.00 0 15375 126 0 0
x_lock_depdocs_on_canc_sup_ord 443 443 0 0.00 0 8508 299 0 0
x_make_qstorno_1000_1200 5630 5630 0 0.00 1 27138 1144 0 0
x_make_qstorno_1200_2000 5720 5720 0 0.00 2 75878 3145 0 0
x_make_qstorno_2100_3300 478278 478278 0 0.00 0 58293 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-12-2022 19:41:12 to 17-12-2022 19:41:16.

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
17:08:37 8 8192000000 8798879696 8810168320 5307904 0.064 2 1 2 0 5407504 56816 42784
17:09:38 24430 8192000000 9918744848 10232242176 1002835312 10.906 100 87 174 0 1031600640 6497488 22267840
17:11:10 24949 8192000000 9968887312 10293829632 1043137152 11.295 100 84 168 0 1073085008 8829008 21118848
17:12:51 10696 8192000000 9989689792 10325622784 1079670208 11.644 100 83 164 2 1109811216 10087168 20053840
17:14:07 11912 8192000000 10046301408 10394238976 1131625856 12.137 100 81 162 0 1162010464 11333904 19050704
17:15:25 6800 8192000000 10094406544 10452066304 1176705248 12.559 100 89 178 0 1210705584 12955024 21045312
17:16:34 2815 8192000000 10135866176 10503753728 1217870064 12.942 100 84 168 0 1250871360 14092576 18908720
17:17:41 1859 8192000000 10161757216 10538110976 1250929552 13.247 100 86 171 1 1283591984 14181408 18481024
17:18:44 1111 8192000000 10194435824 10573996032 1271288160 13.433 100 87 174 1 1304552432 14455024 18809248
17:19:46 798 8192000000 10214165424 10599391232 1297692912 13.674 100 91 183 0 1331859792 13248000 20918880
17:20:48 29289 8192000000 10197595408 10605416448 1302666896 13.719 100 32 62 0 1316572160 7032592 6872672
17:22:23 8844 8192000000 10204908048 10615439360 1312724576 13.811 100 30 59 0 1325744160 5913408 7106176
17:23:34 6433 8192000000 10219597360 10634067968 1322536656 13.900 100 27 54 0 1340497696 12166464 5794576
17:24:44 19193 8192000000 10221462544 10640760832 1331238432 13.978 100 29 57 2 1342919264 5436496 6244336
17:26:03 17816 8192000000 10226604272 10651750400 1337273008 14.033 100 21 41 0 1347955360 6364832 4317520
17:27:24 5976 8192000000 10229408864 10653093888 1339304000 14.051 100 16 33 0 1347983120 4828656 3850464
17:28:33 6149 8192000000 10208753456 10634522624 1315083584 13.832 99 20 40 1 1325365504 5679312 4602608
17:29:39 65460 8192000000 10142320512 10543996928 1192543040 12.707 92 21 40 2 1201900464 4339776 5017648
17:31:48 5899 8192000000 10107006928 10504044544 1186288048 12.649 95 23 44 1 1196961968 5550656 5123264
17:32:54 7220 8192000000 10149521936 10550296576 1254663216 13.281 100 32 64 0 1268879088 7106896 7108976
17:34:02 8815 8192000000 10172184032 10566799360 1255693488 13.291 98 23 44 0 1269512944 9117568 4701888
17:35:17 15514 8192000000 10167637696 10586488832 1285968832 13.567 100 22 44 1 1295979920 4847360 5163728
17:36:32 6099 8192000000 10198169568 10608082944 1302837696 13.721 100 26 50 0 1314614752 6398560 5378496
17:37:43 18382 8192000000 10204386592 10624606208 1314002640 13.822 100 11 22 0 1321735760 5161728 2571392
17:39:05 40001 8192000000 10257763968 10672168960 1352263488 14.168 100 57 109 2 1372805344 7379904 13161952
17:40:49 26536 8192000000 10255915152 10682900480 1361112112 14.247 100 23 46 0 1374573168 8454880 5006176
17:42:45 11445 8192000000 10283422912 10707587072 1379214272 14.410 100 48 94 0 1402710400 12560640 10935488
17:44:02 16526 8192000000 10294454560 10722369536 1390730512 14.512 100 45 90 0 1413776576 12587072 10458992
17:45:27 14082 8192000000 10306908480 10731425792 1390619840 14.511 100 57 114 0 1422367584 17803568 13944176
17:46:49 16048 8192000000 10314918336 10742738944 1395524432 14.555 100 50 100 3 1425299680 17843056 11932192
17:48:10 5217 8192000000 10346288176 10767859712 1413019248 14.711 100 59 112 1 1449092672 22940576 13132848
17:49:18 6919 8192000000 10334350576 10761904128 1409650224 14.681 100 47 93 0 1448127296 27101568 11375504
17:50:29 8863 8192000000 10342145232 10762186752 1397585632 14.573 98 59 113 3 1436757056 25070256 14101168
17:51:41 3044 8192000000 10350803536 10770235392 1406348448 14.651 100 73 143 2 1456833760 32236592 18248720
17:52:51 7052 8192000000 10352612384 10767421440 1407658672 14.663 100 69 138 1 1462764016 38095424 17009920
17:54:09 6170 8192000000 10336237504 10751680512 1393669936 14.539 100 59 118 0 1443693792 35019280 15004576
17:55:22 3772 8192000000 10357183216 10771169280 1406393616 14.652 100 75 150 0 1463317328 37774304 19149408
17:56:36 1118 8192000000 10349533648 10766999552 1385950384 14.470 99 69 138 2 1450387952 45965568 18472000
17:57:42 2778 8192000000 10357296352 10771218432 1401194320 14.606 100 71 142 0 1463036672 43674592 18167760
17:58:48 12683 8192000000 10341394224 10769395712 1383911216 14.452 99 40 80 0 1437669904 43030288 10728400
18:00:11 10201 8192000000 10317545072 10753425408 1382414144 14.438 100 37 73 1 1433750336 41887792 9448400
18:01:39 14181 8192000000 10332144240 10762539008 1382608032 14.440 99 30 60 1 1427401184 36859968 7933184
18:02:59 14527 8192000000 10313971504 10742226944 1349874560 14.146 98 34 66 2 1404180320 45120992 9184768
18:04:19 17527 8192000000 10288452624 10726121472 1344299056 14.096 98 27 53 0 1389813808 38720064 6794688
18:05:39 20733 8192000000 10298831200 10736418816 1355430320 14.196 99 18 36 0 1396695216 36144848 5120048
18:07:11 24739 8192000000 10283960192 10729676800 1357921360 14.219 100 29 58 0 1397303568 32257616 7124592
18:08:37 52175 8192000000 10251066336 10690547712 1267423872 13.398 92 7 14 0 1276887456 8378160 1085424
18:10:38 13834 8192000000 10298961632 10728329216 1374095808 14.364 100 43 83 0 1395205632 11947584 9162240
18:12:04 96284 8192000000 10283429520 10730422272 1334683536 14.009 97 11 20 0 1353081232 16071360 2326336
18:14:40 60058 8192000000 10301210144 10741862400 1376111168 14.382 100 39 78 0 1393518496 7363456 10043872
18:16:46 79253 8192000000 10296876176 10755776512 1390871488 14.514 100 37 74 0 1415954352 17001072 8081792
18:19:07 6748 8192000000 10293093600 10739990528 1372372080 14.348 99 28 55 3 1384076288 5528112 6176096
18:20:16 53599 8192000000 10292406144 10740269056 1365027872 14.282 98 10 20 0 1379964176 12413120 2523184
18:22:31 47061 8192000000 10302472048 10767732736 1391604720 14.520 100 22 44 0 1412775472 16109888 5060864
18:24:19 67221 8192000000 10288927312 10751500288 1380648208 14.422 99 11 22 0 1392453296 8976848 2828240
18:26:27 85079 8192000000 10300006352 10768297984 1386750544 14.477 99 15 30 1 1404531648 13827392 3953712
18:29:29 39369 8192000000 10292326960 10751631360 1392630976 14.529 100 19 38 2 1404938208 7563664 4743568
18:31:14 29949 8192000000 10287946768 10747994112 1367050192 14.301 98 20 40 0 1381472928 10246784 4175952
18:33:03 78214 8192000000 10286173888 10753155072 1375357184 14.375 99 11 21 0 1386603184 8984384 2261616
18:35:43 15840 8192000000 10283111344 10738061312 1369377856 14.321 99 37 75 0 1386967824 8838640 8751328
18:37:04 55937 8192000000 10293878784 10756022272 1353280816 14.177 97 15 30 0 1376740272 19852176 3607280
18:39:07 16653 8192000000 10275835664 10708348928 1288958560 13.595 94 39 78 1 1314601472 15773120 9869792
18:40:29 54896 8192000000 10281925824 10734366720 1320524192 13.881 96 16 32 0 1338353408 14563040 3266176
18:42:27 40383 8192000000 10286526144 10738659328 1364726848 14.280 99 19 36 1 1381366064 11720000 4919216
18:44:08 28341 8192000000 10292792320 10724925440 1350189328 14.149 99 47 94 0 1373177840 12578864 10409648
18:45:40 60658 8192000000 10278419296 10739933184 1361340960 14.249 99 25 48 0 1377856432 11850240 4665232
18:47:43 48192 8192000000 10304220208 10751012864 1370296240 14.330 99 22 44 0 1389766448 14712160 4758048
18:49:35 65351 8192000000 10291601552 10742661120 1356280848 14.204 99 15 30 1 1374981600 14720832 3979920
18:51:43 44612 8192000000 10272536688 10722721792 1348525360 14.134 98 43 84 0 1367738240 9247984 9964896
18:53:29 26519 8192000000 10275394880 10729631744 1362703616 14.262 100 19 37 0 1382770784 16348576 3718592
18:54:57 58542 8192000000 10281879536 10730110976 1368579680 14.314 100 12 23 1 1385883024 14033536 3269808
18:56:56 44814 8192000000 10266571792 10733805568 1354119792 14.185 99 12 22 0 1372363568 15583632 2660144
18:58:50 84540 8192000000 10267516560 10731061248 1342158896 14.077 98 15 30 0 1361385984 15320720 3906368
19:01:20 44811 8192000000 10269990480 10738515968 1349030416 14.139 98 10 20 0 1361290560 9653824 2606320
19:03:07 35023 8192000000 10275753376 10734702592 1363241760 14.266 99 25 50 0 1378377312 9249088 5886464
19:04:42 27020 8192000000 10278846192 10730946560 1351093120 14.157 98 33 65 0 1368507232 10653632 6760480
19:06:12 21586 8192000000 10291194704 10740748288 1374050016 14.363 100 19 38 0 1388443328 10328784 4064528
19:07:36 75761 8192000000 10315560752 10759172096 1385081312 14.462 100 24 47 1 1401963056 12023456 4858288
19:09:56 30204 8192000000 10326513584 10776637440 1400964368 14.604 100 26 50 0 1427661824 21480352 5217104
19:11:28 24675 8192000000 10313275504 10766094336 1378092528 14.399 98 26 51 0 1396594560 13182368 5319664
19:12:54 96425 8192000000 10300023504 10768486400 1360845792 14.245 97 7 14 1 1377818864 15611488 1361584
19:15:57 63164 8192000000 10315342640 10777452544 1399136704 14.587 100 27 54 0 1422005792 16954944 5914144
19:18:22 34182 8192000000 10286769456 10748895232 1373110688 14.355 98 12 23 0 1385244816 10259952 1874176
19:19:59 73535 8192000000 10305262176 10777292800 1402387872 14.616 100 8 16 0 1420097520 15951600 1758048
19:22:16 57349 8192000000 10298277248 10766069760 1367511360 14.305 98 11 22 0 1391364672 21418704 2434608
19:24:14 26370 8192000000 10275286352 10738171904 1338409232 14.043 97 12 24 0 1359217280 18184320 2623728
19:25:48 48839 8192000000 10281623648 10732621824 1347844064 14.128 98 12 24 0 1358630496 8356736 2429696
19:27:38 51024 8192000000 10269315312 10725056512 1331003632 13.976 97 22 44 0 1348861712 12711152 5146928
19:29:30 55458 8192000000 10268821104 10727329792 1314788000 13.829 96 18 36 0 1331779648 12836608 4155040
19:31:32 78427 8192000000 10283832416 10738479104 1345569328 14.108 98 17 34 0 1364827712 15689312 3569072
19:33:51 44522 8192000000 10265837456 10708295680 1285874912 13.567 94 34 68 0 1307132672 13845232 7412528
19:35:54 79343 8192000000 10279540720 10718941184 1264921072 13.375 93 34 68 0 1287498304 14979760 7597472
19:38:15 49175 8192000000 9455268912 9773928448 599066576 6.814 45 12 24 0 609438112 7903424 2468112

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 17-12-2022 19:41:18 to 17-12-2022 19:41:34.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 127 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-12-2022 19:41:35 to 17-12-2022 19:41:37.

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          247210
MON$OLDEST_ACTIVE               248526
MON$OLDEST_SNAPSHOT             248526
MON$NEXT_TRANSACTION            248526
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                       2049952
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.33648 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33648 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33648 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33648 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'

17.12.22 19:41:38. 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-12-17 19:41:40 No severe PSQL-related problems occured
17.12.22 19:41:42. End of report.