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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-10-05 19:38:32
DTS_END                         2022-10-05 19:38:32.1760
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/45644
TRN_ID                          235982
ATT_ID                          701
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: 9092.47 22093 1091096
customer order: creation 167.89 381 20147
customer order: refuse 14.85 392 1782
order to supplier: creation 44.56 1361 5347
order to supplier: removal 3.48 105320 418
invoice (draft): creation 44.43 3758 5332
invoice (draft): removal 52.50 832 6300
invoice accept: apply 40.22 17601 4826
invoice accept: cancel 47.79 851 5735
customer reserve: creation 3684.37 173 442124
customer reserve: removal 4690.54 61 562865
realization accept: apply 82.32 223 9878
realization accept: cancel 93.62 115 11234
payment from customer: creation 56.83 148 6820
payment from customer: removal 5.74 99 689
payment to supplier: creation 47.92 147 5750
payment to supplier: removal 3.52 80 422
service: total inventory turnovers 4.63 1425 555
service: total monetary turnovers 6.28 29 754
service: aggregate perf. data 0.95 15638 114
service: refresh index statistics 0.03 293234 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-10-2022 19:39:35 to 05-10-2022 19:39:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5032
TEST_TIME 2 3047
TEST_TIME 3 4330
TEST_TIME 4 4380
TEST_TIME 5 4833
TEST_TIME 6 4565
TEST_TIME 7 4553
TEST_TIME 8 4556
TEST_TIME 9 4776
TEST_TIME 10 5474
TEST_TIME 11 5680
TEST_TIME 12 5660
TEST_TIME 13 5728
TEST_TIME 14 6167
TEST_TIME 15 6320
TEST_TIME 16 6562
TEST_TIME 17 6732
TEST_TIME 18 7039
TEST_TIME 19 7330
TEST_TIME 20 7916
TEST_TIME 21 7841
TEST_TIME 22 8013
TEST_TIME 23 8217
TEST_TIME 24 8771
TEST_TIME 25 8753
TEST_TIME 26 8754
TEST_TIME 27 8755
TEST_TIME 28 8935
TEST_TIME 29 9647
TEST_TIME 30 10257
TEST_TIME 31 10412
TEST_TIME 32 10203
TEST_TIME 33 10175
TEST_TIME 34 10245
TEST_TIME 35 10143
TEST_TIME 36 10109
TEST_TIME 37 9970
TEST_TIME 38 9878
TEST_TIME 39 9688
TEST_TIME 40 9692
TEST_TIME 41 9674
TEST_TIME 42 9568
TEST_TIME 43 9454
TEST_TIME 44 9416
TEST_TIME 45 9361
TEST_TIME 46 9200
TEST_TIME 47 9334
TEST_TIME 48 9182
TEST_TIME 49 9150
TEST_TIME 50 9258
TEST_TIME 51 9285
TEST_TIME 52 9264
TEST_TIME 53 9172
TEST_TIME 54 9186
TEST_TIME 55 9192
TEST_TIME 56 9225
TEST_TIME 57 9182
TEST_TIME 58 9235
TEST_TIME 59 9323
TEST_TIME 60 9332
TEST_TIME 61 9330
TEST_TIME 62 9308
TEST_TIME 63 9388
TEST_TIME 64 9378
TEST_TIME 65 9394
TEST_TIME 66 9460
TEST_TIME 67 9407
TEST_TIME 68 9404
TEST_TIME 69 9372
TEST_TIME 70 9315
TEST_TIME 71 9293
TEST_TIME 72 9291
TEST_TIME 73 9232
TEST_TIME 74 9181
TEST_TIME 75 9161
TEST_TIME 76 9157
TEST_TIME 77 9192
TEST_TIME 78 9145
TEST_TIME 79 9101
TEST_TIME 80 9141
TEST_TIME 81 9186
TEST_TIME 82 9214
TEST_TIME 83 9284
TEST_TIME 84 9291
TEST_TIME 85 9238
TEST_TIME 86 9285
TEST_TIME 87 9323
TEST_TIME 88 9303
TEST_TIME 89 9270
TEST_TIME 90 9414
TEST_TIME 91 9423
TEST_TIME 92 9462
TEST_TIME 93 9503
TEST_TIME 94 9448
TEST_TIME 95 9367
TEST_TIME 96 9378
TEST_TIME 97 9373
TEST_TIME 98 9325
TEST_TIME 99 9314
TEST_TIME 100 9256
TEST_TIME 101 9221
TEST_TIME 102 9193
TEST_TIME 103 9246
TEST_TIME 104 9184
TEST_TIME 105 9190
TEST_TIME 106 9230
TEST_TIME 107 9217
TEST_TIME 108 9198
TEST_TIME 109 9161
TEST_TIME 110 9141
TEST_TIME 111 9120
TEST_TIME 112 9109
TEST_TIME 113 9112
TEST_TIME 114 9108
TEST_TIME 115 9088
TEST_TIME 116 9090
TEST_TIME 117 9087
TEST_TIME 118 9130
TEST_TIME 119 9141
TEST_TIME 120 9093

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-10-2022 19:39:39 to 05-10-2022 19:39:42.

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 1501070 1501070 0 0.00 0 25458 6 0 0
doc_list_biud 1501070 1501070 0 0.00 0 45355 29 0 0
sp_add_invoice_to_stock 4908 4826 82 1.67 43 196803 17601 0 82
sp_cancel_adding_invoice 5735 5735 0 0.00 4 56986 852 0 0
sp_cancel_client_order 1782 1782 0 0.00 4 15950 392 0 0
sp_cancel_customer_reserve 562865 562865 0 0.00 0 26554 61 0 0
sp_cancel_pay_from_customer 689 689 0 0.00 3 15464 99 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 3 6176 81 0 0
sp_cancel_supplier_invoice 6308 6300 8 0.12 1 45366 832 0 8
sp_cancel_supplier_order 418 418 0 0.00 6 876198 105320 0 0
sp_cancel_write_off 11234 11234 0 0.00 1 14641 116 0 0
sp_client_order 20147 20147 0 0.00 3 21723 382 0 0
sp_customer_reserve 442124 442124 0 0.00 0 65173 174 0 0
sp_fill_shopping_cart 30821 30821 0 0.00 1 33010 49 0 0
sp_fill_shopping_cart_clo_res 442123 442123 0 0.00 0 13424 14 0 0
sp_get_clo_for_invoice 4825 4825 0 0.00 0 23176 318 0 0
sp_kill_cost_storno 18080 18080 0 0.00 0 17318 63 0 0
sp_kill_qstorno_ret_qs2qd 1143682 1143682 0 0.00 0 45351 33 0 0
sp_kill_qty_storno 603037 603037 0 0.00 0 45354 72 0 0
sp_lock_selected_doc 20495 20495 0 0.00 0 8076 7 0 0
sp_make_cost_storno 27273 27273 0 0.00 0 25452 68 0 0
sp_multiply_rows_for_pdistr 27273 27273 0 0.00 0 13441 37 0 0
sp_multiply_rows_for_qdistr 20147 20147 0 0.00 0 12673 159 0 0
sp_pay_from_customer 6820 6820 0 0.00 4 13509 149 0 0
sp_pay_to_supplier 5750 5750 0 0.00 4 25465 147 0 0
sp_payment_common 12570 12570 0 0.00 1 12402 39 0 0
sp_qd_handle_on_cancel_clo 1782 1782 0 0.00 0 15944 364 0 0
sp_qd_handle_on_invoice_upd_sts 10560 10560 0 0.00 0 36679 352 0 0
sp_qd_handle_on_reserve_upd_sts 9878 9878 0 0.00 0 15757 76 0 0
sp_reserve_write_off 9878 9878 0 0.00 6 21883 224 0 0
sp_supplier_invoice 5332 5332 0 0.00 7 93006 3759 0 0
sp_supplier_order 5347 5347 0 0.00 4 41950 1362 0 0
srv_aggregate_perf_data 114 114 0 0.00 2374 170728 15638 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 846 119668 46640 0 0
srv_find_qd_qs_mism 1045216 1045216 0 0.00 0 10552 4 0 0
srv_make_invnt_saldo 555 555 0 0.00 29 118454 1426 0 0
srv_make_money_saldo 754 754 0 0.00 0 335 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 73848 548105 293234 0 0
t$perf-abend:sp_add_invoice_to_stock 82 82 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 4826 4826 0 0.00 0 10785 173 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 0 6461 144 0 0
t$perf-norm:sp_cancel_client_order 1782 1782 0 0.00 0 5111 19 0 0
t$perf-norm:sp_cancel_customer_reserve 682 682 0 0.00 0 6516 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 689 689 0 0.00 0 519 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 1061 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 6091 35 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 36746 1745 0 0
t$perf-norm:sp_cancel_write_off 1006 1006 0 0.00 0 5285 16 0 0
t$perf-norm:sp_client_order 20147 20147 0 0.00 0 15338 20 0 0
t$perf-norm:sp_customer_reserve 1011 1011 0 0.00 0 5206 13 0 0
t$perf-norm:sp_pay_from_customer 6820 6820 0 0.00 0 22939 20 0 0
t$perf-norm:sp_pay_to_supplier 5750 5750 0 0.00 0 5187 12 0 0
t$perf-norm:sp_reserve_write_off 9878 9878 0 0.00 0 7291 15 0 0
t$perf-norm:sp_supplier_invoice 5332 5332 0 0.00 0 8257 18 0 0
t$perf-norm:sp_supplier_order 5347 5347 0 0.00 0 7082 18 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 306 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 234 4 0 0
t$perf-norm:srv_make_invnt_saldo 555 555 0 0.00 0 5048 36 0 0
t$perf-norm:srv_make_money_saldo 754 754 0 0.00 0 1548 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 50 14 0 0
v_add_invoice_to_stock 9814 9650 164 1.67 0 5677 19 0 164
v_all_customers 36736 36736 0 0.00 0 29 1 0 0
v_all_suppliers 29770 29770 0 0.00 0 44 1 0 0
v_all_wares 40294 40294 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 1281 10 0 0
v_cancel_client_order 3564 3564 0 0.00 0 2712 4 0 0
v_cancel_customer_prepayment 1378 1378 0 0.00 0 3026 11 0 0
v_cancel_customer_reserve 1364 1364 0 0.00 0 2221 6 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 3489 10 0 0
v_cancel_supplier_invoice 782 766 16 2.04 0 962 17 0 16
v_cancel_supplier_order 836 836 0 0.00 0 1006 5 0 0
v_cancel_write_off 2012 2012 0 0.00 0 3349 17 0 0
v_max_id_clo_ord 5346 5346 0 0.00 0 1815 2 0 0
v_max_id_clo_res 1011 1011 0 0.00 0 1571 10 0 0
v_max_id_ord_sup 5326 5326 0 0.00 0 5299 7 0 0
v_max_non_paid_invoice 1537 1537 0 0.00 0 2956 5 0 0
v_max_non_paid_realizn 3664 3664 0 0.00 0 6122 7 0 0
v_min_id_clo_ord 5346 5346 0 0.00 0 9019 12 0 0
v_min_id_clo_res 1011 1011 0 0.00 0 4039 14 0 0
v_min_id_ord_sup 5326 5326 0 0.00 0 5194 8 0 0
v_min_non_paid_invoice 5750 5750 0 0.00 0 11139 24 0 0
v_min_non_paid_realizn 6820 6820 0 0.00 0 11101 37 0 0
v_our_firm 9870 9870 0 0.00 0 21 1 0 0
v_reserve_write_off 19756 19756 0 0.00 0 7144 7 0 0
x_lock_depdocs_on_canc_invoice 11652 11652 0 0.00 0 20725 130 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 19406 419 0 0
x_make_qstorno_1000_1200 5347 5347 0 0.00 1 41938 1269 0 0
x_make_qstorno_1200_2000 5332 5332 0 0.00 4 86235 3573 0 0
x_make_qstorno_2100_3300 442124 442124 0 0.00 0 65143 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-10-2022 19:39:43 to 05-10-2022 19:39:47.

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:36 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:36 17659 8192000000 9895145024 10204196864 980933200 10.693 100 90 180 0 1010048592 5689872 23425520
17:10:58 28382 8192000000 9942597392 10263457792 1022589888 11.097 100 91 182 0 1053751664 8000000 23161776
17:12:37 14619 8192000000 9977610096 10310668288 1064746272 11.502 100 83 164 1 1094443296 9172560 20524464
17:14:01 6052 8192000000 10030135712 10374897664 1115030000 11.980 100 72 142 1 1142140832 10131856 16978976
17:15:14 6381 8192000000 10090591936 10449788928 1175316480 12.546 100 83 163 0 1206799696 13158064 18325152
17:16:22 5555 8192000000 10131715056 10501922816 1218306432 12.946 100 76 152 1 1248384576 13013408 17064736
17:17:30 2711 8192000000 10167124688 10540806144 1252564544 13.262 100 92 185 0 1286982480 14381376 20036560
17:18:35 3654 8192000000 10173528976 10554036224 1263160624 13.359 100 70 140 1 1292871376 14608288 15102464
17:19:40 1459 8192000000 10206221904 10589167616 1289538176 13.600 100 90 179 2 1322817440 12452720 20826544
17:20:44 11998 8192000000 10203806624 10594447360 1298016192 13.677 100 63 121 1 1320567840 9667136 12884512
17:21:57 5192 8192000000 10193250672 10604175360 1307458816 13.763 100 35 70 0 1322840224 7903168 7478240
17:23:08 11305 8192000000 10198880368 10616365056 1316448208 13.845 100 16 32 1 1325947120 5894464 3604448
17:24:26 14210 8192000000 10209853264 10631294976 1327830064 13.948 100 15 30 1 1337901824 6037536 4034224
17:25:43 35809 8192000000 10239534624 10648178688 1334578560 14.009 100 47 93 0 1351266896 6110560 10577776
17:27:20 6075 8192000000 10213950048 10631766016 1327054800 13.941 100 21 38 0 1336642288 5481088 4106400
17:28:29 6492 8192000000 10188766144 10599251968 1278810864 13.502 97 20 40 0 1289235312 5667120 4757328
17:29:38 50372 8192000000 10166895232 10565836800 1220372016 12.965 93 28 56 1 1232073136 4753232 6947888
17:31:28 9335 8192000000 10150644304 10551590912 1227759552 13.033 97 27 54 2 1239924128 6052432 6112144
17:32:38 8371 8192000000 10151207840 10545799168 1232718400 13.079 98 22 41 1 1243718272 6020640 4979232
17:33:48 9725 8192000000 10152139632 10557845504 1255857680 13.292 99 19 38 1 1265944496 5167344 4919472
17:35:01 23810 8192000000 10194203696 10586558464 1285117040 13.560 100 64 132 0 1305996432 5800048 15079344
17:36:26 6077 8192000000 10185529808 10595565568 1286293120 13.570 99 23 47 1 1301496688 9742432 5461136
17:37:32 4261 8192000000 10206587440 10622087168 1315447376 13.835 100 33 67 0 1333102480 9934416 7720688
17:38:37 22172 8192000000 10236267136 10647719936 1338572288 14.045 100 33 67 0 1351452640 4895392 7984960
17:40:04 98360 8192000000 10251708512 10681638912 1357380192 14.214 100 45 86 0 1377188992 8880784 10928016
17:43:07 23281 8192000000 10280136352 10707779584 1376991232 14.390 100 30 60 1 1396708144 11929856 7787056
17:44:34 15243 8192000000 10306839728 10729656320 1393966240 14.541 100 43 86 1 1417605120 12761728 10877152
17:45:57 7717 8192000000 10314783088 10739179520 1388996656 14.497 99 47 93 1 1416302560 16266800 11039104
17:47:10 6188 8192000000 10332068880 10757464064 1410574720 14.689 100 51 102 2 1444419040 21767200 12077120
17:48:23 8877 8192000000 10321597792 10746511360 1403108080 14.623 100 59 118 1 1438961392 21693136 14160176
17:49:38 5813 8192000000 10335932144 10761142272 1413520864 14.715 100 54 106 0 1446306672 19940768 12845040
17:50:48 6884 8192000000 10346807680 10772234240 1417156880 14.747 100 47 94 0 1453082688 24423168 11502640
17:52:00 6070 8192000000 10349255456 10771234816 1416968512 14.746 100 60 117 0 1459138496 27637136 14532848
17:53:12 2262 8192000000 10372796352 10788073472 1426035968 14.826 100 89 179 0 1477552320 29992080 21524272
17:54:19 6657 8192000000 10341300688 10756108288 1393835456 14.540 99 62 122 0 1442522704 33239296 15447952
17:55:30 5207 8192000000 10342370096 10759360512 1392094784 14.525 100 61 122 0 1447285760 39514064 15676912
17:56:42 1560 8192000000 10345191888 10753114112 1379027680 14.408 100 77 153 0 1443483040 45325152 19130208
17:57:48 846 8192000000 10345874064 10755997696 1392296448 14.526 100 76 152 1 1450923872 40638160 17989264
17:58:54 4029 8192000000 10341425472 10764054528 1399154080 14.587 100 65 129 1 1448962848 34360064 15448704
18:00:05 4075 8192000000 10350853776 10765402112 1394488640 14.546 100 67 135 1 1454631888 42475552 17667696
18:01:19 4084 8192000000 10333726048 10738065408 1373154016 14.355 100 74 150 2 1433721008 41687776 18879216
18:02:28 2834 8192000000 10332112160 10754527232 1394104656 14.542 100 43 86 0 1438528944 33423440 11000848
18:03:36 13749 8192000000 10314805808 10742898688 1371468016 14.340 99 27 54 0 1410311760 31488768 7354976
18:04:53 22282 8192000000 10326017984 10752745472 1362563600 14.260 98 34 66 1 1409185392 37182416 9439376
18:06:19 29558 8192000000 10324210656 10763755520 1376689712 14.387 100 29 58 1 1431153232 45604000 8859520
18:07:50 65489 8192000000 10298377936 10741264384 1304110416 13.733 95 12 24 0 1348915088 41083552 3721120
18:09:56 57182 8192000000 10296264848 10725675008 1364463040 14.277 99 34 70 0 1383784656 11879536 7442080
18:11:59 93885 8192000000 10294732176 10745245696 1359478768 14.233 98 19 38 0 1374402000 10514096 4409136
18:14:50 54830 8192000000 10302823728 10748432384 1392810256 14.531 100 25 47 0 1407610656 9255264 5545136
18:16:46 95527 8192000000 10294433456 10754646016 1371373472 14.339 98 24 48 1 1390961024 14109760 5477792
18:19:26 107456 8192000000 10299319536 10748604416 1398367648 14.580 100 24 48 1 1410459824 6350864 5741312
18:22:17 24985 8192000000 10299490032 10747387904 1386306640 14.473 99 32 64 0 1402330176 9016192 7007344
18:23:59 41680 8192000000 10337533648 10781990912 1418152832 14.756 100 40 78 1 1443544608 14913760 10478016
18:25:44 64072 8192000000 10306633584 10764140544 1398379984 14.581 99 13 26 0 1419678336 18192944 3105408
18:27:49 12290 8192000000 10320754800 10777042944 1408299840 14.669 100 35 66 1 1435300064 19757120 7243104
18:29:05 61816 8192000000 10307569200 10777579520 1400495392 14.599 99 16 32 0 1419972048 15516304 3960352
18:31:07 67099 8192000000 10312408544 10783657984 1410068880 14.685 100 4 8 0 1422719040 11688176 961984
18:34:13 80539 8192000000 10294323136 10750500864 1339751200 14.055 95 17 34 0 1360784448 17403152 3630096
18:36:39 39558 8192000000 10301473936 10754654208 1382397136 14.438 99 15 30 0 1397170640 11420160 3353344
18:38:26 119665 8192000000 10291399568 10745749504 1300632992 13.701 92 18 37 0 1316625056 12409744 3582320
18:41:31 71060 8192000000 10282472496 10732179456 1300024288 13.695 93 13 26 0 1313023168 10396512 2602368
18:44:14 69948 8192000000 10264663808 10698330112 1314912016 13.831 97 23 46 0 1330136224 10533360 4690848
18:46:25 25448 8192000000 10289373024 10726084608 1367323008 14.303 100 32 63 0 1387975120 14143552 6508560
18:47:54 102207 8192000000 10249607936 10702155776 1326113648 13.932 97 20 40 0 1340876640 9724672 5038320
18:50:40 67459 8192000000 10274607568 10719903744 1343167792 14.086 99 37 74 0 1367131968 15858672 8105504
18:52:50 32953 8192000000 10260323504 10707705856 1320634992 13.882 97 28 55 0 1343653248 16391712 6626544
18:54:23 81798 8192000000 10260464448 10707034112 1334282368 14.006 98 17 32 0 1354981168 16892864 3805936
18:56:46 58548 8192000000 10265474304 10711494656 1351931968 14.165 99 12 24 1 1371540256 16572384 3035904
18:59:27 40488 8192000000 10269529920 10722082816 1352590992 14.171 99 20 40 0 1368480912 11191104 4698816
19:01:35 85164 8192000000 10265078544 10720530432 1317928160 13.858 96 10 20 1 1335769920 15648816 2192944
19:04:06 27105 8192000000 10293183424 10725646336 1362854432 14.263 99 38 74 1 1386382096 14646336 8881328
19:05:36 58062 8192000000 10292559888 10735374336 1354175840 14.185 98 21 42 0 1367042400 8649072 4217488
19:07:37 38086 8192000000 10306231808 10748534784 1378780704 14.406 99 19 38 0 1394276864 11279680 4216480
19:09:16 89305 8192000000 10305921840 10767208448 1395942144 14.559 100 18 36 0 1413846832 14059504 3845184
19:11:46 83019 8192000000 10299081728 10757107712 1395868224 14.558 100 11 22 0 1412954080 14879744 2206112
19:14:55 38519 8192000000 10293570480 10751877120 1374753200 14.370 98 24 48 0 1391150272 10957968 5439104
19:16:40 67851 8192000000 10310121280 10766237696 1396732048 14.566 100 11 22 1 1411417872 12365792 2320032
19:18:48 87043 8192000000 10319324992 10773340160 1377593584 14.395 97 23 46 0 1395405328 12637360 5174384
19:21:55 65256 8192000000 10313266672 10766561280 1395647744 14.556 99 21 42 1 1412753808 11604192 5501872
19:24:00 115367 8192000000 10300365776 10763423744 1319938384 13.876 94 16 30 1 1346767296 23722880 3106032
19:27:01 51222 8192000000 10280798704 10738552832 1370543936 14.332 99 9 18 0 1384015264 11436784 2034544
19:28:57 96890 8192000000 10277610592 10747535360 1341679536 14.073 97 14 28 0 1362982592 17942160 3360896
19:31:41 50877 8192000000 10276254752 10744438784 1364371184 14.277 99 16 32 1 1384458560 16748688 3338688
19:33:38 18697 8192000000 10279370192 10730405888 1369072272 14.319 99 20 40 0 1381629920 8046256 4511392
19:34:59 60561 8192000000 10293749920 10747609088 1375368656 14.375 100 21 42 0 1393890464 13713552 4808256
19:37:06 71465 8192000000 10276719296 10719571968 1272955056 13.449 92 30 55 0 1288222640 8745008 6522576

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-10-2022 19:39:49 to 05-10-2022 19:40:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 82 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 05-10-2022 19:40:05 to 05-10-2022 19:40:07.

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          235107
MON$OLDEST_ACTIVE               237224
MON$OLDEST_SNAPSHOT             237224
MON$NEXT_TRANSACTION            237224
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                       2028448
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.33632 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33632 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33632 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33632 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 ---
:235: 
:236: mscs-test-161.budzdorov.ru    Wed Oct  5 19:38:33 2022
:237:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:238: 
:239: 
:240: mscs-test-161.budzdorov.ru    Wed Oct  5 19:38:34 2022
:241:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:242: 
:243: 
:244: mscs-test-161.budzdorov.ru    Wed Oct  5 19:38:39 2022
:245:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:246: 
:247: 
:248: mscs-test-161.budzdorov.ru    Wed Oct  5 19:38:39 2022
:249:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:250: 
--- end of diff output ---

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

05.10.22 19:40:08. 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-10-05 19:40:11 No severe PSQL-related problems occured
05.10.22 19:40:12. End of report.