Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.01.2023 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 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 15242156 kB
MemAvailable 30413268 kB
Buffers 0 kB
Cached 16457296 kB
SwapCached 1632 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-01-22 19:38:35
DTS_END                         2023-01-22 19:38:35.5280
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/56580
TRN_ID                          246043
ATT_ID                          677
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: 9602.34 19042 1152281
customer order: creation 183.63 332 22036
customer order: refuse 16.00 375 1920
order to supplier: creation 48.43 1257 5812
order to supplier: removal 3.38 112418 406
invoice (draft): creation 48.37 3111 5804
invoice (draft): removal 54.63 910 6555
invoice accept: apply 43.74 16939 5249
invoice accept: cancel 49.13 838 5896
customer reserve: creation 4034.73 167 484167
customer reserve: removal 4813.68 61 577642
realization accept: apply 89.01 172 10681
realization accept: cancel 82.03 114 9844
payment from customer: creation 61.65 103 7398
payment from customer: removal 5.82 83 698
payment to supplier: creation 51.75 111 6210
payment to supplier: removal 3.49 65 419
service: total inventory turnovers 4.93 1162 591
service: total monetary turnovers 7.01 26 841
service: aggregate perf. data 0.90 19446 108
service: refresh index statistics 0.03 223146 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-01-2023 19:39:38 to 22-01-2023 19:39:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3781
TEST_TIME 2 4949
TEST_TIME 3 5549
TEST_TIME 4 5551
TEST_TIME 5 5917
TEST_TIME 6 5430
TEST_TIME 7 5300
TEST_TIME 8 5787
TEST_TIME 9 5679
TEST_TIME 10 6310
TEST_TIME 11 6617
TEST_TIME 12 6816
TEST_TIME 13 6968
TEST_TIME 14 7334
TEST_TIME 15 7256
TEST_TIME 16 7537
TEST_TIME 17 7537
TEST_TIME 18 7533
TEST_TIME 19 7869
TEST_TIME 20 7984
TEST_TIME 21 7921
TEST_TIME 22 8550
TEST_TIME 23 8693
TEST_TIME 24 8889
TEST_TIME 25 8769
TEST_TIME 26 8903
TEST_TIME 27 9296
TEST_TIME 28 10012
TEST_TIME 29 10563
TEST_TIME 30 11052
TEST_TIME 31 11246
TEST_TIME 32 11052
TEST_TIME 33 10918
TEST_TIME 34 10916
TEST_TIME 35 10854
TEST_TIME 36 10772
TEST_TIME 37 10666
TEST_TIME 38 10560
TEST_TIME 39 10564
TEST_TIME 40 10827
TEST_TIME 41 10808
TEST_TIME 42 10623
TEST_TIME 43 10442
TEST_TIME 44 10355
TEST_TIME 45 10241
TEST_TIME 46 10072
TEST_TIME 47 10044
TEST_TIME 48 10032
TEST_TIME 49 10097
TEST_TIME 50 10134
TEST_TIME 51 10178
TEST_TIME 52 10065
TEST_TIME 53 10035
TEST_TIME 54 9926
TEST_TIME 55 9836
TEST_TIME 56 9805
TEST_TIME 57 9812
TEST_TIME 58 9803
TEST_TIME 59 9856
TEST_TIME 60 9849
TEST_TIME 61 9906
TEST_TIME 62 9917
TEST_TIME 63 9903
TEST_TIME 64 9915
TEST_TIME 65 9998
TEST_TIME 66 9934
TEST_TIME 67 9933
TEST_TIME 68 9935
TEST_TIME 69 10000
TEST_TIME 70 9997
TEST_TIME 71 9995
TEST_TIME 72 10030
TEST_TIME 73 9993
TEST_TIME 74 9966
TEST_TIME 75 9922
TEST_TIME 76 9914
TEST_TIME 77 9821
TEST_TIME 78 9833
TEST_TIME 79 9826
TEST_TIME 80 9796
TEST_TIME 81 9760
TEST_TIME 82 9761
TEST_TIME 83 9695
TEST_TIME 84 9624
TEST_TIME 85 9594
TEST_TIME 86 9564
TEST_TIME 87 9576
TEST_TIME 88 9582
TEST_TIME 89 9553
TEST_TIME 90 9594
TEST_TIME 91 9608
TEST_TIME 92 9679
TEST_TIME 93 9763
TEST_TIME 94 9751
TEST_TIME 95 9788
TEST_TIME 96 9813
TEST_TIME 97 9851
TEST_TIME 98 9805
TEST_TIME 99 9876
TEST_TIME 100 9893
TEST_TIME 101 9898
TEST_TIME 102 9932
TEST_TIME 103 9846
TEST_TIME 104 9845
TEST_TIME 105 9797
TEST_TIME 106 9783
TEST_TIME 107 9744
TEST_TIME 108 9750
TEST_TIME 109 9723
TEST_TIME 110 9728
TEST_TIME 111 9682
TEST_TIME 112 9650
TEST_TIME 113 9625
TEST_TIME 114 9638
TEST_TIME 115 9592
TEST_TIME 116 9637
TEST_TIME 117 9655
TEST_TIME 118 9658
TEST_TIME 119 9646
TEST_TIME 120 9611

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-01-2023 19:39:42 to 22-01-2023 19:39:45.

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 1600106 1600106 0 0.00 0 11875 6 0 0
doc_list_biud 1600086 1600086 0 0.00 0 22060 29 0 0
sp_add_invoice_to_stock 5363 5249 114 2.12 24 163829 16940 0 114
sp_cancel_adding_invoice 5896 5896 0 0.00 4 94944 838 0 0
sp_cancel_client_order 1920 1920 0 0.00 4 15461 375 0 0
sp_cancel_customer_reserve 577642 577642 0 0.00 0 20410 62 0 0
sp_cancel_pay_from_customer 698 698 0 0.00 2 7741 84 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 3 1212 65 0 0
sp_cancel_supplier_invoice 6563 6555 8 0.12 1 22084 910 0 8
sp_cancel_supplier_order 406 406 0 0.00 7 999160 112418 0 0
sp_cancel_write_off 9844 9844 0 0.00 1 11374 114 0 0
sp_client_order 22036 22036 0 0.00 3 16595 332 0 0
sp_customer_reserve 484167 484167 0 0.00 0 33386 168 0 0
sp_fill_shopping_cart 33646 33646 0 0.00 1 10500 36 0 0
sp_fill_shopping_cart_clo_res 484137 484137 0 0.00 0 13415 12 0 0
sp_get_clo_for_invoice 5219 5219 0 0.00 0 14373 263 0 0
sp_kill_cost_storno 16857 16857 0 0.00 0 7736 55 0 0
sp_kill_qstorno_ret_qs2qd 1172089 1172089 0 0.00 0 22044 34 0 0
sp_kill_qty_storno 618163 618163 0 0.00 0 22054 73 0 0
sp_lock_selected_doc 22067 22067 0 0.00 0 8779 6 0 0
sp_make_cost_storno 29508 29508 0 0.00 0 11816 50 0 0
sp_multiply_rows_for_pdistr 29508 29508 0 0.00 0 9980 25 0 0
sp_multiply_rows_for_qdistr 22036 22036 0 0.00 0 16230 147 0 0
sp_pay_from_customer 7398 7398 0 0.00 4 19325 103 0 0
sp_pay_to_supplier 6210 6210 0 0.00 4 22246 112 0 0
sp_payment_common 13608 13608 0 0.00 1 22228 29 0 0
sp_qd_handle_on_cancel_clo 1920 1920 0 0.00 0 15441 351 0 0
sp_qd_handle_on_invoice_upd_sts 11115 11115 0 0.00 0 17936 327 0 0
sp_qd_handle_on_reserve_upd_sts 10681 10681 0 0.00 0 16774 68 0 0
sp_reserve_write_off 10681 10681 0 0.00 5 16843 172 0 0
sp_supplier_invoice 5804 5804 0 0.00 7 94241 3111 0 0
sp_supplier_order 5812 5812 0 0.00 5 38994 1258 0 0
srv_aggregate_perf_data 108 108 0 0.00 2757 204971 19446 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 1889 125855 40382 0 0
srv_find_qd_qs_mism 1101790 1101790 0 0.00 0 11253 5 0 0
srv_make_invnt_saldo 591 591 0 0.00 36 82262 1163 0 0
srv_make_money_saldo 841 841 0 0.00 0 826 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 78405 337807 223146 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5249 5249 0 0.00 0 16370 192 0 0
t$perf-norm:sp_cancel_adding_invoice 464 464 0 0.00 0 7801 118 0 0
t$perf-norm:sp_cancel_client_order 1920 1920 0 0.00 0 6744 14 0 0
t$perf-norm:sp_cancel_customer_reserve 760 760 0 0.00 0 3845 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 698 698 0 0.00 0 298 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 4182 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 429 429 0 0.00 0 1568 19 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 24660 1832 0 0
t$perf-norm:sp_cancel_write_off 1072 1072 0 0.00 0 9563 23 0 0
t$perf-norm:sp_client_order 22036 22036 0 0.00 0 7460 18 0 0
t$perf-norm:sp_customer_reserve 1121 1121 0 0.00 0 5097 17 0 0
t$perf-norm:sp_pay_from_customer 7398 7398 0 0.00 0 6292 11 0 0
t$perf-norm:sp_pay_to_supplier 6210 6210 0 0.00 0 6911 12 0 0
t$perf-norm:sp_reserve_write_off 10681 10681 0 0.00 0 11176 12 0 0
t$perf-norm:sp_supplier_invoice 5804 5804 0 0.00 0 14857 19 0 0
t$perf-norm:sp_supplier_order 5812 5812 0 0.00 0 7293 14 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 4233 66 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 83 3 0 0
t$perf-norm:srv_make_invnt_saldo 591 591 0 0.00 0 613 17 0 0
t$perf-norm:srv_make_money_saldo 841 841 0 0.00 0 1538 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 817 272 0 0
v_add_invoice_to_stock 10664 10436 228 2.13 0 9365 19 0 228
v_all_customers 39902 39902 0 0.00 0 20 1 0 0
v_all_suppliers 32590 32590 0 0.00 0 26 1 0 0
v_all_wares 44070 44070 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 928 928 0 0.00 0 3339 11 0 0
v_cancel_client_order 3840 3840 0 0.00 0 1335 4 0 0
v_cancel_customer_prepayment 1396 1396 0 0.00 0 1206 6 0 0
v_cancel_customer_reserve 1520 1520 0 0.00 0 2983 7 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 1087 6 0 0
v_cancel_supplier_invoice 874 858 16 1.83 0 2034 17 0 16
v_cancel_supplier_order 812 812 0 0.00 0 1452 7 0 0
v_cancel_write_off 2144 2144 0 0.00 0 6496 19 0 0
v_max_id_clo_ord 5811 5811 0 0.00 0 1253 2 0 0
v_max_id_clo_res 1121 1121 0 0.00 0 4773 23 0 0
v_max_id_ord_sup 5800 5800 0 0.00 0 4496 4 0 0
v_max_non_paid_invoice 1526 1526 0 0.00 0 1111 4 0 0
v_max_non_paid_realizn 3929 3929 0 0.00 0 2705 4 0 0
v_min_id_clo_ord 5811 5811 0 0.00 0 2302 6 0 0
v_min_id_clo_res 1121 1121 0 0.00 0 4886 13 0 0
v_min_id_ord_sup 5800 5800 0 0.00 0 4976 6 0 0
v_min_non_paid_invoice 6210 6210 0 0.00 0 22222 19 0 0
v_min_non_paid_realizn 7398 7398 0 0.00 0 19318 23 0 0
v_our_firm 11106 11106 0 0.00 0 19 1 0 0
v_reserve_write_off 21362 21362 0 0.00 0 8918 5 0 0
x_lock_depdocs_on_canc_invoice 12022 12022 0 0.00 0 20137 124 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 9129 416 0 0
x_make_qstorno_1000_1200 5812 5812 0 0.00 1 38970 1187 0 0
x_make_qstorno_1200_2000 5804 5804 0 0.00 3 94231 2980 0 0
x_make_qstorno_2100_3300 484167 484167 0 0.00 0 33386 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-01-2023 19:39:46 to 22-01-2023 19:39:50.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:39 1 8192000000 8798466208 8809709568 4941152 0.060 2 1 2 0 5040752 56816 42784
17:09:40 26117 8192000000 9896136960 10205470720 975296256 10.638 100 90 180 0 1005850032 6548352 24005424
17:11:20 21897 8192000000 9941539456 10265673728 1026126368 11.131 100 90 179 1 1058236832 8756560 23353904
17:12:47 14486 8192000000 9981298848 10317361152 1070976576 11.561 100 77 154 0 1099276208 9186896 19112736
17:14:06 9240 8192000000 10038025616 10388279296 1126025664 12.084 100 75 150 0 1153986112 10462016 17498432
17:15:19 7286 8192000000 10080075264 10442633216 1173361856 12.528 100 68 136 0 1200366288 12015696 14988736
17:16:29 3740 8192000000 10127699088 10497916928 1216368784 12.928 100 76 152 1 1246321552 12955712 16997056
17:17:36 2313 8192000000 10168544160 10546454528 1255769600 13.291 100 83 166 3 1291378480 18580288 17028592
17:18:41 1640 8192000000 10193562912 10576642048 1285116208 13.560 100 78 154 0 1313908944 11173888 17618848
17:19:43 6368 8192000000 10192366048 10600620032 1297479104 13.672 100 56 112 0 1322227216 12150832 12597280
17:20:57 3818 8192000000 10199809344 10606198784 1305903248 13.749 100 40 80 0 1327933648 13605760 8424640
17:22:02 10198 8192000000 10208143168 10622140416 1319147776 13.869 100 20 41 1 1328873152 5241056 4484320
17:23:17 10850 8192000000 10208739248 10628726784 1325545488 13.927 100 33 67 0 1339881504 6541776 7794240
17:24:28 13096 8192000000 10236700208 10640035840 1328000224 13.949 100 71 147 0 1350622944 6669920 15952800
17:25:42 6332 8192000000 10230461952 10650251264 1331515088 13.981 100 31 61 0 1344725952 6587216 6623648
17:26:48 12560 8192000000 10233151424 10656522240 1337544640 14.035 100 20 38 0 1347828384 6090272 4193472
17:28:02 34237 8192000000 10203063440 10617835520 1297420112 13.672 98 46 90 1 1314373712 6519424 10434176
17:29:37 10339 8192000000 10186990432 10593382400 1246907824 13.210 95 22 43 0 1257171936 5089040 5175072
17:30:53 6758 8192000000 10127840960 10517856256 1191968768 12.702 95 22 45 0 1202544000 5157808 5417424
17:32:00 71147 8192000000 10121694656 10524196864 1169356512 12.491 93 34 68 1 1182269152 5344128 7568512
17:34:12 8378 8192000000 10145808368 10550198272 1255569456 13.289 99 18 36 2 1264691792 4943008 4179328
17:35:21 8142 8192000000 10178912512 10583543808 1281679200 13.528 100 17 33 0 1290615648 5280592 3655856
17:36:36 20433 8192000000 10200192048 10606084096 1298312896 13.680 100 14 28 1 1306950496 5465536 3172064
17:37:57 23474 8192000000 10203157984 10618408960 1308706608 13.774 100 22 43 0 1318291472 5075552 4509312
17:39:28 20881 8192000000 10245020368 10652131328 1335440848 14.016 100 51 99 1 1355778768 9274272 11063648
17:40:51 5367 8192000000 10263842752 10672037888 1352110592 14.166 100 64 126 1 1378772160 12201024 14460544
17:42:00 7637 8192000000 10266648992 10683781120 1363394320 14.268 100 45 88 0 1384142832 10666000 10082512
17:43:13 21935 8192000000 10276645648 10701869056 1374221168 14.365 100 39 79 1 1396160144 12380064 9558912
17:44:43 10896 8192000000 10295957872 10718625792 1385706672 14.468 100 41 81 0 1408884480 14043920 9133888
17:45:57 8887 8192000000 10305022624 10728865792 1391024368 14.515 100 50 100 0 1421334560 17664816 12645376
17:47:18 10126 8192000000 10329904384 10755211264 1406475168 14.653 100 46 91 1 1441162288 23468528 11218592
17:48:33 5280 8192000000 10346709008 10769371136 1419465264 14.768 100 62 123 0 1459825136 25242304 15117568
17:49:45 3331 8192000000 10345138976 10769858560 1413954368 14.719 100 58 113 2 1452777392 24432192 14390832
17:50:53 3487 8192000000 10368595552 10790457344 1424336528 14.811 100 64 129 0 1471489984 30935904 16217552
17:52:07 4229 8192000000 10352701808 10778828800 1414638272 14.725 100 49 96 2 1461325792 34099552 12587968
17:53:21 5214 8192000000 10364103520 10787254272 1408723776 14.673 99 61 122 2 1460208912 36731696 14753440
17:54:34 8717 8192000000 10360386880 10786500608 1413007760 14.711 100 44 88 0 1467551856 43206256 11337840
17:55:52 1888 8192000000 10365098384 10782765056 1409023424 14.675 100 77 154 3 1473120176 43257328 20839424
17:56:58 6621 8192000000 10369397776 10780635136 1405476944 14.644 100 65 126 0 1473941344 52461264 16003136
17:58:12 15127 8192000000 10360384736 10783690752 1397575280 14.573 99 47 95 0 1462633632 52093488 12964864
17:59:33 3063 8192000000 10399684288 10809049088 1410712128 14.690 100 82 161 0 1491291488 60054768 20524592
18:00:39 8719 8192000000 10366182208 10802872320 1406589552 14.654 100 45 89 0 1478893472 59911184 12392736
18:02:00 6653 8192000000 10353039696 10773622784 1378650128 14.404 99 49 95 0 1448893088 57410784 12832176
18:03:11 10356 8192000000 10355011904 10777858048 1394089200 14.542 100 40 80 0 1462666928 57663760 10913968
18:04:23 12388 8192000000 10364406800 10799071232 1387412288 14.483 100 35 70 0 1467088976 69254256 10422432
18:05:41 34259 8192000000 10324415376 10763980800 1356176512 14.203 99 28 56 0 1435051104 69808016 9066576
18:07:23 27344 8192000000 10295481408 10728263680 1354442608 14.187 99 41 84 0 1401632768 37151360 10038800
18:08:58 48878 8192000000 10275403552 10734542848 1345621536 14.108 99 10 17 0 1369691296 22171152 1898608
18:10:48 47922 8192000000 10286112608 10720157696 1369957392 14.327 100 14 28 0 1384264592 11527360 2779840
18:12:37 41672 8192000000 10252816784 10678394880 1317863888 13.857 97 10 21 0 1330599344 10756048 1979408
18:14:19 56314 8192000000 10274014480 10705383424 1347582000 14.126 99 32 62 1 1362219440 7710608 6926832
18:16:24 36144 8192000000 10302155664 10723536896 1341587280 14.072 98 45 90 0 1362528944 10513808 10427856
18:18:07 112892 8192000000 10288337568 10741133312 1383413200 14.447 100 9 18 0 1402790960 17173696 2204064
18:21:04 105427 8192000000 10286732960 10733862912 1377563232 14.395 100 26 53 0 1394480624 10219408 6697984
18:23:50 39795 8192000000 10308348160 10753675264 1386749680 14.477 100 40 77 1 1413151104 17708336 8693088
18:25:32 23439 8192000000 10290065456 10740752384 1379809312 14.415 99 26 52 0 1397275536 10632176 6834048
18:26:57 14984 8192000000 10320207056 10764128256 1391068784 14.515 100 57 112 2 1417666608 12597312 14000512
18:28:17 36974 8192000000 10296473328 10761711616 1367170176 14.302 98 24 47 0 1390006112 17388800 5447136
18:30:00 32775 8192000000 10291633312 10755674112 1387680128 14.485 100 7 14 2 1401254640 12136080 1438432
18:31:35 32250 8192000000 10279346672 10741481472 1339426656 14.052 96 18 36 1 1356133568 12639760 4067152
18:33:37 69695 8192000000 10285566320 10746064896 1370237120 14.329 99 23 46 0 1385798368 10164448 5396800
18:35:48 79351 8192000000 10288734208 10757013504 1371799888 14.343 99 13 26 0 1384226880 8788752 3638240
18:38:31 55370 8192000000 10274441360 10744537088 1352810832 14.173 98 11 23 0 1363899120 8452848 2635440
18:40:28 35328 8192000000 10294892608 10754060288 1377160592 14.391 99 17 34 0 1389694160 8908384 3625184
18:42:05 21037 8192000000 10303364880 10750152704 1386477760 14.474 100 40 79 1 1405968928 10769584 8721584
18:43:26 74361 8192000000 10269061760 10711703552 1311473056 13.799 95 19 38 0 1328237936 12513568 4251312
18:45:43 51689 8192000000 10289692976 10731560960 1371848528 14.344 100 26 53 0 1388702832 11117040 5737264
18:47:36 69822 8192000000 10266903472 10713718784 1316984400 13.849 96 15 32 0 1329809568 9439136 3386032
18:49:54 37787 8192000000 10277400560 10720043008 1347083200 14.121 99 13 26 0 1362221728 12309232 2829296
18:51:33 114370 8192000000 10268440752 10722746368 1358153952 14.221 100 23 46 1 1378371568 14545232 5672384
18:54:42 42810 8192000000 10273356336 10708090880 1353726608 14.181 99 50 99 1 1374730768 9623904 11380256
18:56:36 33943 8192000000 10247779040 10702159872 1334329376 14.006 98 20 40 0 1346475264 7632480 4513408
18:58:12 42809 8192000000 10240325408 10689912832 1326863600 13.939 98 13 26 0 1336299568 6729968 2706000
19:00:10 64374 8192000000 10247324912 10708131840 1319265488 13.870 97 15 30 0 1336117552 13635936 3216128
19:02:15 29568 8192000000 10251375440 10699952128 1334095312 14.004 98 42 85 1 1348986336 5198016 9693008
19:03:50 77221 8192000000 10257631696 10727571456 1361440928 14.250 100 13 26 1 1376510704 12185008 2884768
19:06:07 41383 8192000000 10275786624 10732683264 1349324208 14.141 99 16 32 0 1369825360 17020224 3480928
19:07:49 24789 8192000000 10297091344 10743242752 1355321712 14.195 99 24 48 0 1382788416 21777488 5689216
19:09:32 72915 8192000000 10282137248 10740043776 1368297728 14.312 99 26 52 1 1394886976 20702272 5886976
19:12:01 45490 8192000000 10297666304 10739585024 1380900704 14.425 100 31 59 0 1394137120 7278288 5958128
19:13:48 67096 8192000000 10311642496 10760794112 1387844480 14.487 100 17 35 0 1404114864 12235456 4034928
19:16:01 32052 8192000000 10314066464 10748796928 1375091728 14.373 99 30 60 0 1391014448 9206864 6715856
19:17:35 101934 8192000000 10297532256 10750214144 1332818272 13.993 96 8 16 0 1346315120 12186720 1310128
19:20:23 125855 8192000000 10293382736 10758086656 1380750688 14.423 99 18 36 1 1398845008 14144496 3949824
19:23:39 46562 8192000000 10283978944 10739859456 1358214768 14.221 98 18 36 0 1373249120 10672000 4362352
19:25:32 49601 8192000000 10257082032 10720804864 1343139840 14.086 98 19 39 0 1363779392 16013744 4625808
19:27:25 83485 8192000000 10282618272 10726084608 1350696880 14.154 98 12 24 0 1362172080 8632928 2842272
19:29:49 78577 8192000000 10267992912 10732634112 1350512832 14.152 98 18 36 1 1366346944 11518944 4315168
19:32:09 67377 8192000000 10279928240 10751979520 1352974656 14.174 98 6 12 0 1372680528 18251136 1454736
19:34:17 78076 8192000000 10241638720 10701684736 1246192288 13.203 91 11 22 0 1264742384 15973904 2576192
19:36:36 50021 8192000000 10275911536 10722586624 1354250768 14.186 100 28 55 1 1370016496 10012720 5753008
19:38:26 9259 8192000000 10296472624 10737455104 1372159248 14.346 100 48 96 0 1396562432 14034000 10369184

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 22-01-2023 19:39:52 to 22-01-2023 19:40:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 114 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-01-2023 19:40:09 to 22-01-2023 19:40:11.

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          246696
MON$OLDEST_ACTIVE               246697
MON$OLDEST_SNAPSHOT             246697
MON$NEXT_TRANSACTION            246697
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                       2038624
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33654 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33654 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33654 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33654 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 ---
:331: 
:332: mscs-test-161.budzdorov.ru    Sun Jan 22 19:38:50 2023
:333:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:334: 
:335: 
:336: mscs-test-161.budzdorov.ru    Sun Jan 22 19:38:51 2023
:337:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:338: 
:339: 
:340: mscs-test-161.budzdorov.ru    Sun Jan 22 19:38:51 2023
:341:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:342: 
--- end of diff output ---

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

22.01.23 19:40:12. 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
2023-01-22 19:40:14 No severe PSQL-related problems occured
22.01.23 19:40:16. End of report.