Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.05.2022 22: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 16594184 kB
MemAvailable 31433056 kB
Buffers 0 kB
Cached 15096096 kB
SwapCached 2640 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.10
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-05-14 22:38:18
DTS_END                         2022-05-14 22:38:18.8590
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/60438
TRN_ID                          247406
ATT_ID                          687
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: 9617.07 19520 1154048
customer order: creation 184.58 350 22149
customer order: refuse 15.10 317 1812
order to supplier: creation 47.80 1223 5736
order to supplier: removal 3.59 96864 431
invoice (draft): creation 47.43 3470 5692
invoice (draft): removal 53.47 771 6416
invoice accept: apply 44.26 16479 5311
invoice accept: cancel 48.26 762 5791
customer reserve: creation 4113.83 163 493660
customer reserve: removal 4745.10 58 569412
realization accept: apply 87.61 154 10513
realization accept: cancel 89.38 109 10726
payment from customer: creation 62.05 81 7446
payment from customer: removal 6.28 74 754
payment to supplier: creation 52.58 111 6310
payment to supplier: removal 3.29 59 395
service: total inventory turnovers 4.83 1089 579
service: total monetary turnovers 6.68 35 802
service: aggregate perf. data 0.91 16513 109
service: refresh index statistics 0.03 251715 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-05-2022 22:39:07 to 14-05-2022 22:39:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2711
TEST_TIME 2 2783
TEST_TIME 3 3777
TEST_TIME 4 4035
TEST_TIME 5 4443
TEST_TIME 6 4237
TEST_TIME 7 4315
TEST_TIME 8 4634
TEST_TIME 9 4806
TEST_TIME 10 5051
TEST_TIME 11 5418
TEST_TIME 12 5454
TEST_TIME 13 5847
TEST_TIME 14 6484
TEST_TIME 15 7027
TEST_TIME 16 7325
TEST_TIME 17 7298
TEST_TIME 18 7936
TEST_TIME 19 8449
TEST_TIME 20 9115
TEST_TIME 21 9667
TEST_TIME 22 9482
TEST_TIME 23 10314
TEST_TIME 24 10469
TEST_TIME 25 11262
TEST_TIME 26 11202
TEST_TIME 27 11091
TEST_TIME 28 10948
TEST_TIME 29 10907
TEST_TIME 30 10834
TEST_TIME 31 10501
TEST_TIME 32 10449
TEST_TIME 33 10324
TEST_TIME 34 10201
TEST_TIME 35 10203
TEST_TIME 36 10244
TEST_TIME 37 10162
TEST_TIME 38 10236
TEST_TIME 39 10122
TEST_TIME 40 10099
TEST_TIME 41 10119
TEST_TIME 42 9965
TEST_TIME 43 9817
TEST_TIME 44 9884
TEST_TIME 45 9790
TEST_TIME 46 9749
TEST_TIME 47 9796
TEST_TIME 48 9791
TEST_TIME 49 9717
TEST_TIME 50 9865
TEST_TIME 51 9940
TEST_TIME 52 9928
TEST_TIME 53 9995
TEST_TIME 54 10010
TEST_TIME 55 10164
TEST_TIME 56 10082
TEST_TIME 57 10103
TEST_TIME 58 9960
TEST_TIME 59 9979
TEST_TIME 60 9838
TEST_TIME 61 9860
TEST_TIME 62 9781
TEST_TIME 63 9693
TEST_TIME 64 9666
TEST_TIME 65 9644
TEST_TIME 66 9580
TEST_TIME 67 9620
TEST_TIME 68 9609
TEST_TIME 69 9713
TEST_TIME 70 9718
TEST_TIME 71 9729
TEST_TIME 72 9741
TEST_TIME 73 9715
TEST_TIME 74 9677
TEST_TIME 75 9759
TEST_TIME 76 9885
TEST_TIME 77 9944
TEST_TIME 78 9903
TEST_TIME 79 9935
TEST_TIME 80 9844
TEST_TIME 81 9819
TEST_TIME 82 9769
TEST_TIME 83 9719
TEST_TIME 84 9757
TEST_TIME 85 9708
TEST_TIME 86 9692
TEST_TIME 87 9665
TEST_TIME 88 9613
TEST_TIME 89 9622
TEST_TIME 90 9631
TEST_TIME 91 9663
TEST_TIME 92 9672
TEST_TIME 93 9729
TEST_TIME 94 9730
TEST_TIME 95 9706
TEST_TIME 96 9778
TEST_TIME 97 9826
TEST_TIME 98 9879
TEST_TIME 99 9834
TEST_TIME 100 9930
TEST_TIME 101 9933
TEST_TIME 102 9872
TEST_TIME 103 9818
TEST_TIME 104 9772
TEST_TIME 105 9750
TEST_TIME 106 9758
TEST_TIME 107 9715
TEST_TIME 108 9711
TEST_TIME 109 9697
TEST_TIME 110 9635
TEST_TIME 111 9624
TEST_TIME 112 9631
TEST_TIME 113 9644
TEST_TIME 114 9641
TEST_TIME 115 9618
TEST_TIME 116 9629
TEST_TIME 117 9672
TEST_TIME 118 9636
TEST_TIME 119 9681
TEST_TIME 120 9619

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-05-2022 22:39:11 to 14-05-2022 22:39:14.

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 1610595 1610595 0 0.00 0 21870 6 0 0
doc_list_biud 1610592 1610592 0 0.00 0 23425 26 0 0
sp_add_invoice_to_stock 5471 5311 160 2.92 39 263130 16480 0 160
sp_cancel_adding_invoice 5791 5791 0 0.00 5 70440 762 0 0
sp_cancel_client_order 1812 1812 0 0.00 4 8238 318 0 0
sp_cancel_customer_reserve 569412 569412 0 0.00 1 23433 59 0 0
sp_cancel_pay_from_customer 754 754 0 0.00 3 6093 75 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 3 5088 59 0 0
sp_cancel_supplier_invoice 6429 6416 13 0.20 2 12391 772 0 13
sp_cancel_supplier_order 431 431 0 0.00 15 1183212 96865 0 0
sp_cancel_write_off 10726 10726 0 0.00 1 9698 110 0 0
sp_client_order 22149 22149 0 0.00 3 21356 351 0 0
sp_customer_reserve 493660 493660 0 0.00 0 138639 164 0 0
sp_fill_shopping_cart 33542 33542 0 0.00 1 13215 37 0 0
sp_fill_shopping_cart_clo_res 493652 493652 0 0.00 0 16079 14 0 0
sp_get_clo_for_invoice 5303 5303 0 0.00 0 33267 296 0 0
sp_kill_cost_storno 17666 17666 0 0.00 0 9692 52 0 0
sp_kill_qstorno_ret_qs2qd 1156397 1156397 0 0.00 0 23398 32 0 0
sp_kill_qty_storno 610404 610404 0 0.00 0 23425 68 0 0
sp_lock_selected_doc 21921 21921 0 0.00 0 8176 5 0 0
sp_make_cost_storno 29572 29572 0 0.00 0 21856 48 0 0
sp_multiply_rows_for_pdistr 29572 29572 0 0.00 0 8629 24 0 0
sp_multiply_rows_for_qdistr 22149 22149 0 0.00 0 20830 171 0 0
sp_pay_from_customer 7446 7446 0 0.00 4 18573 81 0 0
sp_pay_to_supplier 6310 6310 0 0.00 4 27294 111 0 0
sp_payment_common 13756 13756 0 0.00 1 8938 19 0 0
sp_qd_handle_on_cancel_clo 1812 1812 0 0.00 0 7123 288 0 0
sp_qd_handle_on_invoice_upd_sts 11094 11094 0 0.00 0 19509 275 0 0
sp_qd_handle_on_reserve_upd_sts 10513 10513 0 0.00 0 15058 55 0 0
sp_reserve_write_off 10513 10513 0 0.00 6 19869 155 0 0
sp_supplier_invoice 5692 5692 0 0.00 6 98049 3470 0 0
sp_supplier_order 5736 5736 0 0.00 4 23735 1223 0 0
srv_aggregate_perf_data 109 109 0 0.00 2563 147164 16514 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1675 143012 48570 0 0
srv_find_qd_qs_mism 1103066 1103066 0 0.00 0 6053 5 0 0
srv_make_invnt_saldo 579 579 0 0.00 23 62780 1089 0 0
srv_make_money_saldo 802 802 0 0.00 1 10602 35 0 0
srv_recalc_idx_stat 4 4 0 0.00 207980 308498 251715 0 0
t$perf-abend:sp_add_invoice_to_stock 160 160 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5311 5311 0 0.00 0 13152 131 0 0
t$perf-norm:sp_cancel_adding_invoice 471 471 0 0.00 0 8336 82 0 0
t$perf-norm:sp_cancel_client_order 1812 1812 0 0.00 0 4407 16 0 0
t$perf-norm:sp_cancel_customer_reserve 719 719 0 0.00 0 2341 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 754 754 0 0.00 0 514 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 7265 26 0 0
t$perf-norm:sp_cancel_supplier_invoice 465 465 0 0.00 0 2697 19 0 0
t$perf-norm:sp_cancel_supplier_order 431 431 0 0.00 0 18364 1471 0 0
t$perf-norm:sp_cancel_write_off 1058 1058 0 0.00 0 3553 16 0 0
t$perf-norm:sp_client_order 22149 22149 0 0.00 0 7107 15 0 0
t$perf-norm:sp_customer_reserve 1045 1045 0 0.00 0 3414 6 0 0
t$perf-norm:sp_pay_from_customer 7446 7446 0 0.00 0 6929 8 0 0
t$perf-norm:sp_pay_to_supplier 6310 6310 0 0.00 0 2611 6 0 0
t$perf-norm:sp_reserve_write_off 10513 10513 0 0.00 0 10157 10 0 0
t$perf-norm:sp_supplier_invoice 5692 5692 0 0.00 0 8092 16 0 0
t$perf-norm:sp_supplier_order 5736 5736 0 0.00 0 8079 18 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 142 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 80 2 0 0
t$perf-norm:srv_make_invnt_saldo 579 579 0 0.00 0 1085 20 0 0
t$perf-norm:srv_make_money_saldo 802 802 0 0.00 0 977 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 10 4 0 0
v_add_invoice_to_stock 10926 10606 320 2.92 0 2189 12 0 320
v_all_customers 40336 40336 0 0.00 0 182 0 0 0
v_all_suppliers 32346 32346 0 0.00 0 19 1 0 0
v_all_wares 44288 44288 0 0.00 0 67 1 0 0
v_cancel_adding_invoice 942 942 0 0.00 0 2565 8 0 0
v_cancel_client_order 3624 3624 0 0.00 0 1994 3 0 0
v_cancel_customer_prepayment 1508 1508 0 0.00 0 1565 4 0 0
v_cancel_customer_reserve 1438 1438 0 0.00 0 1708 4 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 980 6 0 0
v_cancel_supplier_invoice 956 930 26 2.71 0 8033 24 0 26
v_cancel_supplier_order 862 862 0 0.00 0 4229 8 0 0
v_cancel_write_off 2116 2116 0 0.00 0 3265 12 0 0
v_max_id_clo_ord 5734 5734 0 0.00 0 5508 2 0 0
v_max_id_clo_res 1045 1045 0 0.00 0 1991 11 0 0
v_max_id_ord_sup 5664 5664 0 0.00 0 6589 6 0 0
v_max_non_paid_invoice 1535 1535 0 0.00 0 1248 4 0 0
v_max_non_paid_realizn 3901 3901 0 0.00 0 893 3 0 0
v_min_id_clo_ord 5734 5734 0 0.00 0 1768 5 0 0
v_min_id_clo_res 1045 1045 0 0.00 0 1676 6 0 0
v_min_id_ord_sup 5664 5664 0 0.00 0 6807 10 0 0
v_min_non_paid_invoice 6310 6310 0 0.00 0 8036 13 0 0
v_min_non_paid_realizn 7446 7446 0 0.00 0 8934 14 0 0
v_our_firm 11042 11042 0 0.00 0 11 1 0 0
v_reserve_write_off 21026 21026 0 0.00 0 3666 4 0 0
x_lock_depdocs_on_canc_invoice 11742 11742 0 0.00 0 15193 112 0 0
x_lock_depdocs_on_canc_sup_ord 431 431 0 0.00 0 3564 248 0 0
x_make_qstorno_1000_1200 5736 5736 0 0.00 1 23703 1156 0 0
x_make_qstorno_1200_2000 5692 5692 0 0.00 3 98006 3314 0 0
x_make_qstorno_2100_3300 493660 493660 0 0.00 0 138637 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-05-2022 22:39:15 to 14-05-2022 22:39:19.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:22 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:23 19897 8192000000 9896744768 10204454912 979325728 10.678 100 91 182 1 1008891872 5281104 24285040
20:10:48 23222 8192000000 9950746272 10269011968 1032348016 11.191 100 94 188 1 1063799936 7372032 24079888
20:12:15 19180 8192000000 9979356848 10310639616 1064673232 11.501 100 83 166 0 1094509136 8747952 21087952
20:13:37 10045 8192000000 10032794800 10380648448 1123754528 12.062 100 75 150 1 1151641280 9491392 18395360
20:14:52 7948 8192000000 10091023568 10451136512 1176597504 12.558 100 77 152 1 1205418928 11083136 17738288
20:16:03 6555 8192000000 10134353520 10504380416 1223114816 12.990 100 79 158 0 1253888944 13039360 17734768
20:17:11 3691 8192000000 10175756000 10550378496 1257066816 13.303 100 93 186 2 1293260656 15632688 20561152
20:18:17 2832 8192000000 10195923936 10576887808 1280286864 13.516 100 90 180 3 1313643264 13590032 19766368
20:19:21 1708 8192000000 10212868800 10594746368 1293909744 13.640 100 94 189 1 1329966864 15397904 20659216
20:20:27 2449 8192000000 10222026432 10607226880 1304397088 13.735 100 80 160 3 1335574352 13689872 17487392
20:21:30 8925 8192000000 10200377408 10606047232 1306091488 13.751 100 35 71 0 1320493328 5538864 8862976
20:22:43 6712 8192000000 10196289968 10612731904 1312439072 13.808 100 17 34 0 1322016448 5508864 4068512
20:23:56 7551 8192000000 10201069456 10622050304 1321179376 13.887 100 20 40 0 1330974688 5545520 4249792
20:25:04 67348 8192000000 10248341056 10650763264 1331246112 13.978 100 52 106 1 1350390624 5624816 13519696
20:27:12 6025 8192000000 10225827168 10644389888 1331822240 13.984 100 16 33 0 1340676704 5412336 3442128
20:28:19 7490 8192000000 10213065296 10624634880 1297592320 13.673 98 20 40 0 1307715840 5730848 4392672
20:29:30 30276 8192000000 10181081840 10592047104 1233827360 13.089 94 20 40 3 1243426736 5466720 4132656
20:31:00 3382 8192000000 10171701808 10570596352 1258815280 13.319 99 53 107 4 1279319824 7569040 12935504
20:32:06 9041 8192000000 10147121424 10553765888 1224702288 13.005 97 28 56 2 1237002160 5657808 6642064
20:33:17 66592 8192000000 10171143776 10565898240 1255240064 13.286 99 28 56 1 1267677616 5898976 6538576
20:35:24 8041 8192000000 10179760096 10586238976 1284144544 13.551 100 34 68 1 1299109200 7283872 7680784
20:36:35 5604 8192000000 10191165120 10604072960 1297947808 13.677 100 35 70 0 1312650960 6144224 8558928
20:37:45 97156 8192000000 10231193712 10641944576 1323262192 13.906 100 46 90 2 1339499312 5348576 10888544
20:40:27 39697 8192000000 10264034864 10683105280 1354863904 14.191 100 38 76 1 1376291136 12941936 8485296
20:42:13 19052 8192000000 10288337728 10709688320 1372750672 14.352 100 35 70 0 1397664432 16080512 8833248
20:43:35 19092 8192000000 10313134464 10734710784 1396172176 14.561 100 47 91 0 1427259072 20651952 10434944
20:45:02 24814 8192000000 10329613104 10756743168 1408236656 14.668 100 46 91 0 1443684928 24448768 10999504
20:46:37 13473 8192000000 10351089984 10772037632 1412029696 14.702 99 59 119 2 1455705216 28726576 14948944
20:47:56 10617 8192000000 10356390864 10777964544 1422162960 14.792 100 48 96 0 1465011024 30466688 12381376
20:49:11 4390 8192000000 10366727376 10784149504 1410123936 14.685 99 76 152 0 1464863616 34775600 19964080
20:50:23 3242 8192000000 10376200752 10788454400 1423496896 14.804 100 84 167 4 1481747024 37687024 20563104
20:51:34 4055 8192000000 10384662992 10803478528 1428509872 14.848 100 73 145 2 1489091264 42073136 18508256
20:52:44 1671 8192000000 10383842032 10797481984 1419762944 14.771 100 90 178 3 1487539600 45831536 21945120
20:53:48 11295 8192000000 10349431808 10768859136 1388446832 14.492 98 45 88 0 1444273776 43867824 11959120
20:55:05 5261 8192000000 10359966640 10777780224 1399552304 14.591 100 65 129 0 1465205664 48840480 16812880
20:56:16 6712 8192000000 10381367280 10807369728 1413418080 14.714 100 50 100 1 1483153536 56234512 13500944
20:57:31 20368 8192000000 10333570096 10763194368 1372326128 14.348 98 34 67 0 1430964272 49056080 9582064
20:59:00 39731 8192000000 10312361488 10751209472 1348752544 14.136 98 23 46 0 1400233248 45052272 6428432
21:00:44 17137 8192000000 10293829632 10727796736 1330258016 13.969 98 34 68 0 1383619504 44068000 9293488
21:02:03 46431 8192000000 10282115568 10730192896 1342916944 14.084 99 13 26 0 1384514000 37923872 3673184
21:03:50 40152 8192000000 10280447712 10711502848 1352033792 14.166 100 25 50 2 1372157152 14275232 5848128
21:05:37 56961 8192000000 10288773552 10723110912 1330871184 13.975 97 20 38 1 1345006816 9785664 4349968
21:07:38 59894 8192000000 10249971520 10671931392 1285514640 13.563 94 23 46 0 1299024928 7812928 5697360
21:10:50 102325 8192000000 10266878704 10705612800 1321584288 13.891 97 26 52 0 1336696096 8582752 6529056
21:13:33 23068 8192000000 10265789760 10709880832 1357917680 14.219 99 18 35 0 1371878320 9824480 4136160
21:14:58 36798 8192000000 10300444960 10741706752 1382681744 14.441 100 43 86 1 1404394400 11702656 10010000
21:16:38 134846 8192000000 10271027488 10724388864 1330432656 13.971 96 16 32 0 1352532048 18042432 4056960
21:19:57 89898 8192000000 10267684576 10720124928 1350295744 14.150 98 16 32 0 1361628272 7615392 3717136
21:22:28 46220 8192000000 10268378096 10733899776 1376259136 14.383 100 5 10 0 1384635584 7435008 941440
21:24:18 18905 8192000000 10308720720 10751660032 1391709344 14.521 100 40 78 1 1414584000 13986464 8888192
21:25:43 48125 8192000000 10304801264 10750722048 1375332656 14.375 99 19 38 1 1393001648 12784320 4884672
21:27:36 33326 8192000000 10322132864 10778210304 1409437552 14.679 100 15 30 3 1429940000 16598512 3903936
21:29:14 39432 8192000000 10314502160 10767020032 1392571024 14.529 99 20 40 2 1415273552 17999200 4703328
21:31:10 70249 8192000000 10297864592 10754813952 1357395968 14.214 97 15 30 0 1381195968 19658528 4141472
21:33:23 91014 8192000000 10302877232 10751909888 1361967360 14.255 97 23 47 1 1379462832 11863184 5632288
21:36:31 143012 8192000000 10297466560 10754482176 1354722064 14.190 96 30 60 0 1377298688 16102848 6473776
21:39:59 60129 8192000000 10278721904 10740543488 1377281104 14.392 99 15 30 0 1388418592 7766336 3371152
21:41:59 49686 8192000000 10265758384 10727448576 1364113632 14.274 98 11 23 0 1372986032 6548336 2324064
21:43:49 33222 8192000000 10279511280 10720526336 1321598800 13.891 96 28 56 1 1336040240 7816144 6625296
21:45:27 72871 8192000000 10243615936 10694934528 1302582736 13.719 95 18 37 0 1321832832 14482848 4767248
21:47:40 52543 8192000000 10253740416 10702344192 1317541264 13.854 97 12 24 1 1329596512 9253888 2801360
21:49:40 50780 8192000000 10238301248 10688933888 1273420720 13.453 94 17 34 0 1288663792 11435984 3807088
21:51:31 17283 8192000000 10258424768 10689236992 1315399872 13.835 98 33 65 1 1330681376 8094272 7187232
21:52:49 28289 8192000000 10276534528 10716622848 1340421728 14.061 99 38 76 0 1368514896 19675664 8417504
21:54:28 101851 8192000000 10253876192 10705932288 1298403248 13.681 96 10 20 0 1320908512 20510544 1994720
21:57:10 95351 8192000000 10265759936 10714419200 1351510304 14.161 100 20 40 0 1379432144 23344672 4577168
21:59:50 46519 8192000000 10258950000 10708185088 1353071488 14.175 100 26 52 0 1370538560 10846000 6621072
22:01:42 40499 8192000000 10256144080 10713927680 1364695824 14.279 100 23 46 0 1380628480 10821824 5110832
22:03:23 73296 8192000000 10269129616 10719846400 1366647824 14.297 100 16 31 0 1377226816 6816256 3762736
22:06:42 39089 8192000000 10267387600 10713604096 1361187120 14.248 100 19 37 2 1377136816 11747376 4202320
22:08:23 42659 8192000000 10287795488 10730647552 1374479680 14.367 100 25 50 1 1395887936 15963184 5445072
22:10:06 72887 8192000000 10279981424 10731773952 1347797632 14.128 97 19 38 0 1363834048 11773664 4262752
22:12:25 42879 8192000000 10301948032 10744958976 1369936736 14.326 99 25 50 0 1386313744 10265376 6111632
22:14:08 50259 8192000000 10297927856 10746769408 1354957808 14.192 98 19 38 1 1372205936 13350784 3897344
22:16:01 101394 8192000000 10296506272 10744532992 1384046864 14.453 100 11 22 1 1402266288 15604192 2615232
22:18:45 114075 8192000000 10302947728 10771927040 1393891296 14.541 100 9 18 1 1413440176 17530640 2018240
22:22:01 51591 8192000000 10299638896 10746155008 1387249520 14.481 100 31 62 0 1403677392 10216528 6211344
22:24:07 37989 8192000000 10278089680 10735083520 1364543152 14.278 99 12 24 0 1376174944 8357264 3274528
22:26:02 54434 8192000000 10271675664 10734096384 1365613824 14.288 99 11 22 1 1375668336 7810128 2244384
22:28:00 89793 8192000000 10282772880 10734891008 1367808912 14.307 99 18 36 0 1377995760 6291232 3895616
22:30:32 50047 8192000000 10265895056 10719277056 1338532048 14.044 97 14 28 0 1351643648 9902288 3209312
22:32:26 50423 8192000000 10261543408 10704093184 1318724000 13.865 97 30 59 1 1333362112 7347872 7290240
22:34:20 50369 8192000000 10249628240 10692681728 1317236512 13.852 98 27 54 1 1335266784 12365584 5664688
22:36:12 28191 8192000000 10242932688 10682806272 1292207408 13.624 96 30 60 1 1307723472 8818752 6697312
22:37:40 42999 8192000000 9133997568 9378693120 287818976 3.394 22 6 10 2 295392352 6457984 1115392

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-05-2022 22:39:21 to 14-05-2022 22:39:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 160 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-05-2022 22:39:37 to 14-05-2022 22:39:39.

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          247405
MON$OLDEST_ACTIVE               247696
MON$OLDEST_SNAPSHOT             247696
MON$NEXT_TRANSACTION            247696
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                       2020416
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33587 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33587 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33587 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33587 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 ---
:308: 
:309: mscs-test-161.budzdorov.ru    Sat May 14 22:38:26 2022
:310:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:311: 
:312: 
:313: mscs-test-161.budzdorov.ru    Sat May 14 22:38:27 2022
:314:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:315: 
--- end of diff output ---

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

14.05.22 22:39:40. 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-05-14 22:39:42 No severe PSQL-related problems occured
14.05.22 22:39:43. End of report.