Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.07.2022 16:38
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 15979504 kB
MemAvailable 31058936 kB
Buffers 0 kB
Cached 15722712 kB
SwapCached 2500 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-20 16:38:27
DTS_END                         2022-07-20 16:38:27.3390
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/35566
TRN_ID                          243326
ATT_ID                          730
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: 9631.68 19113 1155802
customer order: creation 175.56 332 21067
customer order: refuse 14.96 303 1795
order to supplier: creation 47.93 1218 5751
order to supplier: removal 3.55 91283 426
invoice (draft): creation 47.88 2810 5745
invoice (draft): removal 55.12 723 6614
invoice accept: apply 43.46 16109 5215
invoice accept: cancel 49.09 710 5891
customer reserve: creation 4046.67 158 485600
customer reserve: removal 4842.23 52 581067
realization accept: apply 84.75 174 10170
realization accept: cancel 88.19 103 10583
payment from customer: creation 59.83 131 7180
payment from customer: removal 6.03 84 724
payment to supplier: creation 50.33 134 6040
payment to supplier: removal 3.68 51 441
service: total inventory turnovers 4.76 1178 571
service: total monetary turnovers 6.80 25 816
service: aggregate perf. data 0.85 19305 102
service: refresh index statistics 0.03 247384 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-07-2022 16:39:18 to 20-07-2022 16:39:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3849
TEST_TIME 2 3681
TEST_TIME 3 3319
TEST_TIME 4 3290
TEST_TIME 5 4178
TEST_TIME 6 4290
TEST_TIME 7 4637
TEST_TIME 8 5340
TEST_TIME 9 5859
TEST_TIME 10 5779
TEST_TIME 11 5956
TEST_TIME 12 6355
TEST_TIME 13 6321
TEST_TIME 14 6787
TEST_TIME 15 6775
TEST_TIME 16 6799
TEST_TIME 17 7253
TEST_TIME 18 7293
TEST_TIME 19 7841
TEST_TIME 20 8070
TEST_TIME 21 8502
TEST_TIME 22 8399
TEST_TIME 23 8618
TEST_TIME 24 8693
TEST_TIME 25 9108
TEST_TIME 26 9326
TEST_TIME 27 10434
TEST_TIME 28 10687
TEST_TIME 29 10661
TEST_TIME 30 10474
TEST_TIME 31 10415
TEST_TIME 32 10322
TEST_TIME 33 10374
TEST_TIME 34 10325
TEST_TIME 35 10258
TEST_TIME 36 10332
TEST_TIME 37 10257
TEST_TIME 38 10240
TEST_TIME 39 10166
TEST_TIME 40 10000
TEST_TIME 41 10004
TEST_TIME 42 9888
TEST_TIME 43 9828
TEST_TIME 44 9802
TEST_TIME 45 9732
TEST_TIME 46 9787
TEST_TIME 47 9702
TEST_TIME 48 9791
TEST_TIME 49 9754
TEST_TIME 50 9692
TEST_TIME 51 9822
TEST_TIME 52 9737
TEST_TIME 53 9678
TEST_TIME 54 9641
TEST_TIME 55 9562
TEST_TIME 56 9488
TEST_TIME 57 9446
TEST_TIME 58 9396
TEST_TIME 59 9472
TEST_TIME 60 9448
TEST_TIME 61 9418
TEST_TIME 62 9502
TEST_TIME 63 9518
TEST_TIME 64 9680
TEST_TIME 65 9659
TEST_TIME 66 9663
TEST_TIME 67 9655
TEST_TIME 68 9709
TEST_TIME 69 9712
TEST_TIME 70 9749
TEST_TIME 71 9755
TEST_TIME 72 9874
TEST_TIME 73 9895
TEST_TIME 74 9840
TEST_TIME 75 9729
TEST_TIME 76 9782
TEST_TIME 77 9723
TEST_TIME 78 9691
TEST_TIME 79 9674
TEST_TIME 80 9652
TEST_TIME 81 9601
TEST_TIME 82 9563
TEST_TIME 83 9542
TEST_TIME 84 9501
TEST_TIME 85 9475
TEST_TIME 86 9529
TEST_TIME 87 9512
TEST_TIME 88 9533
TEST_TIME 89 9502
TEST_TIME 90 9483
TEST_TIME 91 9555
TEST_TIME 92 9642
TEST_TIME 93 9667
TEST_TIME 94 9704
TEST_TIME 95 9665
TEST_TIME 96 9726
TEST_TIME 97 9752
TEST_TIME 98 9734
TEST_TIME 99 9804
TEST_TIME 100 9784
TEST_TIME 101 9785
TEST_TIME 102 9717
TEST_TIME 103 9719
TEST_TIME 104 9724
TEST_TIME 105 9671
TEST_TIME 106 9677
TEST_TIME 107 9678
TEST_TIME 108 9658
TEST_TIME 109 9652
TEST_TIME 110 9606
TEST_TIME 111 9548
TEST_TIME 112 9571
TEST_TIME 113 9577
TEST_TIME 114 9553
TEST_TIME 115 9599
TEST_TIME 116 9596
TEST_TIME 117 9632
TEST_TIME 118 9672
TEST_TIME 119 9661
TEST_TIME 120 9636

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-07-2022 16:39:21 to 20-07-2022 16:39:24.

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 1605726 1605726 0 0.00 0 24480 5 0 0
doc_list_biud 1605725 1605725 0 0.00 0 30573 24 0 0
sp_add_invoice_to_stock 5282 5215 67 1.26 24 189427 16109 0 67
sp_cancel_adding_invoice 5891 5891 0 0.00 3 50648 710 0 0
sp_cancel_client_order 1795 1795 0 0.00 4 10119 303 0 0
sp_cancel_customer_reserve 581067 581067 0 0.00 0 16627 52 0 0
sp_cancel_pay_from_customer 724 724 0 0.00 2 9962 84 0 0
sp_cancel_pay_to_supplier 441 441 0 0.00 2 1088 52 0 0
sp_cancel_supplier_invoice 6620 6614 6 0.09 1 30579 724 0 6
sp_cancel_supplier_order 426 426 0 0.00 5 1039699 91284 0 0
sp_cancel_write_off 10583 10583 0 0.00 1 20412 104 0 0
sp_client_order 21067 21067 0 0.00 3 21087 333 0 0
sp_customer_reserve 485600 485600 0 0.00 0 48782 159 0 0
sp_fill_shopping_cart 32551 32551 0 0.00 1 10343 32 0 0
sp_fill_shopping_cart_clo_res 485591 485591 0 0.00 0 18444 13 0 0
sp_get_clo_for_invoice 5206 5206 0 0.00 0 19504 247 0 0
sp_kill_cost_storno 17639 17639 0 0.00 0 9957 54 0 0
sp_kill_qstorno_ret_qs2qd 1179757 1179757 0 0.00 0 30571 28 0 0
sp_kill_qty_storno 621752 621752 0 0.00 0 30572 61 0 0
sp_lock_selected_doc 21336 21336 0 0.00 0 10870 6 0 0
sp_make_cost_storno 28596 28596 0 0.00 0 22501 54 0 0
sp_multiply_rows_for_pdistr 28596 28596 0 0.00 0 10051 31 0 0
sp_multiply_rows_for_qdistr 21067 21067 0 0.00 0 19618 140 0 0
sp_pay_from_customer 7180 7180 0 0.00 3 11304 131 0 0
sp_pay_to_supplier 6040 6040 0 0.00 4 17088 134 0 0
sp_payment_common 13220 13220 0 0.00 1 16921 38 0 0
sp_qd_handle_on_cancel_clo 1795 1795 0 0.00 0 10098 279 0 0
sp_qd_handle_on_invoice_upd_sts 11097 11097 0 0.00 0 29016 282 0 0
sp_qd_handle_on_reserve_upd_sts 10170 10170 0 0.00 0 17125 55 0 0
sp_reserve_write_off 10170 10170 0 0.00 6 19782 175 0 0
sp_supplier_invoice 5745 5745 0 0.00 6 99376 2811 0 0
sp_supplier_order 5751 5751 0 0.00 4 66689 1218 0 0
srv_aggregate_perf_data 102 102 0 0.00 2769 165459 19305 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2623 149154 48212 0 0
srv_find_qd_qs_mism 1106553 1106553 0 0.00 0 24478 4 0 0
srv_make_invnt_saldo 571 571 0 0.00 30 45844 1178 0 0
srv_make_money_saldo 816 816 0 0.00 0 2465 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 185997 302386 247385 0 0
t$perf-abend:sp_add_invoice_to_stock 67 67 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5215 5215 0 0.00 0 8106 152 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 0 3931 75 0 0
t$perf-norm:sp_cancel_client_order 1795 1795 0 0.00 0 8462 24 0 0
t$perf-norm:sp_cancel_customer_reserve 708 708 0 0.00 0 5296 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 724 724 0 0.00 0 1133 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 441 441 0 0.00 0 755 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 430 430 0 0.00 0 1077 19 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 26291 1491 0 0
t$perf-norm:sp_cancel_write_off 1032 1032 0 0.00 0 2430 8 0 0
t$perf-norm:sp_client_order 21067 21067 0 0.00 0 6172 15 0 0
t$perf-norm:sp_customer_reserve 1068 1068 0 0.00 0 892 5 0 0
t$perf-norm:sp_pay_from_customer 7180 7180 0 0.00 0 6991 13 0 0
t$perf-norm:sp_pay_to_supplier 6040 6040 0 0.00 0 3877 10 0 0
t$perf-norm:sp_reserve_write_off 10170 10170 0 0.00 0 6087 11 0 0
t$perf-norm:sp_supplier_invoice 5745 5745 0 0.00 0 6330 16 0 0
t$perf-norm:sp_supplier_order 5751 5751 0 0.00 0 6836 18 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 327 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 30 1 0 0
t$perf-norm:srv_make_invnt_saldo 571 571 0 0.00 0 6419 33 0 0
t$perf-norm:srv_make_money_saldo 816 816 0 0.00 0 1004 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 11 4 0 0
v_add_invoice_to_stock 10540 10406 134 1.27 0 4765 15 0 134
v_all_customers 38186 38186 0 0.00 0 101 0 0 0
v_all_suppliers 31988 31988 0 0.00 0 99 1 0 0
v_all_wares 42134 42134 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 587 7 0 0
v_cancel_client_order 3590 3590 0 0.00 0 1690 4 0 0
v_cancel_customer_prepayment 1448 1448 0 0.00 0 2028 6 0 0
v_cancel_customer_reserve 1416 1416 0 0.00 0 3073 5 0 0
v_cancel_payment_to_supplier 882 882 0 0.00 0 1071 5 0 0
v_cancel_supplier_invoice 872 860 12 1.37 0 1700 17 0 12
v_cancel_supplier_order 852 852 0 0.00 0 2178 7 0 0
v_cancel_write_off 2064 2064 0 0.00 0 4588 16 0 0
v_max_id_clo_ord 5747 5747 0 0.00 0 382 1 0 0
v_max_id_clo_res 1068 1068 0 0.00 0 5047 25 0 0
v_max_id_ord_sup 5736 5736 0 0.00 0 4675 4 0 0
v_max_non_paid_invoice 1529 1529 0 0.00 0 4944 6 0 0
v_max_non_paid_realizn 3881 3881 0 0.00 0 4910 4 0 0
v_min_id_clo_ord 5747 5747 0 0.00 0 1404 4 0 0
v_min_id_clo_res 1068 1068 0 0.00 0 905 3 0 0
v_min_id_ord_sup 5736 5736 0 0.00 0 5535 4 0 0
v_min_non_paid_invoice 6040 6040 0 0.00 0 16917 29 0 0
v_min_non_paid_realizn 7180 7180 0 0.00 0 11286 33 0 0
v_our_firm 10546 10546 0 0.00 0 100 1 0 0
v_reserve_write_off 20340 20340 0 0.00 0 3477 5 0 0
x_lock_depdocs_on_canc_invoice 12075 12075 0 0.00 0 20549 112 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 7661 325 0 0
x_make_qstorno_1000_1200 5751 5751 0 0.00 1 66669 1152 0 0
x_make_qstorno_1200_2000 5745 5745 0 0.00 3 99345 2695 0 0
x_make_qstorno_2100_3300 485600 485600 0 0.00 0 43221 144 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-07-2022 16:39:25 to 20-07-2022 16:39:29.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:08:31 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:32 21803 8192000000 9888532672 10196156416 973162976 10.618 100 90 180 0 1002756480 5604576 23988928
14:10:58 21083 8192000000 9943769888 10263916544 1025725792 11.127 100 87 174 0 1056151408 7966224 22459392
14:12:26 13293 8192000000 9983549616 10312441856 1060447792 11.461 100 88 176 0 1092271184 9701472 22121920
14:13:45 9171 8192000000 10027380128 10374418432 1116724032 11.996 100 72 142 0 1143873520 10635840 16513648
14:14:57 6390 8192000000 10083270640 10441609216 1172082288 12.516 100 78 156 0 1203454560 13399984 17972288
14:16:13 4524 8192000000 10118089456 10489548800 1212305312 12.890 100 70 140 0 1243360048 15765632 15289104
14:17:19 3695 8192000000 10145546640 10520711168 1234850128 13.099 100 84 169 0 1268143184 14320592 18972464
14:18:24 2679 8192000000 10180717952 10562772992 1267928576 13.403 100 83 166 0 1299243696 12972192 18342928
14:19:28 1984 8192000000 10206606960 10588938240 1294751760 13.647 100 90 175 2 1326932928 12627008 19554160
14:20:33 12561 8192000000 10205100256 10600763392 1303606672 13.728 100 65 125 2 1326725568 9548832 13570064
14:21:47 9505 8192000000 10211237776 10608734208 1308795616 13.775 100 49 93 1 1325581088 7140816 9644656
14:22:57 3823 8192000000 10205456112 10618073088 1315389008 13.835 100 28 54 2 1327808640 6062624 6357008
14:24:07 6349 8192000000 10212954320 10630500352 1323939184 13.912 100 21 42 0 1334361856 5897696 4524976
14:25:23 13505 8192000000 10213215632 10636922880 1327514560 13.945 100 14 28 0 1336316304 5653328 3148416
14:26:38 31402 8192000000 10242627904 10647920640 1329122320 13.959 100 55 108 0 1352778112 9726096 13929696
14:28:10 7680 8192000000 10214653328 10631155712 1308451440 13.772 99 29 56 1 1321813392 6296144 7065808
14:29:18 8548 8192000000 10172393632 10585051136 1242264592 13.167 96 20 41 0 1256528512 9666400 4597520
14:30:28 51231 8192000000 10154665392 10539917312 1225823216 13.015 98 47 94 1 1241582880 4664144 11095520
14:32:21 4672 8192000000 10145020896 10540126208 1249350320 13.232 100 41 84 0 1266029856 6981248 9698288
14:33:28 6869 8192000000 10149349120 10553450496 1250169952 13.240 99 16 32 0 1259194000 5261584 3762464
14:34:39 62390 8192000000 10187088336 10590470144 1282287024 13.534 100 37 69 2 1296005072 4883136 8834912
14:36:41 9070 8192000000 10200744832 10613088256 1304299344 13.734 100 19 38 0 1313895952 5515584 4081024
14:37:51 8991 8192000000 10200411104 10617389056 1308400320 13.772 100 15 30 1 1317459968 5170208 3889440
14:39:12 23585 8192000000 10242506864 10654801920 1342750400 14.082 100 38 75 0 1356880448 6338720 7791328
14:40:44 23377 8192000000 10267993744 10684993536 1360863968 14.245 100 50 102 0 1385385696 13256432 11265296
14:42:21 52810 8192000000 10279180032 10703769600 1370715856 14.333 100 36 72 0 1397017776 17785968 8515952
14:44:24 7868 8192000000 10301996800 10723033088 1383376528 14.447 100 51 102 0 1411547584 16640416 11530640
14:45:35 3479 8192000000 10310248000 10732359680 1392445824 14.528 100 52 104 0 1422313136 17743824 12123488
14:46:45 8910 8192000000 10328330000 10753740800 1401546336 14.609 100 45 90 0 1436704832 23683536 11474960
14:47:59 6799 8192000000 10336692000 10763124736 1403777520 14.629 99 53 106 0 1438909760 22823120 12309120
14:49:11 5921 8192000000 10351751312 10775388160 1414074784 14.720 100 67 134 1 1457802544 27295888 16431872
14:50:21 8765 8192000000 10339903152 10764419072 1398085456 14.578 99 57 113 1 1438722544 26446896 14190192
14:51:35 3741 8192000000 10355034320 10774401024 1415124432 14.729 100 80 157 1 1463511440 29651008 18736000
14:52:41 6598 8192000000 10348960944 10768171008 1402870320 14.621 99 63 125 0 1448924400 30253120 15800960
14:53:57 3215 8192000000 10348060624 10768269312 1403864848 14.629 100 55 110 1 1451086976 33091424 14130704
14:55:05 2621 8192000000 10348559040 10765234176 1387127632 14.480 99 77 152 0 1447090112 40344592 19617888
14:56:13 5813 8192000000 10358031184 10771873792 1401137872 14.605 100 72 143 1 1466754992 47331408 18285712
14:57:26 10719 8192000000 10325803024 10753540096 1375974464 14.381 100 43 86 1 1439153952 50567952 12611536
14:59:05 14355 8192000000 10302346048 10728103936 1352844960 14.173 98 37 74 0 1407959264 44811040 10303264
15:00:41 11559 8192000000 10325588592 10749571072 1362053920 14.256 98 34 67 0 1411077744 39692320 9331504
15:01:57 21783 8192000000 10330874000 10763108352 1380027408 14.417 100 30 58 0 1435876864 47966656 7882800
15:03:26 10947 8192000000 10303943120 10738335744 1349918080 14.147 99 28 56 0 1405757920 48138496 7701344
15:04:40 27347 8192000000 10280762352 10725945344 1340957776 14.066 99 17 34 0 1388500512 42492832 5049904
15:06:10 44057 8192000000 10247987184 10692624384 1319473424 13.872 98 7 14 0 1342151760 21172256 1506080
15:08:31 62538 8192000000 10252195680 10691350528 1327852464 13.948 98 20 40 1 1339017360 6949952 4214944
15:10:37 57887 8192000000 10266871312 10711298048 1341420016 14.070 99 16 32 0 1356059872 11039120 3600736
15:12:35 58958 8192000000 10283440736 10720976896 1358762528 14.226 99 16 31 2 1376631760 14372880 3496352
15:14:40 55621 8192000000 10310626320 10751897600 1386591424 14.475 100 14 28 0 1401526704 12201504 2733776
15:16:36 117152 8192000000 10306051760 10763993088 1395306320 14.553 100 22 45 1 1418332624 18429936 4596368
15:19:36 27174 8192000000 10297974704 10742489088 1368403664 14.313 98 39 76 2 1387142192 10012272 8726256
15:22:37 25164 8192000000 10312337808 10761093120 1402504032 14.617 100 23 44 0 1416536384 8830768 5201584
15:24:08 28929 8192000000 10319128752 10778804224 1409336928 14.678 100 38 76 0 1431085968 12196432 9552608
15:25:44 84459 8192000000 10302452704 10771644416 1370930240 14.335 97 18 36 0 1388652656 13528592 4193824
15:28:11 58447 8192000000 10309316992 10761310208 1385120384 14.462 99 23 46 1 1404726144 14335632 5270128
15:30:16 47223 8192000000 10292420896 10764279808 1393166576 14.534 100 8 16 0 1407072576 12129488 1776512
15:32:03 62251 8192000000 10300240096 10765660160 1371093744 14.337 98 7 14 0 1382180704 9645504 1441456
15:34:06 48895 8192000000 10298359728 10755862528 1374981440 14.372 98 9 18 0 1386722880 9750896 1990544
15:35:55 49970 8192000000 10285965424 10754764800 1369669344 14.324 98 5 10 0 1382586256 11671360 1245552
15:37:58 64456 8192000000 10278222096 10738667520 1312717504 13.811 94 13 26 0 1323583408 8105520 2760384
15:40:03 49174 8192000000 10290829776 10746187776 1387411904 14.483 100 16 32 0 1404399856 13730000 3257952
15:41:53 57852 8192000000 10244656544 10703048704 1310869456 13.794 97 11 22 0 1326932720 13798992 2264272
15:43:53 46647 8192000000 10287029664 10731917312 1355663984 14.198 99 28 56 0 1376438064 14545088 6228992
15:45:41 37762 8192000000 10264945168 10708979712 1326724416 13.938 98 27 54 0 1347078112 14362016 5991680
15:47:22 95228 8192000000 10255811552 10691129344 1265113872 13.377 94 18 36 0 1282035136 12819232 4102032
15:50:03 108327 8192000000 10245418384 10684002304 1275674416 13.473 95 9 18 0 1296240336 18187456 2378464
15:52:57 149154 8192000000 10256449920 10718453760 1339302208 14.051 99 29 56 0 1359953232 14285328 6365696
15:56:27 53255 8192000000 10249808928 10705129472 1345625568 14.108 99 22 44 0 1358969184 8929760 4413856
15:58:29 56989 8192000000 10260201408 10720964608 1362942816 14.264 100 17 35 0 1377618224 10519680 4155728
16:00:50 77370 8192000000 10265365472 10724175872 1360617856 14.243 99 10 20 1 1378642656 15714656 2310144
16:03:11 66954 8192000000 10300007232 10763309056 1379875536 14.415 100 17 34 0 1406747936 22644896 4227504
16:05:23 56141 8192000000 10267181088 10740129792 1335812944 14.020 97 13 26 1 1356593424 17768928 3011552
16:07:26 83540 8192000000 10303894880 10762043392 1388777632 14.495 100 21 42 0 1413113376 19500880 4834864
16:10:25 99904 8192000000 10310063184 10761904128 1376828096 14.388 99 18 35 1 1398798768 18360912 3609760
16:13:14 54582 8192000000 10317251520 10762690560 1379836368 14.415 99 29 56 1 1394198960 8012784 6349808
16:15:17 67733 8192000000 10304854688 10758549504 1383121472 14.444 99 16 32 0 1403429968 16779168 3529328
16:17:25 81396 8192000000 10311943696 10771226624 1390698480 14.512 99 15 30 0 1409149888 14813744 3637664
16:19:49 89041 8192000000 10300421616 10758963200 1376103136 14.382 99 20 40 0 1398366208 17452288 4810784
16:22:38 69186 8192000000 10290919856 10754445312 1355143520 14.194 97 17 34 0 1373416016 14235760 4036736
16:24:53 53468 8192000000 10275441776 10738139136 1359488432 14.233 99 9 18 0 1377562688 16196976 1877280
16:27:01 41013 8192000000 10256726784 10711085056 1322546656 13.900 96 16 32 0 1336148816 9968128 3634032
16:28:50 46424 8192000000 10236320736 10686488576 1306779968 13.757 97 12 24 0 1319914720 10260640 2874112
16:30:36 109134 8192000000 10272553712 10728058880 1308371936 13.771 96 20 40 0 1330700944 17389536 4939472
16:33:28 36811 8192000000 10256554880 10709135360 1292960720 13.631 95 17 32 1 1308106032 12088944 3056368
16:35:50 100342 8192000000 10262111312 10707525632 1285850496 13.566 95 13 26 0 1299505440 11026672 2628272

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-07-2022 16:39:31 to 20-07-2022 16:39:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 67 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-07-2022 16:39:47 to 20-07-2022 16:39:49.

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

20.07.22 16:39:50. 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-20 16:39:52 No severe PSQL-related problems occured
20.07.22 16:39:54. End of report.