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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16829500 kB
MemAvailable 31530600 kB
Buffers 0 kB
Cached 14819964 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-24 22:37:58
DTS_END                         2021-11-24 22:37:58.6990
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/50318
TRN_ID                          244579
ATT_ID                          723
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: 9280.62 18049 1113674
customer order: creation 180.93 376 21711
customer order: refuse 15.42 298 1850
order to supplier: creation 48.54 1300 5825
order to supplier: removal 3.65 89294 438
invoice (draft): creation 47.83 3096 5740
invoice (draft): removal 53.15 761 6378
invoice accept: apply 42.03 17266 5043
invoice accept: cancel 47.30 731 5676
customer reserve: creation 3867.07 171 464048
customer reserve: removal 4667.51 55 560101
realization accept: apply 87.66 159 10519
realization accept: cancel 83.90 108 10068
payment from customer: creation 62.50 116 7500
payment from customer: removal 5.82 124 698
payment to supplier: creation 51.29 133 6155
payment to supplier: removal 3.42 79 410
service: total inventory turnovers 4.78 984 573
service: total monetary turnovers 6.96 66 835
service: aggregate perf. data 0.85 22301 102
service: refresh index statistics 0.03 223562 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-11-2021 22:39:11 to 24-11-2021 22:39:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3153
TEST_TIME 2 3953
TEST_TIME 3 4416
TEST_TIME 4 4282
TEST_TIME 5 4950
TEST_TIME 6 4881
TEST_TIME 7 4853
TEST_TIME 8 5054
TEST_TIME 9 5390
TEST_TIME 10 5482
TEST_TIME 11 5825
TEST_TIME 12 6198
TEST_TIME 13 6572
TEST_TIME 14 6598
TEST_TIME 15 7279
TEST_TIME 16 7325
TEST_TIME 17 8005
TEST_TIME 18 8306
TEST_TIME 19 8257
TEST_TIME 20 8361
TEST_TIME 21 8758
TEST_TIME 22 8979
TEST_TIME 23 9061
TEST_TIME 24 9678
TEST_TIME 25 10327
TEST_TIME 26 10944
TEST_TIME 27 11061
TEST_TIME 28 10815
TEST_TIME 29 10674
TEST_TIME 30 10537
TEST_TIME 31 10265
TEST_TIME 32 10287
TEST_TIME 33 10361
TEST_TIME 34 10153
TEST_TIME 35 10025
TEST_TIME 36 9960
TEST_TIME 37 9829
TEST_TIME 38 10038
TEST_TIME 39 10153
TEST_TIME 40 9973
TEST_TIME 41 9953
TEST_TIME 42 9895
TEST_TIME 43 9812
TEST_TIME 44 9692
TEST_TIME 45 9722
TEST_TIME 46 9643
TEST_TIME 47 9633
TEST_TIME 48 9529
TEST_TIME 49 9542
TEST_TIME 50 9490
TEST_TIME 51 9499
TEST_TIME 52 9443
TEST_TIME 53 9497
TEST_TIME 54 9495
TEST_TIME 55 9747
TEST_TIME 56 9771
TEST_TIME 57 9728
TEST_TIME 58 9702
TEST_TIME 59 9933
TEST_TIME 60 9975
TEST_TIME 61 9916
TEST_TIME 62 9843
TEST_TIME 63 9730
TEST_TIME 64 9635
TEST_TIME 65 9578
TEST_TIME 66 9666
TEST_TIME 67 9552
TEST_TIME 68 9496
TEST_TIME 69 9424
TEST_TIME 70 9366
TEST_TIME 71 9372
TEST_TIME 72 9396
TEST_TIME 73 9357
TEST_TIME 74 9327
TEST_TIME 75 9340
TEST_TIME 76 9376
TEST_TIME 77 9447
TEST_TIME 78 9497
TEST_TIME 79 9508
TEST_TIME 80 9568
TEST_TIME 81 9565
TEST_TIME 82 9711
TEST_TIME 83 9696
TEST_TIME 84 9644
TEST_TIME 85 9558
TEST_TIME 86 9496
TEST_TIME 87 9519
TEST_TIME 88 9498
TEST_TIME 89 9462
TEST_TIME 90 9478
TEST_TIME 91 9428
TEST_TIME 92 9411
TEST_TIME 93 9401
TEST_TIME 94 9406
TEST_TIME 95 9393
TEST_TIME 96 9390
TEST_TIME 97 9482
TEST_TIME 98 9560
TEST_TIME 99 9537
TEST_TIME 100 9550
TEST_TIME 101 9558
TEST_TIME 102 9588
TEST_TIME 103 9611
TEST_TIME 104 9588
TEST_TIME 105 9619
TEST_TIME 106 9589
TEST_TIME 107 9649
TEST_TIME 108 9576
TEST_TIME 109 9500
TEST_TIME 110 9478
TEST_TIME 111 9460
TEST_TIME 112 9415
TEST_TIME 113 9392
TEST_TIME 114 9357
TEST_TIME 115 9326
TEST_TIME 116 9311
TEST_TIME 117 9309
TEST_TIME 118 9300
TEST_TIME 119 9283
TEST_TIME 120 9282
TEST_TIME 121 9206

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 24-11-2021 22:39:14 to 24-11-2021 22:39:18.

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 1545356 1545356 0 0.00 0 24605 6 0 0
doc_list_biud 1545354 1545354 0 0.00 0 47615 25 0 0
sp_add_invoice_to_stock 5134 5043 91 1.77 45 226078 17267 0 91
sp_cancel_adding_invoice 5676 5676 0 0.00 5 68943 732 0 0
sp_cancel_client_order 1850 1850 0 0.00 4 7373 298 0 0
sp_cancel_customer_reserve 560101 560101 0 0.00 1 47617 55 0 0
sp_cancel_pay_from_customer 698 698 0 0.00 3 6585 124 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 2 3211 79 0 0
sp_cancel_supplier_invoice 6388 6378 10 0.15 2 14544 762 0 10
sp_cancel_supplier_order 438 438 0 0.00 7 991718 89295 0 0
sp_cancel_write_off 10068 10068 0 0.00 1 10715 109 0 0
sp_client_order 21711 21711 0 0.00 3 50731 377 0 0
sp_customer_reserve 464048 464048 0 0.00 0 127745 172 0 0
sp_fill_shopping_cart 33274 33274 0 0.00 1 12461 33 0 0
sp_fill_shopping_cart_clo_res 464041 464041 0 0.00 0 13073 14 0 0
sp_get_clo_for_invoice 5035 5035 0 0.00 0 21881 282 0 0
sp_kill_cost_storno 16852 16852 0 0.00 0 10107 51 0 0
sp_kill_qstorno_ret_qs2qd 1137086 1137086 0 0.00 0 47614 30 0 0
sp_kill_qty_storno 600064 600064 0 0.00 0 47615 65 0 0
sp_lock_selected_doc 21639 21639 0 0.00 0 11181 8 0 0
sp_make_cost_storno 29209 29209 0 0.00 0 17869 47 0 0
sp_multiply_rows_for_pdistr 29207 29207 0 0.00 0 9978 27 0 0
sp_multiply_rows_for_qdistr 21711 21711 0 0.00 0 48142 180 0 0
sp_pay_from_customer 7500 7500 0 0.00 4 15459 116 0 0
sp_pay_to_supplier 6155 6155 0 0.00 5 18069 133 0 0
sp_payment_common 13654 13654 0 0.00 1 15451 30 0 0
sp_qd_handle_on_cancel_clo 1850 1850 0 0.00 0 7367 269 0 0
sp_qd_handle_on_invoice_upd_sts 10710 10710 0 0.00 0 33979 288 0 0
sp_qd_handle_on_reserve_upd_sts 10519 10519 0 0.00 0 10480 51 0 0
sp_reserve_write_off 10519 10519 0 0.00 6 11642 159 0 0
sp_supplier_invoice 5740 5740 0 0.00 6 88142 3097 0 0
sp_supplier_order 5825 5825 0 0.00 5 31065 1301 0 0
srv_aggregate_perf_data 103 102 1 0.97 2393 556951 22302 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1375 129154 46921 0 0
srv_find_qd_qs_mism 1065330 1065330 0 0.00 0 24605 5 0 0
srv_make_invnt_saldo 573 573 0 0.00 37 108469 984 0 0
srv_make_money_saldo 835 835 0 0.00 1 38568 67 0 0
srv_recalc_idx_stat 4 4 0 0.00 131495 301723 223562 0 0
t$perf-abend:sp_add_invoice_to_stock 91 91 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5043 5043 0 0.00 0 11175 168 0 0
t$perf-norm:sp_cancel_adding_invoice 432 432 0 0.00 0 8123 99 0 0
t$perf-norm:sp_cancel_client_order 1850 1850 0 0.00 0 5162 13 0 0
t$perf-norm:sp_cancel_customer_reserve 766 766 0 0.00 0 3724 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 698 698 0 0.00 0 416 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 6215 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 540 9 0 0
t$perf-norm:sp_cancel_supplier_order 438 438 0 0.00 0 22348 1404 0 0
t$perf-norm:sp_cancel_write_off 1097 1097 0 0.00 0 5794 19 0 0
t$perf-norm:sp_client_order 21711 21711 0 0.00 0 12561 18 0 0
t$perf-norm:sp_customer_reserve 1062 1062 0 0.00 0 695 4 0 0
t$perf-norm:sp_pay_from_customer 7500 7500 0 0.00 0 12430 12 0 0
t$perf-norm:sp_pay_to_supplier 6155 6155 0 0.00 0 6697 10 0 0
t$perf-norm:sp_reserve_write_off 10519 10519 0 0.00 0 5996 14 0 0
t$perf-norm:sp_supplier_invoice 5740 5740 0 0.00 0 5689 19 0 0
t$perf-norm:sp_supplier_order 5825 5825 0 0.00 0 9193 16 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 395 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 8343 165 0 0
t$perf-norm:srv_make_invnt_saldo 573 573 0 0.00 0 4945 25 0 0
t$perf-norm:srv_make_money_saldo 835 835 0 0.00 0 3001 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10248 10066 182 1.77 0 6704 20 0 182
v_all_customers 40046 40046 0 0.00 0 14 0 0 0
v_all_suppliers 32170 32170 0 0.00 0 93 1 0 0
v_all_wares 43422 43422 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 864 864 0 0.00 0 946 8 0 0
v_cancel_client_order 3700 3700 0 0.00 0 2341 7 0 0
v_cancel_customer_prepayment 1396 1396 0 0.00 0 4880 18 0 0
v_cancel_customer_reserve 1532 1532 0 0.00 0 5764 12 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 3185 9 0 0
v_cancel_supplier_invoice 812 792 20 2.46 0 1945 15 0 20
v_cancel_supplier_order 876 876 0 0.00 0 1290 4 0 0
v_cancel_write_off 2194 2194 0 0.00 0 2014 13 0 0
v_max_id_clo_ord 5825 5825 0 0.00 0 3765 2 0 0
v_max_id_clo_res 1062 1062 0 0.00 0 4046 25 0 0
v_max_id_ord_sup 5738 5738 0 0.00 0 5432 4 0 0
v_max_non_paid_invoice 1632 1632 0 0.00 0 3012 6 0 0
v_max_non_paid_realizn 3746 3746 0 0.00 0 836 3 0 0
v_min_id_clo_ord 5825 5825 0 0.00 0 2974 6 0 0
v_min_id_clo_res 1062 1062 0 0.00 0 3571 9 0 0
v_min_id_ord_sup 5738 5738 0 0.00 0 3838 6 0 0
v_min_non_paid_invoice 6154 6154 0 0.00 0 8390 22 0 0
v_min_non_paid_realizn 7500 7500 0 0.00 0 15448 24 0 0
v_our_firm 10884 10884 0 0.00 0 9 1 0 0
v_reserve_write_off 21034 21034 0 0.00 0 6108 5 0 0
x_lock_depdocs_on_canc_invoice 11658 11658 0 0.00 0 14847 110 0 0
x_lock_depdocs_on_canc_sup_ord 438 438 0 0.00 0 5274 327 0 0
x_make_qstorno_1000_1200 5825 5825 0 0.00 1 31055 1232 0 0
x_make_qstorno_1200_2000 5740 5740 0 0.00 2 83952 2978 0 0
x_make_qstorno_2100_3300 464048 464048 0 0.00 0 127744 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-11-2021 22:39:19 to 24-11-2021 22:39:23.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:02 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:03 28767 8192000000 9905007840 10218024960 986228976 10.745 100 88 176 2 1014858608 5861568 22768064
20:10:39 30881 8192000000 9950033120 10272944128 1031523728 11.183 100 93 186 0 1064545888 8867712 24154448
20:12:14 20254 8192000000 9992965920 10334154752 1073973312 11.590 100 82 163 1 1103673888 9877104 19823472
20:13:36 9630 8192000000 10060053136 10415480832 1143484736 12.248 100 74 147 1 1171727088 11320160 16922192
20:14:49 9288 8192000000 10091797120 10460946432 1182933136 12.618 100 77 154 1 1213241456 12928992 17379328
20:16:01 4812 8192000000 10133911680 10506653696 1223080816 12.990 100 78 157 0 1253767696 13560256 17126624
20:17:09 2768 8192000000 10172560512 10551050240 1254523600 13.280 100 87 172 1 1287819296 14223584 19072112
20:18:14 2324 8192000000 10204007696 10586206208 1282590272 13.537 100 94 185 1 1317805648 13805488 21409888
20:19:20 6383 8192000000 10205504272 10597969920 1295094784 13.651 100 78 157 2 1324021648 11566000 17360864
20:20:27 3866 8192000000 10197019856 10604761088 1303484432 13.727 100 42 84 2 1321952000 8398368 10069200
20:21:32 4897 8192000000 10202043824 10611818496 1310590512 13.791 100 28 57 0 1322596992 5501376 6505104
20:22:37 55571 8192000000 10204633200 10625990656 1316357888 13.844 100 23 46 1 1326741808 5356128 5027792
20:24:37 8683 8192000000 10213834848 10634592256 1324606240 13.918 100 26 52 2 1335654144 5623920 5423984
20:25:49 7442 8192000000 10214812464 10637193216 1326168032 13.933 100 15 31 1 1334213936 4489600 3556304
20:27:04 60756 8192000000 10195383296 10613956608 1269590800 13.418 96 26 52 0 1281395120 5333952 6470368
20:29:09 7367 8192000000 10180938912 10591547392 1271338176 13.434 98 23 46 0 1282948272 5267712 6342384
20:30:17 4698 8192000000 10118693360 10513854464 1195444880 12.734 96 25 50 1 1206757104 5528896 5783328
20:31:24 10259 8192000000 10154216784 10538983424 1208458400 12.855 97 23 46 0 1220349856 5747184 6144272
20:32:35 20473 8192000000 10133827360 10536022016 1208969872 12.860 96 20 41 3 1219316368 4691808 5654688
20:33:59 6313 8192000000 10167272144 10578636800 1281879776 13.530 100 24 48 0 1293320176 5148016 6292384
20:35:05 7094 8192000000 10192595280 10596929536 1290557056 13.609 100 33 66 0 1303815120 5497568 7760496
20:36:14 12309 8192000000 10192326960 10605498368 1301743696 13.711 100 21 42 2 1312249296 5037312 5468288
20:37:27 28845 8192000000 10199337888 10615492608 1311996096 13.804 100 28 56 0 1325014832 6192256 6826480
20:39:03 20598 8192000000 10245325808 10660208640 1344147680 14.095 100 47 94 0 1364466576 9372864 10946032
20:40:35 10237 8192000000 10270322096 10687111168 1364234224 14.275 100 47 94 1 1387074400 12350512 10489664
20:41:48 25167 8192000000 10289372080 10713231360 1372835968 14.352 100 38 72 1 1396138208 14382304 8919936
20:43:25 29712 8192000000 10307413376 10730889216 1391350528 14.518 100 45 89 0 1416787072 14595968 10840576
20:45:04 24275 8192000000 10316133744 10746691584 1401911680 14.612 100 38 76 3 1429127408 17922192 9293536
20:46:31 12903 8192000000 10338724096 10760146944 1409334624 14.678 100 63 126 0 1448353584 23988288 15030672
20:47:52 4405 8192000000 10361594160 10780090368 1422378704 14.794 100 76 152 2 1471564864 30797872 18388288
20:49:02 4109 8192000000 10372555040 10797551616 1434325552 14.900 100 57 114 2 1482775152 34138496 14311104
20:50:18 1373 8192000000 10381086416 10794934272 1429496832 14.857 100 92 184 0 1488958144 36551040 22910272
20:51:23 3145 8192000000 10380801168 10800762880 1427896960 14.843 100 75 151 1 1486500928 39567616 19036352
20:52:35 5059 8192000000 10378838384 10802044928 1414236256 14.722 99 67 133 3 1476184592 44810608 17137728
20:53:48 3254 8192000000 10362074992 10774163456 1392781888 14.531 99 77 154 1 1462858288 50005520 20070880
20:54:57 2378 8192000000 10337012064 10752237568 1373076784 14.355 99 62 124 0 1430884032 41945376 15861872
20:56:09 19537 8192000000 10317644544 10745356288 1380453808 14.421 100 39 78 0 1431855072 40650592 10750672
20:57:33 17126 8192000000 10332781040 10757038080 1375596048 14.377 100 36 70 3 1433211824 47892816 9722960
20:58:57 13220 8192000000 10312637024 10741448704 1353741344 14.181 98 30 60 0 1408239104 46692176 7805584
21:00:12 28594 8192000000 10321678192 10760818688 1374261008 14.365 100 26 52 0 1435634240 52721456 8651776
21:01:49 39422 8192000000 10285761936 10737332224 1346266912 14.114 99 20 40 1 1395861104 43716192 5878000
21:03:29 42963 8192000000 10258558592 10705891328 1339413104 14.052 99 13 26 0 1357091328 14578912 3099312
21:05:13 12263 8192000000 10281525808 10711605248 1350314352 14.150 99 47 94 3 1366915440 5283264 11317824
21:06:32 87970 8192000000 10264335600 10707410944 1351219280 14.158 100 27 54 2 1365471040 7837792 6413968
21:09:39 56367 8192000000 10266584960 10712825856 1321688672 13.892 96 23 45 1 1337002224 10506272 4807280
21:11:41 88758 8192000000 10286690864 10728849408 1358114464 14.220 98 19 38 0 1372403536 10110432 4178640
21:14:10 41570 8192000000 10308088880 10748702720 1381139504 14.427 99 40 80 1 1406619472 16177936 9302032
21:15:54 35895 8192000000 10296206256 10755899392 1384923568 14.461 100 15 30 1 1402316032 13787552 3604912
21:17:30 54572 8192000000 10295098240 10756124672 1396268432 14.562 100 18 36 1 1410843504 10797008 3778064
21:19:26 45069 8192000000 10290857216 10756476928 1381667872 14.431 99 15 30 1 1395809216 10837056 3304288
21:21:18 50408 8192000000 10302555104 10756526080 1396596896 14.565 100 24 46 1 1411022016 8975856 5449264
21:23:12 42938 8192000000 10296486112 10764627968 1391850544 14.522 99 12 24 1 1404535568 10001616 2683408
21:25:04 59697 8192000000 10289798576 10753863680 1389131024 14.498 99 18 36 0 1401634976 8325120 4178832
21:27:06 64296 8192000000 10311893136 10776571904 1408747360 14.673 100 11 22 2 1423627952 12098512 2782080
21:29:10 43872 8192000000 10315004864 10779115520 1410275120 14.686 100 19 38 1 1430299632 15713216 4311296
21:31:02 81879 8192000000 10303097440 10773012480 1352314032 14.168 96 15 28 2 1375362064 19468848 3579184
21:33:37 36464 8192000000 10322003824 10778996736 1405033840 14.640 100 29 58 0 1425692384 14597360 6061184
21:35:14 49602 8192000000 10305420400 10761977856 1379269936 14.410 98 29 59 1 1401767520 16019920 6477664
21:37:07 76425 8192000000 10282998992 10722320384 1298428864 13.681 93 15 28 2 1310636432 8960928 3246640
21:39:24 98584 8192000000 10280283280 10719641600 1339975264 14.057 97 47 94 1 1360148272 10087408 10085600
21:42:06 24735 8192000000 10249227616 10690183168 1322879776 13.903 97 48 95 0 1346426320 12955168 10591376
21:43:46 32062 8192000000 10248428256 10718920704 1340588288 14.063 99 3 6 0 1357731392 16803344 339760
21:45:18 22422 8192000000 10221533712 10669649920 1308767360 13.775 97 21 42 0 1320247008 6763632 4716016
21:46:49 89833 8192000000 10246345184 10707390464 1336343344 14.024 98 24 48 1 1351908800 9593424 5972032
21:49:29 31643 8192000000 10248767648 10697392128 1332799328 13.992 98 26 52 1 1346470064 6641584 7029152
21:51:01 49992 8192000000 10272844368 10716696576 1351580144 14.162 99 21 43 0 1369623472 12965728 5077600
21:52:54 62389 8192000000 10272601312 10729443328 1350861152 14.155 99 17 34 0 1370099184 15225984 4012048
21:54:59 87954 8192000000 10264088880 10709618688 1299878080 13.694 95 21 42 0 1316352096 11848096 4625920
21:57:28 87775 8192000000 10283918000 10738544640 1357198640 14.212 99 21 42 1 1376596256 14478288 4919328
21:59:57 111328 8192000000 10252827200 10695471104 1277125808 13.487 93 19 38 0 1292118864 11327664 3665392
22:02:53 58365 8192000000 10265080688 10710106112 1335713712 14.019 98 28 56 2 1351280784 8993456 6573616
22:05:03 43834 8192000000 10268667104 10720751616 1344514304 14.098 98 11 22 0 1358178704 11008288 2656112
22:06:48 87839 8192000000 10261756896 10722299904 1350006128 14.148 99 15 30 0 1367209456 13144848 4058480
22:09:16 83706 8192000000 10280437984 10745495552 1379545840 14.412 100 6 12 0 1391035584 10193440 1296304
22:11:40 64255 8192000000 10302030112 10757939200 1378819968 14.406 99 12 24 1 1390661296 9517824 2323504
22:13:50 88126 8192000000 10317718384 10785681408 1407579424 14.662 100 25 50 0 1429166432 15649728 5937280
22:16:20 17940 8192000000 10329541376 10769637376 1408298288 14.669 100 51 102 0 1428637456 8228544 12110624
22:17:41 77247 8192000000 10322011456 10781216768 1383150304 14.445 98 15 30 0 1407567696 21267184 3150208
22:20:05 83772 8192000000 10314688880 10772234240 1383405984 14.447 98 16 31 1 1400479728 14059360 3014384
22:22:30 57960 8192000000 10300785728 10748166144 1335017040 14.012 96 32 64 0 1357446160 15142288 7286832
22:24:41 129153 8192000000 10274262064 10726858752 1339710800 14.055 97 12 24 0 1354577280 11962576 2903904
22:27:55 35591 8192000000 10258198288 10725236736 1358709280 14.226 99 2 4 0 1366190496 7004080 477136
22:29:37 52457 8192000000 10243832144 10702336000 1324862880 13.921 97 14 28 1 1334258224 5779488 3615856
22:31:36 70230 8192000000 10241479136 10700374016 1319252448 13.870 98 21 42 1 1338409440 14110224 5046768
22:34:01 58712 8192000000 10243893248 10708246528 1335239040 14.014 99 6 12 0 1346162880 9700576 1223264
22:36:00 18149 8192000000 10241394592 10685759488 1329456704 13.962 99 32 60 1 1343368544 7744496 6167344
22:37:21 40927 8192000000 9233027648 9503694848 387864544 4.520 29 12 24 1 396012432 5626992 2520896

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-11-2021 22:39:25 to 24-11-2021 22:39:40.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 91 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-11-2021 22:39:40 to 24-11-2021 22:39:42.

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          242570
MON$OLDEST_ACTIVE               244833
MON$OLDEST_SNAPSHOT             244833
MON$NEXT_TRANSACTION            244833
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                       2019968
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 ---
:308: 
:309: mscs-test-161.budzdorov.ru    Wed Nov 24 22:38:05 2021
:310:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:311: 
:312: 
:313: mscs-test-161.budzdorov.ru    Wed Nov 24 22:38:07 2021
:314:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:315: 
--- end of diff output ---

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

24.11.21 22:39:43. 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-24 22:39:46 No severe PSQL-related problems occured
24.11.21 22:39:47. End of report.