Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.04.2022 01:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16494836 kB
MemAvailable 31565428 kB
Buffers 0 kB
Cached 15240904 kB
SwapCached 2580 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-04-23 01:38:14
DTS_END                         2022-04-23 01:38:14.3680
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/39416
TRN_ID                          245698
ATT_ID                          654
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: 9332.67 19818 1119921
customer order: creation 178.23 380 21387
customer order: refuse 15.30 359 1836
order to supplier: creation 47.43 1335 5691
order to supplier: removal 3.31 109999 397
invoice (draft): creation 46.31 3634 5557
invoice (draft): removal 53.23 801 6387
invoice accept: apply 41.44 17368 4973
invoice accept: cancel 48.05 811 5766
customer reserve: creation 3835.06 171 460207
customer reserve: removal 4754.38 60 570526
realization accept: apply 86.83 188 10419
realization accept: cancel 89.08 102 10690
payment from customer: creation 61.08 141 7329
payment from customer: removal 5.81 86 697
payment to supplier: creation 51.04 152 6125
payment to supplier: removal 3.53 83 424
service: total inventory turnovers 5.14 1088 617
service: total monetary turnovers 6.68 30 801
service: aggregate perf. data 0.73 25971 88
service: refresh index statistics 0.03 233599 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-04-2022 01:39:43 to 23-04-2022 01:39:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5151
TEST_TIME 2 3690
TEST_TIME 3 3463
TEST_TIME 4 3714
TEST_TIME 5 3784
TEST_TIME 6 4045
TEST_TIME 7 4422
TEST_TIME 8 4853
TEST_TIME 9 5368
TEST_TIME 10 5914
TEST_TIME 11 6332
TEST_TIME 12 6324
TEST_TIME 13 6447
TEST_TIME 14 6709
TEST_TIME 15 6794
TEST_TIME 16 6969
TEST_TIME 17 6995
TEST_TIME 18 7443
TEST_TIME 19 7350
TEST_TIME 20 7596
TEST_TIME 21 8083
TEST_TIME 22 8632
TEST_TIME 23 8800
TEST_TIME 24 8969
TEST_TIME 25 8924
TEST_TIME 26 9371
TEST_TIME 27 9537
TEST_TIME 28 10051
TEST_TIME 29 10931
TEST_TIME 30 10932
TEST_TIME 31 10733
TEST_TIME 32 10764
TEST_TIME 33 10904
TEST_TIME 34 10724
TEST_TIME 35 10515
TEST_TIME 36 10313
TEST_TIME 37 10296
TEST_TIME 38 10342
TEST_TIME 39 10269
TEST_TIME 40 10204
TEST_TIME 41 10149
TEST_TIME 42 10025
TEST_TIME 43 9902
TEST_TIME 44 10135
TEST_TIME 45 10130
TEST_TIME 46 10241
TEST_TIME 47 10112
TEST_TIME 48 10037
TEST_TIME 49 9936
TEST_TIME 50 9935
TEST_TIME 51 10028
TEST_TIME 52 9930
TEST_TIME 53 9893
TEST_TIME 54 9869
TEST_TIME 55 9922
TEST_TIME 56 9889
TEST_TIME 57 9890
TEST_TIME 58 10017
TEST_TIME 59 10032
TEST_TIME 60 10120
TEST_TIME 61 10037
TEST_TIME 62 10081
TEST_TIME 63 10025
TEST_TIME 64 9902
TEST_TIME 65 9921
TEST_TIME 66 9826
TEST_TIME 67 9781
TEST_TIME 68 9759
TEST_TIME 69 9713
TEST_TIME 70 9647
TEST_TIME 71 9616
TEST_TIME 72 9531
TEST_TIME 73 9506
TEST_TIME 74 9566
TEST_TIME 75 9557
TEST_TIME 76 9537
TEST_TIME 77 9537
TEST_TIME 78 9583
TEST_TIME 79 9611
TEST_TIME 80 9632
TEST_TIME 81 9584
TEST_TIME 82 9652
TEST_TIME 83 9781
TEST_TIME 84 9764
TEST_TIME 85 9756
TEST_TIME 86 9806
TEST_TIME 87 9787
TEST_TIME 88 9692
TEST_TIME 89 9682
TEST_TIME 90 9648
TEST_TIME 91 9645
TEST_TIME 92 9596
TEST_TIME 93 9579
TEST_TIME 94 9527
TEST_TIME 95 9483
TEST_TIME 96 9469
TEST_TIME 97 9507
TEST_TIME 98 9540
TEST_TIME 99 9609
TEST_TIME 100 9601
TEST_TIME 101 9602
TEST_TIME 102 9560
TEST_TIME 103 9546
TEST_TIME 104 9629
TEST_TIME 105 9616
TEST_TIME 106 9643
TEST_TIME 107 9652
TEST_TIME 108 9634
TEST_TIME 109 9563
TEST_TIME 110 9534
TEST_TIME 111 9558
TEST_TIME 112 9525
TEST_TIME 113 9522
TEST_TIME 114 9497
TEST_TIME 115 9472
TEST_TIME 116 9400
TEST_TIME 117 9414
TEST_TIME 118 9418
TEST_TIME 119 9397
TEST_TIME 120 9335

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 23-04-2022 01:39:46 to 23-04-2022 01:39:50.

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 1548167 1548167 0 0.00 0 22570 6 0 0
doc_list_biud 1548165 1548165 0 0.00 0 38154 28 0 0
sp_add_invoice_to_stock 5117 4973 144 2.81 14 192583 17368 0 144
sp_cancel_adding_invoice 5766 5766 0 0.00 4 90400 812 0 0
sp_cancel_client_order 1836 1836 0 0.00 4 11833 360 0 0
sp_cancel_customer_reserve 570526 570526 0 0.00 1 38154 61 0 0
sp_cancel_pay_from_customer 697 697 0 0.00 3 3306 86 0 0
sp_cancel_pay_to_supplier 424 424 0 0.00 3 7997 83 0 0
sp_cancel_supplier_invoice 6397 6387 10 0.15 1 16243 802 0 10
sp_cancel_supplier_order 397 397 0 0.00 10 1016011 110000 0 0
sp_cancel_write_off 10690 10690 0 0.00 1 12809 102 0 0
sp_client_order 21387 21387 0 0.00 3 66091 381 0 0
sp_customer_reserve 460207 460207 0 0.00 0 73171 172 0 0
sp_fill_shopping_cart 32634 32634 0 0.00 1 13522 40 0 0
sp_fill_shopping_cart_clo_res 460204 460204 0 0.00 0 24450 14 0 0
sp_get_clo_for_invoice 4969 4969 0 0.00 0 29079 300 0 0
sp_kill_cost_storno 17577 17577 0 0.00 0 5934 55 0 0
sp_kill_qstorno_ret_qs2qd 1158526 1158526 0 0.00 0 38152 33 0 0
sp_kill_qty_storno 610990 610990 0 0.00 0 38153 70 0 0
sp_lock_selected_doc 21329 21329 0 0.00 0 7737 6 0 0
sp_make_cost_storno 28842 28842 0 0.00 0 22508 59 0 0
sp_multiply_rows_for_pdistr 28842 28842 0 0.00 0 17132 37 0 0
sp_multiply_rows_for_qdistr 21387 21387 0 0.00 0 66019 176 0 0
sp_pay_from_customer 7329 7329 0 0.00 4 17153 141 0 0
sp_pay_to_supplier 6125 6125 0 0.00 5 12987 152 0 0
sp_payment_common 13454 13454 0 0.00 1 12070 30 0 0
sp_qd_handle_on_cancel_clo 1836 1836 0 0.00 0 11814 323 0 0
sp_qd_handle_on_invoice_upd_sts 10735 10735 0 0.00 0 12382 321 0 0
sp_qd_handle_on_reserve_upd_sts 10419 10419 0 0.00 0 20678 64 0 0
sp_reserve_write_off 10419 10419 0 0.00 7 24053 189 0 0
sp_supplier_invoice 5557 5557 0 0.00 6 96858 3635 0 0
sp_supplier_order 5691 5691 0 0.00 5 69668 1335 0 0
srv_aggregate_perf_data 88 88 0 0.00 2661 289968 25971 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1728 109411 43086 0 0
srv_find_qd_qs_mism 1072292 1072292 0 0.00 0 11944 5 0 0
srv_make_invnt_saldo 617 617 0 0.00 41 102196 1088 0 0
srv_make_money_saldo 801 801 0 0.00 1 1529 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 218154 245274 233600 0 0
t$perf-abend:sp_add_invoice_to_stock 144 144 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4973 4973 0 0.00 0 25251 177 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 0 3669 96 0 0
t$perf-norm:sp_cancel_client_order 1836 1836 0 0.00 0 3230 14 0 0
t$perf-norm:sp_cancel_customer_reserve 731 731 0 0.00 0 3422 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 697 697 0 0.00 0 17121 39 0 0
t$perf-norm:sp_cancel_pay_to_supplier 424 424 0 0.00 0 3912 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 5572 29 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 22805 1714 0 0
t$perf-norm:sp_cancel_write_off 1042 1042 0 0.00 0 1470 8 0 0
t$perf-norm:sp_client_order 21387 21387 0 0.00 0 18629 19 0 0
t$perf-norm:sp_customer_reserve 1024 1024 0 0.00 0 1382 5 0 0
t$perf-norm:sp_pay_from_customer 7329 7329 0 0.00 0 4191 11 0 0
t$perf-norm:sp_pay_to_supplier 6125 6125 0 0.00 0 4645 8 0 0
t$perf-norm:sp_reserve_write_off 10419 10419 0 0.00 0 22859 18 0 0
t$perf-norm:sp_supplier_invoice 5557 5557 0 0.00 0 18777 27 0 0
t$perf-norm:sp_supplier_order 5691 5691 0 0.00 0 10784 19 0 0
t$perf-norm:srv_aggregate_perf_data 88 88 0 0.00 0 207 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 8 0 0 0
t$perf-norm:srv_make_invnt_saldo 617 617 0 0.00 0 1564 19 0 0
t$perf-norm:srv_make_money_saldo 801 801 0 0.00 0 704 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10226 9938 288 2.81 0 14368 24 0 288
v_all_customers 39110 39110 0 0.00 0 34 1 0 0
v_all_suppliers 31520 31520 0 0.00 0 30 1 0 0
v_all_wares 42774 42774 0 0.00 0 106 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 237 5 0 0
v_cancel_client_order 3672 3672 0 0.00 0 5709 8 0 0
v_cancel_customer_prepayment 1394 1394 0 0.00 0 3273 13 0 0
v_cancel_customer_reserve 1462 1462 0 0.00 0 11714 15 0 0
v_cancel_payment_to_supplier 848 848 0 0.00 0 2266 12 0 0
v_cancel_supplier_invoice 838 818 20 2.38 0 3294 17 0 20
v_cancel_supplier_order 794 794 0 0.00 0 1620 8 0 0
v_cancel_write_off 2084 2084 0 0.00 0 3272 15 0 0
v_max_id_clo_ord 5690 5690 0 0.00 0 241 1 0 0
v_max_id_clo_res 1024 1024 0 0.00 0 1152 11 0 0
v_max_id_ord_sup 5557 5557 0 0.00 0 8060 8 0 0
v_max_non_paid_invoice 1612 1612 0 0.00 0 4527 8 0 0
v_max_non_paid_realizn 3824 3824 0 0.00 0 1701 3 0 0
v_min_id_clo_ord 5690 5690 0 0.00 0 5050 12 0 0
v_min_id_clo_res 1024 1024 0 0.00 0 3005 10 0 0
v_min_id_ord_sup 5557 5557 0 0.00 0 8221 7 0 0
v_min_non_paid_invoice 6125 6125 0 0.00 0 6707 21 0 0
v_min_non_paid_realizn 7329 7329 0 0.00 0 12067 22 0 0
v_our_firm 10674 10674 0 0.00 0 19 1 0 0
v_reserve_write_off 20838 20838 0 0.00 0 14663 10 0 0
x_lock_depdocs_on_canc_invoice 11744 11744 0 0.00 0 36416 121 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 13134 378 0 0
x_make_qstorno_1000_1200 5691 5691 0 0.00 2 68552 1250 0 0
x_make_qstorno_1200_2000 5557 5557 0 0.00 2 96498 3492 0 0
x_make_qstorno_2100_3300 460207 460207 0 0.00 0 73167 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-04-2022 01:39:51 to 23-04-2022 01:39:55.

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
23:08:18 13 8192000000 8810813824 8826101760 14790320 0.180 4 4 5 0 15017056 87456 139280
23:09:19 15887 8192000000 9904026288 10213638144 988148560 10.763 100 86 172 0 1016676112 6119408 22408144
23:10:39 18889 8192000000 9940861488 10260070400 1025076208 11.121 100 89 178 0 1056189872 8059488 23054176
23:12:06 12475 8192000000 9976837936 10307653632 1059165248 11.448 100 89 178 0 1091088752 9366192 22557312
23:13:25 11856 8192000000 10022621280 10365267968 1104327264 11.879 100 81 162 0 1135166496 11415808 19423424
23:14:38 10618 8192000000 10079548768 10433409024 1153090576 12.338 100 81 161 1 1184509200 12025184 19393440
23:15:52 6062 8192000000 10120414992 10487009280 1203561472 12.809 100 77 154 1 1232559408 11751552 17246384
23:17:00 2784 8192000000 10155428048 10530791424 1241013024 13.156 100 87 171 1 1274286928 14493280 18780624
23:18:05 3175 8192000000 10182336256 10564964352 1271954320 13.439 100 84 169 1 1303562080 13180032 18427728
23:19:10 1570 8192000000 10208136464 10592591872 1293455488 13.636 100 93 185 2 1327974864 13335680 21183696
23:20:14 8271 8192000000 10187654416 10597978112 1301007504 13.704 100 36 73 3 1316731776 7706768 8017504
23:21:23 8334 8192000000 10194009824 10610135040 1310713024 13.793 100 24 47 1 1322013936 5996608 5304304
23:22:32 8808 8192000000 10207290960 10624573440 1321134608 13.887 100 19 38 0 1331747168 6581120 4031440
23:23:43 24704 8192000000 10218689040 10634907648 1328113616 13.950 100 34 68 0 1341866752 5901744 7851392
23:25:21 11625 8192000000 10213148320 10638364672 1328185344 13.951 100 24 49 0 1339867168 6182864 5498960
23:26:33 9754 8192000000 10214133136 10634653696 1314426144 13.826 99 23 45 2 1329471568 10199200 4846224
23:27:44 8833 8192000000 10215646688 10625851392 1305952112 13.749 99 30 63 2 1319291536 5390768 7948656
23:28:56 62403 8192000000 10126544160 10527379456 1163690624 12.438 90 36 73 0 1177585216 5194448 8700144
23:31:00 5222 8192000000 10140459520 10538098688 1232451712 13.077 98 35 70 0 1247338656 7289136 7597808
23:32:06 8035 8192000000 10159012464 10562482176 1233384128 13.085 97 17 34 0 1242610800 5455312 3771360
23:33:16 8143 8192000000 10171333008 10570530816 1273111680 13.450 100 28 55 0 1285954080 5810672 7031728
23:34:25 23641 8192000000 10169279664 10585026560 1282487504 13.536 100 30 60 0 1295897952 6800192 6610256
23:35:50 10304 8192000000 10194692480 10608779264 1302691328 13.720 100 31 62 1 1316803072 7119824 6991920
23:37:03 13510 8192000000 10201276624 10616836096 1309541632 13.782 100 13 26 0 1317520880 4783984 3195264
23:38:19 47813 8192000000 10238482928 10647515136 1333580112 13.999 100 34 68 1 1347235072 5656576 7998384
23:40:09 86637 8192000000 10267214592 10688606208 1358836032 14.227 100 43 86 1 1378165952 9902944 9426976
23:43:01 9365 8192000000 10289934320 10709114880 1378433920 14.403 100 46 91 0 1401772448 13136384 10202144
23:44:14 15458 8192000000 10299408016 10723479552 1385366576 14.465 100 36 71 1 1414567680 19880688 9320416
23:45:38 9061 8192000000 10313525216 10733723648 1389872816 14.505 100 51 100 1 1423240304 21195072 12172416
23:46:59 10112 8192000000 10325180416 10753536000 1400207712 14.597 100 47 95 1 1439548608 26940400 12400496
23:48:16 9267 8192000000 10323341168 10751307776 1397467184 14.572 100 58 116 1 1437132768 25288416 14377168
23:49:29 3816 8192000000 10320374832 10734931968 1373777248 14.361 99 70 140 1 1418988608 28460368 16750992
23:50:41 4347 8192000000 10336519920 10757480448 1398702640 14.583 100 59 118 2 1442219040 28026320 15490080
23:51:49 7617 8192000000 10308169696 10722451456 1368315888 14.312 100 60 119 3 1418754688 35716576 14722224
23:53:05 3505 8192000000 10301124272 10715590656 1340808160 14.065 98 62 124 0 1396354160 39909712 15636288
23:54:14 1728 8192000000 10310882192 10712399872 1356471424 14.206 100 83 165 0 1416234496 38925936 20837136
23:55:21 4933 8192000000 10318731488 10721681408 1359195296 14.230 100 72 141 1 1419590304 42339600 18055408
23:56:31 15371 8192000000 10310777824 10734919680 1357566992 14.216 99 35 70 1 1413353280 46175328 9610960
23:57:59 3145 8192000000 10357250208 10759659520 1378234784 14.401 100 85 170 1 1453867232 53415344 22217104
23:59:09 4499 8192000000 10357634656 10768957440 1381080960 14.426 100 72 143 0 1454822624 54840960 18900704
00:00:18 6658 8192000000 10347333824 10767806464 1388005568 14.488 100 48 96 3 1449783888 48109936 13668384
00:01:29 33087 8192000000 10327671232 10757791744 1350245040 14.150 97 30 60 0 1400504352 41856272 8403040
00:03:17 19528 8192000000 10322074496 10752536576 1373508448 14.358 100 40 80 0 1430616240 46246864 10860928
00:04:39 34550 8192000000 10307792992 10750799872 1374974528 14.372 100 18 36 2 1419753952 39742000 5037424
00:06:24 40946 8192000000 10297400352 10743574528 1321857520 13.894 97 18 36 2 1372796240 45311792 5626928
00:08:06 56570 8192000000 10292991664 10732109824 1372595952 14.350 100 18 37 0 1387731488 11556160 3579376
00:10:03 60446 8192000000 10287148128 10738192384 1383346080 14.446 100 21 42 0 1405195408 17598576 4250752
00:12:04 79316 8192000000 10283349760 10722529280 1354122032 14.185 98 19 39 0 1370271296 11164496 4984768
00:15:01 31017 8192000000 10287383824 10729803776 1356274112 14.204 98 29 58 0 1369826288 7368416 6183760
00:16:37 65151 8192000000 10296122832 10750443520 1386986304 14.479 99 33 66 1 1403731840 9387648 7357888
00:18:48 11995 8192000000 10312518976 10767319040 1401277792 14.606 100 15 30 1 1414468416 9857920 3332704
00:20:02 28902 8192000000 10316815168 10768109568 1407580576 14.662 100 24 48 0 1425452336 11930816 5940944
00:21:42 25996 8192000000 10342732432 10784018432 1395312784 14.553 98 36 72 1 1419440368 14670464 9457120
00:23:17 56330 8192000000 10316536880 10785181696 1403867808 14.629 100 30 60 0 1431730640 19931696 7931136
00:25:19 32025 8192000000 10302508912 10762113024 1379496848 14.412 98 14 28 0 1394996544 12525568 2974128
00:26:52 69384 8192000000 10308057376 10781999104 1400576800 14.600 100 7 14 0 1418015616 15712576 1726240
00:29:16 109411 8192000000 10284936448 10724147200 1268647536 13.409 90 16 32 0 1283247664 11191712 3408416
00:32:06 33465 8192000000 10295336064 10741460992 1362886240 14.263 98 40 78 5 1381517872 10127120 8504512
00:33:47 64541 8192000000 10280124000 10730000384 1333406736 13.998 97 22 44 0 1352836512 14324576 5105200
00:36:01 62570 8192000000 10263597568 10716434432 1307511808 13.763 95 15 30 1 1323445216 12299264 3634144
00:38:05 88075 8192000000 10265325632 10707951616 1274923600 13.467 93 20 40 0 1290820736 12024400 3872736
00:41:16 52563 8192000000 10273527344 10723373056 1340123536 14.059 98 23 44 2 1353477312 8664208 4689568
00:43:36 59768 8192000000 10274621616 10730508288 1333560832 13.999 97 22 45 0 1346108592 7584800 4962960
00:45:39 51072 8192000000 10274775840 10738888704 1375246368 14.374 100 27 52 1 1391334880 10358960 5729552
00:49:03 53967 8192000000 10262678096 10721353728 1340953424 14.066 97 17 35 1 1358306384 12742672 4610288
00:51:08 53038 8192000000 10264215888 10728890368 1337962064 14.039 97 13 26 0 1356636592 15065328 3609200
00:53:14 62572 8192000000 10259596640 10712969216 1323559152 13.909 97 17 34 0 1346862960 19735248 3568560
00:55:24 57407 8192000000 10283069024 10749009920 1373974496 14.363 100 19 38 0 1390600224 12283168 4342560
00:57:22 62576 8192000000 10286936656 10735017984 1306231920 13.752 95 23 46 1 1329513632 18360560 4921152
00:59:26 38603 8192000000 10301823200 10743267328 1362310112 14.258 99 34 68 0 1382476416 12665520 7500784
01:01:07 82772 8192000000 10295091280 10763034624 1366116720 14.292 99 12 25 1 1388221456 19403312 2701424
01:03:29 33776 8192000000 10293701472 10746867712 1374165184 14.364 100 35 70 0 1391422608 9622320 7635104
01:05:08 99756 8192000000 10306284112 10757210112 1385065872 14.462 100 21 42 0 1400711776 10605136 5040768
01:07:55 47543 8192000000 10309737824 10773078016 1392648608 14.529 100 34 68 1 1416178400 16470944 7058848
01:10:08 65618 8192000000 10265868624 10735902720 1332479264 13.990 95 13 27 0 1345573952 10335392 2759296
01:12:22 33797 8192000000 10281784640 10750984192 1345576016 14.108 97 13 26 0 1359524240 10585632 3362592
01:14:00 46015 8192000000 10286946640 10750169088 1340743776 14.064 96 26 53 1 1366403472 19301296 6358400
01:15:55 74939 8192000000 10302721888 10771738624 1370657536 14.333 99 12 24 1 1394967136 21156832 3152768
01:18:16 47248 8192000000 10297244848 10749665280 1359688240 14.235 98 32 64 1 1375790512 9059872 7042400
01:20:04 46333 8192000000 10292015344 10744983552 1357318368 14.213 98 25 50 0 1375461696 12495344 5647984
01:21:52 75172 8192000000 10259903200 10702958592 1302026000 13.714 95 22 44 2 1321711824 14588944 5096880
01:24:08 45834 8192000000 10257723440 10704072704 1322117200 13.896 98 32 64 0 1345042064 16051840 6873024
01:25:58 59477 8192000000 10269848144 10710638592 1341995296 14.075 99 9 18 0 1352825472 8903296 1926880
01:27:58 47945 8192000000 10258218016 10707988480 1320790192 13.884 97 39 77 2 1339648432 9855616 9002624
01:29:57 35728 8192000000 10256812336 10722983936 1346250096 14.114 99 22 44 1 1365894896 15033216 4611584
01:31:33 48320 8192000000 10243838528 10703470592 1341563136 14.072 99 8 16 0 1352521888 9341424 1617328
01:33:31 93938 8192000000 10263744272 10717372416 1327423056 13.944 97 15 30 1 1341874736 10658464 3793216
01:36:07 63059 8192000000 10265484528 10731184128 1334634048 14.009 98 5 10 0 1345105312 9479616 991648
01:38:10 15011 8192000000 8972623920 9163489280 130537488 1.568 10 4 7 0 135487872 4037296 913088

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-04-2022 01:39:57 to 23-04-2022 01:40:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 144 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-04-2022 01:40:12 to 23-04-2022 01:40:14.

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          248117
MON$OLDEST_ACTIVE               248118
MON$OLDEST_SNAPSHOT             248118
MON$NEXT_TRANSACTION            248118
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                       2048672
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.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:1483: 
:1484: mscs-test-161.budzdorov.ru    Sat Apr 23 01:38:26 2022
:1485:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1486: 
:1487: 
:1488: mscs-test-161.budzdorov.ru    Sat Apr 23 01:38:29 2022
:1489:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1490: 
--- end of diff output ---

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

23.04.22 01:40:15. 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-04-23 01:40:18 No severe PSQL-related problems occured
23.04.22 01:40:19. End of report.