Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.11.2021 19: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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15909080 kB
MemAvailable 30821296 kB
Buffers 0 kB
Cached 15809000 kB
SwapCached 2436 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.8
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 2021-11-06 19:37:51
DTS_END                         2021-11-06 19:37:51.5790
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/33516
TRN_ID                          243939
ATT_ID                          721
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: 9698.78 20723 1163854
customer order: creation 180.17 353 21620
customer order: refuse 15.51 356 1861
order to supplier: creation 48.60 1332 5832
order to supplier: removal 3.77 88595 452
invoice (draft): creation 47.63 3064 5716
invoice (draft): removal 54.95 746 6594
invoice accept: apply 43.82 18307 5258
invoice accept: cancel 49.78 736 5973
customer reserve: creation 4047.99 181 485759
customer reserve: removal 4898.63 53 587835
realization accept: apply 84.87 182 10184
realization accept: cancel 88.95 105 10674
payment from customer: creation 61.87 135 7424
payment from customer: removal 5.93 85 711
payment to supplier: creation 50.20 158 6024
payment to supplier: removal 3.58 81 430
service: total inventory turnovers 4.71 1218 565
service: total monetary turnovers 6.93 29 831
service: aggregate perf. data 0.89 22913 107
service: refresh index statistics 0.03 275826 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 06-11-2021 19:40:08 to 06-11-2021 19:40:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4206
TEST_TIME 2 5228
TEST_TIME 3 6205
TEST_TIME 4 6144
TEST_TIME 5 6064
TEST_TIME 6 6260
TEST_TIME 7 6334
TEST_TIME 8 6240
TEST_TIME 9 6240
TEST_TIME 10 6288
TEST_TIME 11 6110
TEST_TIME 12 6217
TEST_TIME 13 6194
TEST_TIME 14 6395
TEST_TIME 15 6599
TEST_TIME 16 6839
TEST_TIME 17 7078
TEST_TIME 18 7316
TEST_TIME 19 7688
TEST_TIME 20 8273
TEST_TIME 21 8173
TEST_TIME 22 8564
TEST_TIME 23 8812
TEST_TIME 24 8745
TEST_TIME 25 8825
TEST_TIME 26 9255
TEST_TIME 27 9212
TEST_TIME 28 9109
TEST_TIME 29 9716
TEST_TIME 30 10190
TEST_TIME 31 10812
TEST_TIME 32 10677
TEST_TIME 33 10761
TEST_TIME 34 10912
TEST_TIME 35 10697
TEST_TIME 36 10644
TEST_TIME 37 10648
TEST_TIME 38 10457
TEST_TIME 39 10389
TEST_TIME 40 10288
TEST_TIME 41 10235
TEST_TIME 42 10234
TEST_TIME 43 10179
TEST_TIME 44 10080
TEST_TIME 45 10051
TEST_TIME 46 9996
TEST_TIME 47 10027
TEST_TIME 48 10049
TEST_TIME 49 10129
TEST_TIME 50 10068
TEST_TIME 51 9989
TEST_TIME 52 9949
TEST_TIME 53 9907
TEST_TIME 54 9861
TEST_TIME 55 9873
TEST_TIME 56 9800
TEST_TIME 57 9821
TEST_TIME 58 9765
TEST_TIME 59 9667
TEST_TIME 60 9747
TEST_TIME 61 9710
TEST_TIME 62 9770
TEST_TIME 63 9811
TEST_TIME 64 9804
TEST_TIME 65 9897
TEST_TIME 66 9878
TEST_TIME 67 9940
TEST_TIME 68 9904
TEST_TIME 69 9935
TEST_TIME 70 9973
TEST_TIME 71 10086
TEST_TIME 72 10040
TEST_TIME 73 10110
TEST_TIME 74 10078
TEST_TIME 75 9953
TEST_TIME 76 9940
TEST_TIME 77 9892
TEST_TIME 78 9888
TEST_TIME 79 9857
TEST_TIME 80 9785
TEST_TIME 81 9813
TEST_TIME 82 9738
TEST_TIME 83 9648
TEST_TIME 84 9707
TEST_TIME 85 9742
TEST_TIME 86 9695
TEST_TIME 87 9707
TEST_TIME 88 9740
TEST_TIME 89 9757
TEST_TIME 90 9801
TEST_TIME 91 9799
TEST_TIME 92 9841
TEST_TIME 93 9873
TEST_TIME 94 9872
TEST_TIME 95 9904
TEST_TIME 96 9956
TEST_TIME 97 9928
TEST_TIME 98 9856
TEST_TIME 99 9833
TEST_TIME 100 9795
TEST_TIME 101 9755
TEST_TIME 102 9743
TEST_TIME 103 9737
TEST_TIME 104 9690
TEST_TIME 105 9659
TEST_TIME 106 9667
TEST_TIME 107 9649
TEST_TIME 108 9626
TEST_TIME 109 9650
TEST_TIME 110 9703
TEST_TIME 111 9709
TEST_TIME 112 9726
TEST_TIME 113 9741
TEST_TIME 114 9752
TEST_TIME 115 9733
TEST_TIME 116 9736
TEST_TIME 117 9721
TEST_TIME 118 9785
TEST_TIME 119 9749
TEST_TIME 120 9700

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 06-11-2021 19:40:12 to 06-11-2021 19:40:16.

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 1613052 1613052 0 0.00 0 25536 6 0 0
doc_list_biud 1613052 1613052 0 0.00 0 22229 25 0 0
sp_add_invoice_to_stock 5365 5258 107 1.99 37 212899 18307 0 107
sp_cancel_adding_invoice 5973 5973 0 0.00 7 59176 737 0 0
sp_cancel_client_order 1861 1861 0 0.00 5 12375 357 0 0
sp_cancel_customer_reserve 587835 587835 0 0.00 1 22238 53 0 0
sp_cancel_pay_from_customer 711 711 0 0.00 2 4482 85 0 0
sp_cancel_pay_to_supplier 430 430 0 0.00 3 5005 81 0 0
sp_cancel_supplier_invoice 6598 6594 4 0.06 3 13891 747 0 4
sp_cancel_supplier_order 452 452 0 0.00 5 902568 88595 0 0
sp_cancel_write_off 10674 10674 0 0.00 1 12766 106 0 0
sp_client_order 21620 21620 0 0.00 3 54447 354 0 0
sp_customer_reserve 485759 485759 0 0.00 0 111075 182 0 0
sp_fill_shopping_cart 33161 33161 0 0.00 1 14496 32 0 0
sp_fill_shopping_cart_clo_res 485754 485754 0 0.00 0 16071 12 0 0
sp_get_clo_for_invoice 5253 5253 0 0.00 0 31602 251 0 0
sp_kill_cost_storno 17788 17788 0 0.00 0 15226 56 0 0
sp_kill_qstorno_ret_qs2qd 1193390 1193390 0 0.00 0 22222 29 0 0
sp_kill_qty_storno 628826 628826 0 0.00 0 22227 63 0 0
sp_lock_selected_doc 21478 21478 0 0.00 0 8459 8 0 0
sp_make_cost_storno 28885 28885 0 0.00 0 15164 56 0 0
sp_multiply_rows_for_pdistr 28885 28885 0 0.00 0 15517 31 0 0
sp_multiply_rows_for_qdistr 21620 21620 0 0.00 0 54436 149 0 0
sp_pay_from_customer 7424 7424 0 0.00 4 15647 135 0 0
sp_pay_to_supplier 6024 6024 0 0.00 5 17058 158 0 0
sp_payment_common 13448 13448 0 0.00 1 15154 41 0 0
sp_qd_handle_on_cancel_clo 1861 1861 0 0.00 0 12367 319 0 0
sp_qd_handle_on_invoice_upd_sts 11226 11226 0 0.00 0 15556 320 0 0
sp_qd_handle_on_reserve_upd_sts 10184 10184 0 0.00 0 9629 54 0 0
sp_reserve_write_off 10184 10184 0 0.00 6 15693 183 0 0
sp_supplier_invoice 5716 5716 0 0.00 5 58818 3064 0 0
sp_supplier_order 5832 5832 0 0.00 4 22881 1332 0 0
srv_aggregate_perf_data 107 107 0 0.00 2395 354786 22914 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1104 137204 47412 0 0
srv_find_qd_qs_mism 1113650 1113650 0 0.00 0 25501 5 0 0
srv_make_invnt_saldo 565 565 0 0.00 25 145254 1218 0 0
srv_make_money_saldo 831 831 0 0.00 1 4241 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 114910 371621 275826 0 0
t$perf-abend:sp_add_invoice_to_stock 107 107 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5258 5258 0 0.00 0 19207 183 0 0
t$perf-norm:sp_cancel_adding_invoice 425 425 0 0.00 1 4148 82 0 0
t$perf-norm:sp_cancel_client_order 1861 1861 0 0.00 0 5780 15 0 0
t$perf-norm:sp_cancel_customer_reserve 694 694 0 0.00 0 2882 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 711 711 0 0.00 0 829 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 430 430 0 0.00 0 4714 27 0 0
t$perf-norm:sp_cancel_supplier_invoice 430 430 0 0.00 0 2784 22 0 0
t$perf-norm:sp_cancel_supplier_order 452 452 0 0.00 0 19590 1592 0 0
t$perf-norm:sp_cancel_write_off 1039 1039 0 0.00 0 3418 9 0 0
t$perf-norm:sp_client_order 21620 21620 0 0.00 0 8808 18 0 0
t$perf-norm:sp_customer_reserve 1064 1064 0 0.00 0 3178 11 0 0
t$perf-norm:sp_pay_from_customer 7424 7424 0 0.00 0 6340 10 0 0
t$perf-norm:sp_pay_to_supplier 6024 6024 0 0.00 0 6374 14 0 0
t$perf-norm:sp_reserve_write_off 10184 10184 0 0.00 0 6233 12 0 0
t$perf-norm:sp_supplier_invoice 5716 5716 0 0.00 0 4036 19 0 0
t$perf-norm:sp_supplier_order 5832 5832 0 0.00 0 5002 17 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 362 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1232 22 0 0
t$perf-norm:srv_make_invnt_saldo 565 565 0 0.00 0 1245 21 0 0
t$perf-norm:srv_make_money_saldo 831 831 0 0.00 0 3870 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 4 0 0
v_add_invoice_to_stock 10718 10504 214 1.99 0 6502 18 0 214
v_all_customers 39690 39690 0 0.00 0 13 1 0 0
v_all_suppliers 31740 31740 0 0.00 0 26 1 0 0
v_all_wares 43236 43236 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 850 850 0 0.00 0 1463 9 0 0
v_cancel_client_order 3722 3722 0 0.00 0 3964 9 0 0
v_cancel_customer_prepayment 1422 1422 0 0.00 0 4461 14 0 0
v_cancel_customer_reserve 1388 1388 0 0.00 0 1802 6 0 0
v_cancel_payment_to_supplier 860 860 0 0.00 0 2074 5 0 0
v_cancel_supplier_invoice 868 860 8 0.92 0 2648 19 0 8
v_cancel_supplier_order 904 904 0 0.00 0 4780 9 0 0
v_cancel_write_off 2078 2078 0 0.00 0 3538 17 0 0
v_max_id_clo_ord 5832 5832 0 0.00 0 1316 2 0 0
v_max_id_clo_res 1064 1064 0 0.00 0 1991 8 0 0
v_max_id_ord_sup 5711 5711 0 0.00 0 4812 3 0 0
v_max_non_paid_invoice 1697 1697 0 0.00 0 2024 3 0 0
v_max_non_paid_realizn 3754 3754 0 0.00 0 2969 4 0 0
v_min_id_clo_ord 5832 5832 0 0.00 0 4539 7 0 0
v_min_id_clo_res 1064 1064 0 0.00 0 4216 7 0 0
v_min_id_ord_sup 5711 5711 0 0.00 0 5295 5 0 0
v_min_non_paid_invoice 6024 6024 0 0.00 0 15144 35 0 0
v_min_non_paid_realizn 7424 7424 0 0.00 0 14535 33 0 0
v_our_firm 10886 10886 0 0.00 0 20 1 0 0
v_reserve_write_off 20368 20368 0 0.00 0 4205 5 0 0
x_lock_depdocs_on_canc_invoice 12137 12137 0 0.00 0 22362 112 0 0
x_lock_depdocs_on_canc_sup_ord 452 452 0 0.00 0 16313 303 0 0
x_make_qstorno_1000_1200 5832 5832 0 0.00 1 22874 1251 0 0
x_make_qstorno_1200_2000 5716 5716 0 0.00 2 58795 2961 0 0
x_make_qstorno_2100_3300 485759 485759 0 0.00 0 111074 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-11-2021 19:40:17 to 06-11-2021 19:40:21.

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:07:55 10 8192000000 8804221024 8817446912 8258288 0.100 3 1 2 0 8401072 67712 75072
17:08:56 23821 8192000000 9916626224 10234769408 1000312320 10.882 100 91 182 0 1030567120 6538624 23716176
17:10:33 28429 8192000000 9974862336 10305527808 1053260416 11.392 100 87 174 0 1084724208 9569888 21893904
17:12:08 15306 8192000000 9994740400 10333442048 1081937648 11.666 100 85 170 1 1113650528 11214640 20498240
17:13:30 13085 8192000000 10035851984 10387365888 1123208656 12.057 100 78 156 0 1154021600 12373600 18439344
17:14:49 9556 8192000000 10095405776 10456707072 1177995232 12.571 100 83 166 1 1210138944 13725472 18418240
17:16:02 4401 8192000000 10127883488 10497875968 1212875824 12.896 100 78 156 0 1244336944 14110720 17350400
17:17:08 4090 8192000000 10167080448 10541903872 1250142432 13.240 100 83 167 0 1281838320 12852880 18843008
17:18:14 2691 8192000000 10191611088 10570272768 1272850896 13.448 100 86 170 2 1307087760 15152448 19084416
17:19:20 1502 8192000000 10210722112 10593329152 1289242016 13.597 100 90 182 2 1323097168 13301472 20553680
17:20:24 2363 8192000000 10211499872 10609106944 1306568592 13.755 100 59 118 0 1330292976 10313536 13410848
17:21:28 9186 8192000000 10194450128 10608226304 1311852624 13.803 100 31 62 3 1325405360 6360864 7191872
17:22:42 16728 8192000000 10201659728 10622574592 1325181472 13.924 100 26 52 0 1337050192 6840560 5028160
17:24:00 6875 8192000000 10243103360 10648166400 1330641968 13.973 100 59 117 3 1351374112 5909920 14822224
17:25:07 4092 8192000000 10230278304 10646949888 1334322000 14.006 100 30 60 0 1347580304 5474752 7783552
17:26:13 12571 8192000000 10220719440 10644447232 1322727744 13.901 99 21 42 2 1333396704 5396384 5272576
17:27:27 70250 8192000000 10230720016 10633265152 1313634816 13.819 99 33 65 0 1327551648 5020160 8896672
17:29:38 5385 8192000000 10174284624 10588680192 1270777568 13.429 98 41 80 1 1286319120 6472512 9069040
17:30:46 10417 8192000000 10152021008 10560995328 1254633680 13.281 98 21 43 1 1264562608 5197536 4731392
17:31:59 58547 8192000000 10120051456 10523369472 1208340832 12.854 97 30 60 0 1221565120 5968064 7256224
17:33:57 7010 8192000000 10156597664 10564161536 1265330320 13.379 100 32 64 0 1280042432 7442960 7269152
17:35:07 11344 8192000000 10182024784 10596921344 1293159472 13.633 100 29 55 4 1305560064 5646112 6754480
17:36:18 9280 8192000000 10186461904 10605907968 1303409552 13.726 100 14 28 0 1312345680 5654864 3281264
17:37:29 28389 8192000000 10218728528 10628595712 1319101696 13.869 100 18 35 0 1328209184 4558096 4549392
17:39:01 9573 8192000000 10265882688 10671529984 1350522816 14.152 100 74 147 2 1377923040 10856736 16543488
17:40:14 10593 8192000000 10268538752 10684309504 1360610416 14.243 100 56 110 0 1384212736 11295072 12307248
17:41:31 10609 8192000000 10281787504 10701185024 1372075424 14.346 100 40 80 3 1390734864 9685808 8973632
17:42:44 33177 8192000000 10290669296 10719064064 1387845440 14.487 100 44 88 2 1410589792 12492976 10251376
17:44:27 10025 8192000000 10304059072 10730721280 1392409568 14.527 100 44 88 1 1415943296 12965424 10568304
17:45:46 12935 8192000000 10322895248 10749771776 1406759664 14.655 100 48 96 1 1432149120 13629568 11759888
17:47:06 12009 8192000000 10345001184 10767040512 1420632992 14.778 100 63 125 2 1453781840 18939168 14209680
17:48:23 13464 8192000000 10349210304 10778472448 1432348368 14.882 100 51 100 0 1463414256 19569232 11496656
17:49:40 9525 8192000000 10348680448 10780405760 1425401568 14.821 100 49 96 2 1458172864 20818928 11952368
17:51:02 3771 8192000000 10364089168 10790576128 1434186928 14.898 100 65 131 0 1472202576 22092032 15923616
17:52:12 4186 8192000000 10343312192 10763489280 1407720000 14.664 100 62 122 0 1449821200 27080528 15020672
17:53:37 3214 8192000000 10365406656 10790694912 1425885520 14.825 100 58 116 3 1474572176 32953376 15733280
17:54:45 2760 8192000000 10345856048 10748829696 1383201200 14.445 98 93 184 1 1439850304 34185504 22463600
17:55:54 4544 8192000000 10345535424 10757304320 1393602336 14.538 100 65 126 0 1453115648 43671648 15841664
17:57:04 1102 8192000000 10344840272 10759311360 1392513408 14.528 100 65 130 0 1451068384 41886784 16668192
17:58:26 4447 8192000000 10331958048 10743623680 1379875200 14.415 100 64 127 0 1434476896 38043168 16558528
17:59:34 28411 8192000000 10294920272 10725478400 1333979024 14.003 97 27 55 1 1379004032 37474592 7550416
18:01:07 6216 8192000000 10307625328 10724319232 1345362512 14.106 98 48 95 2 1393377056 35034944 12979600
18:02:25 18569 8192000000 10305465264 10734878720 1348012336 14.130 98 33 66 1 1399119952 42148336 8959280
18:03:45 31577 8192000000 10320780368 10753712128 1366305744 14.294 99 22 44 1 1420781408 48337024 6138640
18:05:18 36222 8192000000 10327055872 10769010688 1372798848 14.352 100 18 36 1 1431976464 53687360 5490256
18:06:58 41881 8192000000 10325987792 10768506880 1362612720 14.261 99 16 32 0 1414057072 46910608 4533744
18:08:41 50941 8192000000 10292979040 10728214528 1354499536 14.188 98 16 32 1 1370534592 11697040 4338016
18:10:43 47290 8192000000 10294087296 10748403712 1379576384 14.413 100 6 12 0 1398535136 17773040 1185712
18:12:41 87529 8192000000 10291084432 10742808576 1370775504 14.334 99 19 37 1 1387124976 12141648 4207824
18:15:10 61965 8192000000 10294713488 10733346816 1355534432 14.197 98 45 88 0 1378712176 12922432 10255312
18:17:27 73770 8192000000 10304139040 10745167872 1363784848 14.271 99 28 57 1 1380295040 10233152 6277040
18:19:42 17771 8192000000 10313432464 10754822144 1396219648 14.561 100 53 106 0 1417204576 9312720 11672208
18:21:03 29286 8192000000 10288121440 10731057152 1367527616 14.305 99 33 66 1 1385946336 10919408 7499312
18:22:39 51846 8192000000 10319292672 10764451840 1394729136 14.548 100 24 49 0 1413261152 12264032 6267984
18:24:36 62692 8192000000 10285608768 10745188352 1361982608 14.255 98 38 76 0 1385370688 14211696 9176384
18:26:41 43315 8192000000 10287137840 10751983616 1389015440 14.497 100 21 42 0 1404114784 10114112 4985232
18:28:25 137204 8192000000 10277134528 10747629568 1364701216 14.280 98 11 22 0 1377359760 9618928 3039616
18:31:46 87316 8192000000 10280896656 10741051392 1352540576 14.170 97 11 21 0 1369876336 14713728 2622032
18:34:15 64802 8192000000 10270136752 10729148416 1344956848 14.102 97 16 32 0 1356812784 7872320 3983616
18:36:54 53678 8192000000 10258479920 10728759296 1337862784 14.038 97 8 16 0 1355388544 15130704 2395056
18:38:48 42664 8192000000 10271253968 10722574336 1354451568 14.188 99 35 70 1 1370739920 8218640 8069712
18:40:31 46544 8192000000 10255031312 10707001344 1310796160 13.793 96 8 16 0 1321854384 9582160 1476064
18:42:20 65277 8192000000 10261635520 10706079744 1309714048 13.783 96 15 29 1 1325003344 11883392 3405904
18:44:26 45650 8192000000 10253496224 10693267456 1291499152 13.618 95 18 36 0 1304677904 9053040 4125712
18:46:13 24316 8192000000 10279474208 10720440320 1350344960 14.151 99 41 82 3 1374428176 14758080 9325136
18:47:40 70836 8192000000 10256494208 10714656768 1309796912 13.784 96 12 24 0 1332553056 19932944 2823200
18:49:52 118124 8192000000 10263082784 10712031232 1311295552 13.798 97 21 41 1 1337129664 21012512 4821600
18:52:52 120188 8192000000 10276602624 10721849344 1326617120 13.937 97 32 64 1 1345162304 11294416 7250768
18:55:53 107191 8192000000 10261067040 10716372992 1355642176 14.198 99 9 18 0 1373715264 15969440 2103648
18:59:30 92409 8192000000 10274811600 10725965824 1347193376 14.122 98 13 26 0 1361729552 11431280 3104896
19:02:03 64390 8192000000 10255847744 10720747520 1327810224 13.947 97 10 20 0 1345576032 15181104 2584704
19:04:08 105999 8192000000 10290502144 10746220544 1363039296 14.265 98 24 46 0 1383265008 15169024 5056688
19:07:05 99854 8192000000 10282900896 10740506624 1319023024 13.868 95 12 24 0 1332377712 10878736 2475952
19:10:07 59890 8192000000 10295859584 10756165632 1360792832 14.244 98 16 32 0 1375375136 11343840 3238464
19:12:07 50478 8192000000 10290264480 10745245696 1338816752 14.047 96 21 42 2 1352911024 9767568 4326704
19:14:00 55419 8192000000 10309838688 10760830976 1375942912 14.380 98 26 52 0 1395471328 13422704 6105712
19:16:02 104452 8192000000 10299441760 10755088384 1374696992 14.369 98 27 54 0 1389829040 9340144 5791904
19:18:51 60000 8192000000 10286274592 10753200128 1380952512 14.425 99 11 22 2 1392590912 9047616 2590784
19:22:12 63549 8192000000 10280179360 10742411264 1373703104 14.360 99 16 32 2 1387202128 9176880 4322144
19:25:09 67670 8192000000 10296004192 10760380416 1370926208 14.335 98 20 40 0 1388655024 13094352 4634464
19:27:24 83318 8192000000 10313414352 10763038720 1359595904 14.234 97 24 48 0 1383286672 17603888 6086880
19:30:05 56701 8192000000 10270536800 10718228480 1246052672 13.202 90 12 24 0 1259973536 10790448 3130416
19:32:09 58484 8192000000 10247252160 10678030336 1248020096 13.220 92 18 36 0 1260152848 8591152 3541600
19:34:12 10583 8192000000 10285350176 10714279936 1347795712 14.128 99 48 96 1 1369427296 11675216 9956368
19:35:24 71832 8192000000 10281987216 10726572032 1345525312 14.107 98 25 50 1 1368088032 17131904 5430816
19:37:40 34388 8192000000 9154752912 9414254592 318230304 3.739 24 1 2 0 321595392 3322736 42352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-11-2021 19:40:22 to 06-11-2021 19:40:37.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-11-2021 19:40:38 to 06-11-2021 19:40:40.

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          244242
MON$OLDEST_ACTIVE               244243
MON$OLDEST_SNAPSHOT             244243
MON$NEXT_TRANSACTION            244243
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                       2044256
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33528 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33528 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33528 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33528 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'

06.11.21 19:40:41. 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
2021-11-06 19:40:44 No severe PSQL-related problems occured
06.11.21 19:40:45. End of report.