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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-11-28 01:37:56
DTS_END                         2021-11-28 01:37:56.2770
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/42742
TRN_ID                          229128
ATT_ID                          691
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: 9000.56 28117 1080067
customer order: creation 161.16 388 19339
customer order: refuse 13.60 368 1632
order to supplier: creation 43.84 1327 5261
order to supplier: removal 3.53 91836 424
invoice (draft): creation 43.33 3607 5199
invoice (draft): removal 51.91 732 6229
invoice accept: apply 40.28 16465 4833
invoice accept: cancel 47.09 760 5651
customer reserve: creation 3668.78 163 440253
customer reserve: removal 4630.71 55 555685
realization accept: apply 78.36 219 9403
realization accept: cancel 94.54 106 11345
payment from customer: creation 55.93 133 6711
payment from customer: removal 5.97 97 716
payment to supplier: creation 46.86 197 5623
payment to supplier: removal 3.23 101 388
service: total inventory turnovers 4.56 1275 547
service: total monetary turnovers 5.98 74 717
service: aggregate perf. data 0.89 14632 107
service: refresh index statistics 0.03 429796 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-11-2021 01:39:25 to 28-11-2021 01:39:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1627
TEST_TIME 2 2491
TEST_TIME 3 3271
TEST_TIME 4 2880
TEST_TIME 5 2933
TEST_TIME 6 3986
TEST_TIME 7 3732
TEST_TIME 8 4223
TEST_TIME 9 4299
TEST_TIME 10 5050
TEST_TIME 11 5154
TEST_TIME 12 5597
TEST_TIME 13 5691
TEST_TIME 14 5857
TEST_TIME 15 6161
TEST_TIME 16 6606
TEST_TIME 17 7423
TEST_TIME 18 7487
TEST_TIME 19 8285
TEST_TIME 20 8347
TEST_TIME 21 8957
TEST_TIME 22 9050
TEST_TIME 23 9442
TEST_TIME 24 9435
TEST_TIME 25 9572
TEST_TIME 26 9629
TEST_TIME 27 10012
TEST_TIME 28 10107
TEST_TIME 29 9941
TEST_TIME 30 10167
TEST_TIME 31 10315
TEST_TIME 32 10234
TEST_TIME 33 10230
TEST_TIME 34 10074
TEST_TIME 35 9955
TEST_TIME 36 9900
TEST_TIME 37 9837
TEST_TIME 38 9798
TEST_TIME 39 9727
TEST_TIME 40 9524
TEST_TIME 41 9423
TEST_TIME 42 9392
TEST_TIME 43 9365
TEST_TIME 44 9365
TEST_TIME 45 9292
TEST_TIME 46 9370
TEST_TIME 47 9272
TEST_TIME 48 9221
TEST_TIME 49 9198
TEST_TIME 50 9161
TEST_TIME 51 9118
TEST_TIME 52 9106
TEST_TIME 53 9081
TEST_TIME 54 9026
TEST_TIME 55 9120
TEST_TIME 56 9135
TEST_TIME 57 9192
TEST_TIME 58 9244
TEST_TIME 59 9187
TEST_TIME 60 9238
TEST_TIME 61 9293
TEST_TIME 62 9427
TEST_TIME 63 9396
TEST_TIME 64 9334
TEST_TIME 65 9430
TEST_TIME 66 9292
TEST_TIME 67 9187
TEST_TIME 68 9161
TEST_TIME 69 9081
TEST_TIME 70 9099
TEST_TIME 71 9143
TEST_TIME 72 9141
TEST_TIME 73 9151
TEST_TIME 74 9122
TEST_TIME 75 9141
TEST_TIME 76 9144
TEST_TIME 77 9128
TEST_TIME 78 9275
TEST_TIME 79 9291
TEST_TIME 80 9273
TEST_TIME 81 9213
TEST_TIME 82 9346
TEST_TIME 83 9327
TEST_TIME 84 9262
TEST_TIME 85 9195
TEST_TIME 86 9143
TEST_TIME 87 9089
TEST_TIME 88 9136
TEST_TIME 89 9125
TEST_TIME 90 9087
TEST_TIME 91 9063
TEST_TIME 92 9060
TEST_TIME 93 9022
TEST_TIME 94 8966
TEST_TIME 95 8947
TEST_TIME 96 8949
TEST_TIME 97 8975
TEST_TIME 98 8993
TEST_TIME 99 8972
TEST_TIME 100 9042
TEST_TIME 101 9064
TEST_TIME 102 9080
TEST_TIME 103 9092
TEST_TIME 104 9154
TEST_TIME 105 9172
TEST_TIME 106 9155
TEST_TIME 107 9191
TEST_TIME 108 9208
TEST_TIME 109 9204
TEST_TIME 110 9198
TEST_TIME 111 9126
TEST_TIME 112 9120
TEST_TIME 113 9065
TEST_TIME 114 9104
TEST_TIME 115 9065
TEST_TIME 116 9054
TEST_TIME 117 9036
TEST_TIME 118 8998
TEST_TIME 119 8979
TEST_TIME 120 8963
TEST_TIME 121 8927

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-11-2021 01:39:28 to 28-11-2021 01:39:32.

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 1488626 1488626 0 0.00 0 32241 6 0 0
doc_list_biud 1488625 1488625 0 0.00 0 29218 26 0 0
sp_add_invoice_to_stock 4978 4833 145 2.91 36 182640 16466 0 145
sp_cancel_adding_invoice 5651 5651 0 0.00 5 74309 760 0 0
sp_cancel_client_order 1632 1632 0 0.00 4 14718 368 0 0
sp_cancel_customer_reserve 555685 555685 0 0.00 1 29220 55 0 0
sp_cancel_pay_from_customer 716 716 0 0.00 3 6847 98 0 0
sp_cancel_pay_to_supplier 388 388 0 0.00 3 6903 101 0 0
sp_cancel_supplier_invoice 6241 6229 12 0.19 2 14439 733 0 12
sp_cancel_supplier_order 424 424 0 0.00 5 856323 91836 0 0
sp_cancel_write_off 11345 11345 0 0.00 1 10404 107 0 0
sp_client_order 19339 19339 0 0.00 3 28029 388 0 0
sp_customer_reserve 440253 440253 0 0.00 0 52421 164 0 0
sp_fill_shopping_cart 29787 29787 0 0.00 1 41465 45 0 0
sp_fill_shopping_cart_clo_res 440250 440250 0 0.00 0 12692 14 0 0
sp_get_clo_for_invoice 4829 4829 0 0.00 0 27658 359 0 0
sp_kill_cost_storno 18100 18100 0 0.00 0 7614 56 0 0
sp_kill_qstorno_ret_qs2qd 1129368 1129368 0 0.00 0 29216 30 0 0
sp_kill_qty_storno 595198 595198 0 0.00 0 29218 65 0 0
sp_lock_selected_doc 19785 19785 0 0.00 0 9665 5 0 0
sp_make_cost_storno 26566 26566 0 0.00 0 30196 74 0 0
sp_multiply_rows_for_pdistr 26566 26566 0 0.00 0 9130 35 0 0
sp_multiply_rows_for_qdistr 19339 19339 0 0.00 0 27060 171 0 0
sp_pay_from_customer 6711 6711 0 0.00 5 35232 133 0 0
sp_pay_to_supplier 5623 5623 0 0.00 4 35353 197 0 0
sp_payment_common 12334 12334 0 0.00 1 25966 40 0 0
sp_qd_handle_on_cancel_clo 1632 1632 0 0.00 0 14711 333 0 0
sp_qd_handle_on_invoice_upd_sts 10480 10480 0 0.00 0 21175 343 0 0
sp_qd_handle_on_reserve_upd_sts 9403 9403 0 0.00 0 17039 65 0 0
sp_reserve_write_off 9403 9403 0 0.00 6 32237 219 0 0
sp_supplier_invoice 5199 5199 0 0.00 6 78374 3607 0 0
sp_supplier_order 5261 5261 0 0.00 5 50096 1327 0 0
srv_aggregate_perf_data 107 107 0 0.00 1998 107335 14633 0 0
srv_fill_mon_cache_memory 59 59 0 0.00 2396 150573 54839 0 0
srv_find_qd_qs_mism 1035200 1035200 0 0.00 0 24055 4 0 0
srv_make_invnt_saldo 547 547 0 0.00 13 105741 1276 0 0
srv_make_money_saldo 717 717 0 0.00 1 22348 74 0 0
srv_recalc_idx_stat 4 4 0 0.00 253477 684493 429797 0 0
t$perf-abend:sp_add_invoice_to_stock 145 145 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4833 4833 0 0.00 0 10506 155 0 0
t$perf-norm:sp_cancel_adding_invoice 377 377 0 0.00 0 3922 88 0 0
t$perf-norm:sp_cancel_client_order 1632 1632 0 0.00 0 5282 16 0 0
t$perf-norm:sp_cancel_customer_reserve 667 667 0 0.00 0 2992 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 716 716 0 0.00 0 3100 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 388 388 0 0.00 0 4268 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 2731 20 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 20657 1641 0 0
t$perf-norm:sp_cancel_write_off 959 959 0 0.00 0 1618 4 0 0
t$perf-norm:sp_client_order 19339 19339 0 0.00 0 10664 20 0 0
t$perf-norm:sp_customer_reserve 948 948 0 0.00 0 3268 7 0 0
t$perf-norm:sp_pay_from_customer 6711 6711 0 0.00 0 10729 13 0 0
t$perf-norm:sp_pay_to_supplier 5623 5623 0 0.00 0 3474 9 0 0
t$perf-norm:sp_reserve_write_off 9403 9403 0 0.00 0 8586 12 0 0
t$perf-norm:sp_supplier_invoice 5199 5199 0 0.00 0 7068 20 0 0
t$perf-norm:sp_supplier_order 5261 5261 0 0.00 0 10290 18 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 306 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 277 5 0 0
t$perf-norm:srv_make_invnt_saldo 547 547 0 0.00 0 4613 26 0 0
t$perf-norm:srv_make_money_saldo 717 717 0 0.00 0 947 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 35 10 0 0
v_add_invoice_to_stock 9948 9658 290 2.91 0 6803 22 0 290
v_all_customers 35354 35354 0 0.00 0 95 1 0 0
v_all_suppliers 29002 29002 0 0.00 0 58 1 0 0
v_all_wares 38678 38678 0 0.00 0 55 1 0 0
v_cancel_adding_invoice 754 754 0 0.00 0 3098 20 0 0
v_cancel_client_order 3264 3264 0 0.00 0 4913 10 0 0
v_cancel_customer_prepayment 1432 1432 0 0.00 0 6791 17 0 0
v_cancel_customer_reserve 1334 1334 0 0.00 0 5479 15 0 0
v_cancel_payment_to_supplier 776 776 0 0.00 0 6815 29 0 0
v_cancel_supplier_invoice 804 780 24 2.98 0 5938 32 0 24
v_cancel_supplier_order 848 848 0 0.00 0 1095 5 0 0
v_cancel_write_off 1918 1918 0 0.00 0 4295 19 0 0
v_max_id_clo_ord 5259 5259 0 0.00 0 1264 2 0 0
v_max_id_clo_res 948 948 0 0.00 0 12495 31 0 0
v_max_id_ord_sup 5189 5189 0 0.00 0 5664 4 0 0
v_max_non_paid_invoice 1570 1570 0 0.00 0 9127 8 0 0
v_max_non_paid_realizn 3560 3560 0 0.00 0 9124 10 0 0
v_min_id_clo_ord 5259 5259 0 0.00 0 10996 13 0 0
v_min_id_clo_res 948 948 0 0.00 0 3511 14 0 0
v_min_id_ord_sup 5189 5189 0 0.00 0 7437 9 0 0
v_min_non_paid_invoice 5623 5623 0 0.00 0 25962 32 0 0
v_min_non_paid_realizn 6711 6711 0 0.00 0 16041 28 0 0
v_our_firm 9626 9626 0 0.00 0 34 1 0 0
v_reserve_write_off 18806 18806 0 0.00 0 5228 9 0 0
x_lock_depdocs_on_canc_invoice 11490 11490 0 0.00 0 19095 109 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 8361 320 0 0
x_make_qstorno_1000_1200 5261 5261 0 0.00 2 50083 1240 0 0
x_make_qstorno_1200_2000 5199 5199 0 0.00 2 72653 3444 0 0
x_make_qstorno_2100_3300 440253 440253 0 0.00 0 41996 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-11-2021 01:39:33 to 28-11-2021 01:39:37.

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:07:59 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:00 25903 8192000000 9892669424 10200702976 973611664 10.622 100 89 178 0 1002757072 5425168 23720240
23:10:29 30780 8192000000 9940468544 10258735104 1018750128 11.060 100 94 188 1 1050942432 7677504 24514800
23:12:06 20758 8192000000 9971794128 10309644288 1057411840 11.432 100 78 156 0 1085996768 8920000 19664928
23:13:36 12401 8192000000 10029600992 10378244096 1115431568 11.984 100 80 160 0 1144885408 10042928 19410912
23:14:54 9872 8192000000 10072938592 10436177920 1166680608 12.466 100 71 140 1 1194026064 11011296 16334160
23:16:07 4117 8192000000 10130548800 10499887104 1219053024 12.953 100 83 166 0 1249734080 12513120 18167936
23:17:14 3369 8192000000 10162321072 10543579136 1248470576 13.224 100 75 149 1 1277306016 12579008 16256432
23:18:20 1091 8192000000 10197489456 10581663744 1284520800 13.554 100 83 166 1 1315759296 12342784 18895712
23:19:22 1438 8192000000 10217186096 10603483136 1304478928 13.736 100 90 177 1 1337736656 13080432 20177296
23:20:24 2292 8192000000 10218811472 10616262656 1309941152 13.786 100 75 150 0 1338400464 11081648 17377664
23:21:30 11879 8192000000 10198774080 10612441088 1312206736 13.806 100 25 50 1 1324335696 6285616 5843344
23:22:45 13059 8192000000 10204757152 10621730816 1318246368 13.861 100 17 34 2 1328382960 6864784 3271808
23:23:59 18412 8192000000 10209788752 10633154560 1325483312 13.926 100 10 20 0 1333331088 5713984 2133792
23:25:20 14724 8192000000 10234367520 10644680704 1327036688 13.940 100 58 118 3 1346472960 5530992 13905280
23:26:36 3535 8192000000 10239569008 10654982144 1336575440 14.027 100 39 78 0 1353020512 7605856 8839216
23:27:42 7672 8192000000 10212110016 10631393280 1314673488 13.828 99 28 56 2 1327160880 6082160 6405232
23:28:52 10329 8192000000 10158576592 10568523776 1225529984 13.013 94 9 17 0 1232179440 4618896 2030560
23:30:04 14890 8192000000 10155591408 10566889472 1249688560 13.235 98 45 90 0 1266769712 6171152 10910000
23:31:20 4171 8192000000 10131831232 10527264768 1226818960 13.025 99 37 73 1 1241685648 6279120 8587568
23:32:26 10393 8192000000 10135065392 10535186432 1220960864 12.971 98 23 46 2 1232353792 5718816 5674112
23:33:37 12221 8192000000 10172069392 10578853888 1275045280 13.468 100 24 49 1 1285928288 5007152 5875856
23:34:50 40949 8192000000 10175341024 10586124288 1274276256 13.461 99 33 66 0 1287629312 5708608 7644448
23:36:31 6311 8192000000 10198973488 10610130944 1302370848 13.717 100 26 52 1 1314031472 5607792 6052832
23:37:38 9876 8192000000 10212237824 10622636032 1312591472 13.810 100 22 44 1 1323438720 5141440 5705808
23:39:00 22982 8192000000 10251008768 10663395328 1344300816 14.096 100 48 95 1 1365373504 9875408 11197280
23:40:31 104156 8192000000 10281237552 10706640896 1371611200 14.341 100 36 71 4 1395203424 15881712 7710512
23:43:21 91157 8192000000 10309774496 10732027904 1389477104 14.501 100 34 67 0 1415635504 17398560 8759840
23:45:58 16334 8192000000 10325141280 10748768256 1397197200 14.570 100 32 64 1 1428844368 23467248 8179920
23:47:22 6170 8192000000 10347480672 10770604032 1413038336 14.711 100 63 123 1 1453241200 24934544 15268320
23:48:34 4991 8192000000 10364296304 10784808960 1425662320 14.823 100 65 128 4 1470032528 28634944 15735264
23:49:45 5593 8192000000 10368234768 10787962880 1427154864 14.836 100 72 142 2 1475083712 30542464 17386384
23:50:57 4609 8192000000 10366635280 10778423296 1414602416 14.725 100 74 147 1 1468445504 34340064 19503024
23:52:06 7226 8192000000 10359117312 10780610560 1395758112 14.557 98 63 124 1 1451063888 39380832 15924944
23:53:25 3670 8192000000 10378577792 10795933696 1412535856 14.706 100 76 151 2 1476263488 43577008 20150624
23:54:33 4883 8192000000 10357153392 10769940480 1405961328 14.648 100 78 156 1 1459513920 34529056 19023536
23:55:45 2396 8192000000 10368227200 10782351360 1399237984 14.588 99 82 165 2 1457158048 37011952 20908112
23:56:50 8736 8192000000 10351864512 10783264768 1415138400 14.730 100 36 72 0 1459105328 34059456 9907472
23:58:01 11597 8192000000 10338131328 10763362304 1380680752 14.423 99 52 107 1 1432802736 38234560 13887424
23:59:17 14176 8192000000 10321802208 10752729088 1372346896 14.348 99 42 84 2 1413440608 30392320 10701392
00:00:34 8896 8192000000 10311888272 10749337600 1358748304 14.226 98 29 58 0 1397701360 30806016 8147040
00:02:22 12103 8192000000 10306413120 10746183680 1376461056 14.385 100 36 72 2 1418390960 32313312 9616592
00:03:35 12924 8192000000 10307236000 10747236352 1371673536 14.342 100 28 54 0 1412331280 33783488 6874256
00:04:50 24880 8192000000 10305578496 10742153216 1362688912 14.261 99 20 40 0 1396671616 28643472 5339232
00:06:15 53529 8192000000 10304346560 10749460480 1372211584 14.347 100 16 32 0 1407782576 31350720 4220272
00:09:18 85017 8192000000 10267507104 10717704192 1329291120 13.961 97 16 32 0 1349904512 17003536 3609856
00:12:01 88309 8192000000 10291507632 10721492992 1348081296 14.130 98 32 64 2 1364846976 10256704 6508976
00:14:30 113820 8192000000 10277071376 10725818368 1353759888 14.181 98 24 48 0 1369627600 10999696 4868016
00:17:25 59968 8192000000 10293264448 10732339200 1355311984 14.195 98 47 92 7 1378623888 12035280 11276624
00:19:27 53606 8192000000 10295627168 10754834432 1392049808 14.524 100 15 30 1 1410757552 14975264 3732480
00:21:37 35350 8192000000 10305122592 10760056832 1394978416 14.550 100 33 66 1 1419977808 16991872 8007520
00:23:17 65906 8192000000 10293592304 10758406144 1385175584 14.463 99 11 22 1 1401048976 13233328 2640064
00:25:29 111294 8192000000 10295938240 10761818112 1390042960 14.506 100 13 26 0 1409521616 16433504 3045152
00:28:28 48477 8192000000 10295549456 10753515520 1396282048 14.562 100 22 44 1 1409500432 7773264 5445120
00:30:24 72017 8192000000 10317733792 10768687104 1363150928 14.266 97 21 42 0 1378496768 10684896 4660944
00:33:23 69837 8192000000 10318804784 10789941248 1393339024 14.536 99 14 28 2 1423896592 27614608 2942960
00:35:53 57882 8192000000 10308626944 10743222272 1331381008 13.980 95 32 62 2 1353433344 15040672 7011664
00:37:51 70120 8192000000 10310499424 10772897792 1386073840 14.471 99 12 24 0 1408369216 19287712 3007664
00:40:02 105274 8192000000 10310096720 10753982464 1346959120 14.120 96 12 24 0 1360990528 11800016 2231392
00:42:48 114409 8192000000 10283151232 10717380608 1318493136 13.863 96 27 54 0 1339617648 15269424 5855088
00:45:48 63693 8192000000 10259186864 10712907776 1318606080 13.864 96 17 34 1 1335002448 12480448 3915920
00:47:52 67969 8192000000 10258020704 10727157760 1325561376 13.927 97 7 14 0 1344125328 17145168 1418784
00:50:00 150570 8192000000 10271429248 10718752768 1255551904 13.289 91 17 35 0 1272687552 13241712 3893936
00:53:34 66422 8192000000 10285727024 10732707840 1331293952 13.979 97 34 68 0 1353843216 14500576 8048688
00:55:46 107872 8192000000 10268408016 10715860992 1315136336 13.833 97 19 36 2 1332370656 13090896 4143424
00:58:41 54314 8192000000 10286504368 10735751168 1325678816 13.928 97 29 59 1 1345902752 13933728 6290208
01:00:37 64074 8192000000 10269764224 10699689984 1279284240 13.506 94 23 46 1 1297375264 13170736 4920288
01:02:41 116213 8192000000 10255981440 10698231808 1302901424 13.722 96 15 30 0 1316370256 10044320 3424512
01:05:38 10913 8192000000 10251090016 10701340672 1343304000 14.087 100 20 40 1 1367831184 20050544 4476640
01:06:55 72560 8192000000 10263010512 10714583040 1348520752 14.134 99 13 26 0 1361631040 10288224 2822064
01:09:08 47656 8192000000 10262629712 10719875072 1357481360 14.215 99 10 20 0 1365999744 6218496 2299888
01:10:57 63579 8192000000 10269262416 10727202816 1372442768 14.349 100 16 32 1 1385189040 8898560 3847712
01:13:01 43231 8192000000 10270562928 10731339776 1364199264 14.275 99 9 17 1 1377650880 11839792 1611824
01:14:45 61826 8192000000 10280136464 10729697280 1357433568 14.214 98 17 34 1 1372975648 12373600 3168480
01:16:53 106260 8192000000 10287816784 10756075520 1345186080 14.104 96 25 50 0 1363828400 12533936 6108384
01:19:43 37737 8192000000 10312493776 10764537856 1390362560 14.509 100 32 63 2 1409995616 12362240 7270816
01:21:22 60817 8192000000 10308335408 10768670720 1392661200 14.530 100 16 32 0 1408812528 12710928 3440400
01:23:27 57694 8192000000 10325099152 10780987392 1403150640 14.623 100 28 54 1 1428276928 18784160 6342128
01:25:27 73771 8192000000 10314669296 10768486400 1384616112 14.458 99 28 56 1 1401628560 11122400 5890048
01:27:42 135495 8192000000 10285623456 10747658240 1366638016 14.297 98 29 58 0 1388838208 16032848 6167344
01:30:58 51881 8192000000 10269645648 10744635392 1378011776 14.399 100 12 24 0 1394044592 13375232 2657584
01:32:51 48481 8192000000 10261669376 10724315136 1337554384 14.035 97 8 16 0 1349119824 9814800 1750640
01:35:19 78078 8192000000 10279290240 10739900416 1363740512 14.271 99 15 30 1 1381242224 13958672 3543040
01:37:44 22083 8192000000 9116999424 9362251776 277454256 3.275 21 2 3 0 285297040 7021712 821072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 28-11-2021 01:39:39 to 28-11-2021 01:39:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 145 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-11-2021 01:39:54 to 28-11-2021 01:39:56.

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          233554
MON$OLDEST_ACTIVE               233555
MON$OLDEST_SNAPSHOT             233555
MON$NEXT_TRANSACTION            233555
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                       2015616
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

28.11.21 01:39:57. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-11-28 01:39:59 No severe PSQL-related problems occured
28.11.21 01:40:00. End of report.