Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.08.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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16183356 kB
MemAvailable 30970016 kB
Buffers 0 kB
Cached 15513784 kB
SwapCached 1868 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-08-23 22:37:46
DTS_END                         2021-08-23 22:37:46.0500
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(141:26) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/48218
TRN_ID                          236207
ATT_ID                          614
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: 9492.73 23674 1139128
customer order: creation 161.23 340 19348
customer order: refuse 13.88 325 1666
order to supplier: creation 43.02 1273 5162
order to supplier: removal 3.48 114573 418
invoice (draft): creation 43.85 3327 5262
invoice (draft): removal 54.60 824 6552
invoice accept: apply 40.52 18511 4862
invoice accept: cancel 50.20 791 6024
customer reserve: creation 3809.16 179 457099
customer reserve: removal 4985.21 63 598225
realization accept: apply 78.49 183 9419
realization accept: cancel 84.39 114 10127
payment from customer: creation 57.09 118 6851
payment from customer: removal 5.63 66 675
payment to supplier: creation 46.73 144 5608
payment to supplier: removal 3.18 81 381
service: total inventory turnovers 4.68 1046 562
service: total monetary turnovers 6.48 28 777
service: aggregate perf. data 0.88 17712 106
service: refresh index statistics 0.03 313784 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-08-2021 22:39:09 to 23-08-2021 22:39:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3528
TEST_TIME 2 3453
TEST_TIME 3 4053
TEST_TIME 4 3966
TEST_TIME 5 4364
TEST_TIME 6 4872
TEST_TIME 7 5097
TEST_TIME 8 5267
TEST_TIME 9 5170
TEST_TIME 10 5703
TEST_TIME 11 5517
TEST_TIME 12 5717
TEST_TIME 13 5706
TEST_TIME 14 6032
TEST_TIME 15 6468
TEST_TIME 16 6582
TEST_TIME 17 6807
TEST_TIME 18 7060
TEST_TIME 19 7170
TEST_TIME 20 7476
TEST_TIME 21 8113
TEST_TIME 22 8468
TEST_TIME 23 8631
TEST_TIME 24 9018
TEST_TIME 25 9269
TEST_TIME 26 9207
TEST_TIME 27 10014
TEST_TIME 28 10185
TEST_TIME 29 11624
TEST_TIME 30 11370
TEST_TIME 31 11135
TEST_TIME 32 10883
TEST_TIME 33 10878
TEST_TIME 34 10859
TEST_TIME 35 10877
TEST_TIME 36 10655
TEST_TIME 37 10561
TEST_TIME 38 10513
TEST_TIME 39 10437
TEST_TIME 40 10509
TEST_TIME 41 10500
TEST_TIME 42 10459
TEST_TIME 43 10400
TEST_TIME 44 10274
TEST_TIME 45 10257
TEST_TIME 46 10158
TEST_TIME 47 10085
TEST_TIME 48 10049
TEST_TIME 49 9991
TEST_TIME 50 9875
TEST_TIME 51 9834
TEST_TIME 52 9704
TEST_TIME 53 9742
TEST_TIME 54 9823
TEST_TIME 55 9872
TEST_TIME 56 9815
TEST_TIME 57 9952
TEST_TIME 58 9936
TEST_TIME 59 9927
TEST_TIME 60 9994
TEST_TIME 61 9987
TEST_TIME 62 10016
TEST_TIME 63 10141
TEST_TIME 64 10153
TEST_TIME 65 10210
TEST_TIME 66 10139
TEST_TIME 67 10042
TEST_TIME 68 10046
TEST_TIME 69 9982
TEST_TIME 70 9873
TEST_TIME 71 9841
TEST_TIME 72 9809
TEST_TIME 73 9733
TEST_TIME 74 9702
TEST_TIME 75 9710
TEST_TIME 76 9669
TEST_TIME 77 9594
TEST_TIME 78 9644
TEST_TIME 79 9678
TEST_TIME 80 9641
TEST_TIME 81 9683
TEST_TIME 82 9742
TEST_TIME 83 9802
TEST_TIME 84 9759
TEST_TIME 85 9750
TEST_TIME 86 9839
TEST_TIME 87 9856
TEST_TIME 88 9917
TEST_TIME 89 9912
TEST_TIME 90 9891
TEST_TIME 91 9824
TEST_TIME 92 9814
TEST_TIME 93 9798
TEST_TIME 94 9761
TEST_TIME 95 9730
TEST_TIME 96 9677
TEST_TIME 97 9652
TEST_TIME 98 9623
TEST_TIME 99 9607
TEST_TIME 100 9608
TEST_TIME 101 9579
TEST_TIME 102 9607
TEST_TIME 103 9680
TEST_TIME 104 9673
TEST_TIME 105 9672
TEST_TIME 106 9699
TEST_TIME 107 9674
TEST_TIME 108 9729
TEST_TIME 109 9697
TEST_TIME 110 9756
TEST_TIME 111 9751
TEST_TIME 112 9714
TEST_TIME 113 9676
TEST_TIME 114 9634
TEST_TIME 115 9633
TEST_TIME 116 9609
TEST_TIME 117 9567
TEST_TIME 118 9539
TEST_TIME 119 9532
TEST_TIME 120 9495

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-08-2021 22:39:13 to 23-08-2021 22:39:16.

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 1561861 1561861 0 0.00 0 40064 6 0 0
doc_list_biud 1561861 1561861 0 0.00 0 79740 30 0 0
sp_add_invoice_to_stock 5022 4862 160 3.18 68 220933 18511 0 160
sp_cancel_adding_invoice 6024 6024 0 0.00 4 90705 791 0 0
sp_cancel_client_order 1666 1666 0 0.00 4 9370 326 0 0
sp_cancel_customer_reserve 598225 598225 0 0.00 1 79742 64 0 0
sp_cancel_pay_from_customer 675 675 0 0.00 2 2233 67 0 0
sp_cancel_pay_to_supplier 381 381 0 0.00 3 3304 82 0 0
sp_cancel_supplier_invoice 6567 6552 15 0.22 2 14418 825 0 15
sp_cancel_supplier_order 418 418 0 0.00 7 1197988 114573 0 0
sp_cancel_write_off 10127 10127 0 0.00 1 19609 115 0 0
sp_client_order 19348 19348 0 0.00 3 43336 340 0 0
sp_customer_reserve 457099 457099 0 0.00 0 91354 179 0 0
sp_fill_shopping_cart 29755 29755 0 0.00 1 10312 36 0 0
sp_fill_shopping_cart_clo_res 457095 457095 0 0.00 0 13235 16 0 0
sp_get_clo_for_invoice 4856 4856 0 0.00 0 21254 320 0 0
sp_kill_cost_storno 17207 17207 0 0.00 0 10996 60 0 0
sp_kill_qstorno_ret_qs2qd 1213547 1213547 0 0.00 0 79735 34 0 0
sp_kill_qty_storno 637283 637283 0 0.00 0 79739 73 0 0
sp_lock_selected_doc 19791 19791 0 0.00 0 5952 5 0 0
sp_make_cost_storno 26730 26730 0 0.00 0 40056 53 0 0
sp_multiply_rows_for_pdistr 26730 26730 0 0.00 0 9558 29 0 0
sp_multiply_rows_for_qdistr 19348 19348 0 0.00 0 35996 151 0 0
sp_pay_from_customer 6851 6851 0 0.00 4 38415 118 0 0
sp_pay_to_supplier 5608 5608 0 0.00 5 26541 145 0 0
sp_payment_common 12459 12459 0 0.00 2 38383 31 0 0
sp_qd_handle_on_cancel_clo 1666 1666 0 0.00 0 8791 292 0 0
sp_qd_handle_on_invoice_upd_sts 10876 10876 0 0.00 0 20246 328 0 0
sp_qd_handle_on_reserve_upd_sts 9419 9419 0 0.00 0 9394 59 0 0
sp_reserve_write_off 9419 9419 0 0.00 7 20951 184 0 0
sp_supplier_invoice 5262 5262 0 0.00 7 78474 3328 0 0
sp_supplier_order 5162 5162 0 0.00 5 38678 1274 0 0
srv_aggregate_perf_data 106 106 0 0.00 2297 225599 17713 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1361 135045 46810 0 0
srv_find_qd_qs_mism 1092824 1092824 0 0.00 0 16306 5 0 0
srv_make_invnt_saldo 562 562 0 0.00 33 64825 1047 0 0
srv_make_money_saldo 777 777 0 0.00 0 902 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 198122 433163 313785 0 0
t$perf-abend:sp_add_invoice_to_stock 160 160 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4862 4862 0 0.00 0 17938 200 0 0
t$perf-norm:sp_cancel_adding_invoice 425 425 0 0.00 0 12405 120 0 0
t$perf-norm:sp_cancel_client_order 1666 1666 0 0.00 0 4364 13 0 0
t$perf-norm:sp_cancel_customer_reserve 623 623 0 0.00 0 1790 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 675 675 0 0.00 0 6630 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 381 381 0 0.00 0 5343 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 3813 23 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 21301 1701 0 0
t$perf-norm:sp_cancel_write_off 949 949 0 0.00 0 6673 22 0 0
t$perf-norm:sp_client_order 19348 19348 0 0.00 0 10985 20 0 0
t$perf-norm:sp_customer_reserve 1005 1005 0 0.00 0 3052 11 0 0
t$perf-norm:sp_pay_from_customer 6851 6851 0 0.00 0 9060 11 0 0
t$perf-norm:sp_pay_to_supplier 5608 5608 0 0.00 0 4201 10 0 0
t$perf-norm:sp_reserve_write_off 9419 9419 0 0.00 0 10615 17 0 0
t$perf-norm:sp_supplier_invoice 5262 5262 0 0.00 0 3804 18 0 0
t$perf-norm:sp_supplier_order 5162 5162 0 0.00 0 11066 21 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 846 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 12 1 0 0
t$perf-norm:srv_make_invnt_saldo 562 562 0 0.00 0 10871 53 0 0
t$perf-norm:srv_make_money_saldo 777 777 0 0.00 0 592 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 8 4 0 0
v_add_invoice_to_stock 10022 9702 320 3.19 0 22794 30 0 320
v_all_customers 35540 35540 0 0.00 0 37 1 0 0
v_all_suppliers 29024 29024 0 0.00 0 44 1 0 0
v_all_wares 38696 38696 0 0.00 0 64 1 0 0
v_cancel_adding_invoice 850 850 0 0.00 0 23075 33 0 0
v_cancel_client_order 3332 3332 0 0.00 0 9322 10 0 0
v_cancel_customer_prepayment 1350 1350 0 0.00 0 1865 5 0 0
v_cancel_customer_reserve 1246 1246 0 0.00 0 18989 21 0 0
v_cancel_payment_to_supplier 762 762 0 0.00 0 554 4 0 0
v_cancel_supplier_invoice 798 768 30 3.75 0 3670 21 0 30
v_cancel_supplier_order 836 836 0 0.00 0 22319 37 0 0
v_cancel_write_off 1898 1898 0 0.00 0 16093 29 0 0
v_max_id_clo_ord 5162 5162 0 0.00 0 2465 2 0 0
v_max_id_clo_res 1005 1005 0 0.00 0 3832 25 0 0
v_max_id_ord_sup 5244 5244 0 0.00 0 4103 3 0 0
v_max_non_paid_invoice 1502 1502 0 0.00 0 1366 4 0 0
v_max_non_paid_realizn 3508 3508 0 0.00 0 5335 4 0 0
v_min_id_clo_ord 5162 5162 0 0.00 0 2706 4 0 0
v_min_id_clo_res 1005 1005 0 0.00 0 6849 19 0 0
v_min_id_ord_sup 5244 5244 0 0.00 0 6795 9 0 0
v_min_non_paid_invoice 5608 5608 0 0.00 0 9294 19 0 0
v_min_non_paid_realizn 6851 6851 0 0.00 0 8284 18 0 0
v_our_firm 9842 9842 0 0.00 0 27 1 0 0
v_reserve_write_off 18838 18838 0 0.00 0 20811 9 0 0
x_lock_depdocs_on_canc_invoice 12192 12192 0 0.00 0 14229 132 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 5331 373 0 0
x_make_qstorno_1000_1200 5162 5162 0 0.00 1 38614 1202 0 0
x_make_qstorno_1200_2000 5262 5262 0 0.00 3 78464 3195 0 0
x_make_qstorno_2100_3300 457099 457099 0 0.00 0 91351 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-08-2021 22:39:17 to 23-08-2021 22:39:21.

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:07:49 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:50 19447 8192000000 10382170704 10703585280 1436236000 14.916 100 85 170 2 1470109088 6236688 27636400
20:10:27 16760 8192000000 10458732352 10791751680 1505616800 15.525 100 87 174 0 1542382000 9184880 27580320
20:11:56 15710 8192000000 10525070320 10873470976 1587512992 16.233 100 82 164 0 1623239248 11018528 24707728
20:13:16 16924 8192000000 10573900736 10931376128 1634834960 16.636 100 81 162 0 1670263696 11864848 23563888
20:14:38 7647 8192000000 10645965120 11020242944 1710812240 17.276 100 76 152 1 1744828368 12260208 21755920
20:15:48 5029 8192000000 10709235872 11084759040 1769391344 17.762 100 86 170 2 1806593520 12912272 24289904
20:16:54 1419 8192000000 10771429824 11149840384 1821492816 18.190 100 90 176 1 1860219184 14705504 24020864
20:17:57 2190 8192000000 10801736752 11188498432 1853786544 18.453 100 91 181 1 1893732000 14311216 25634240
20:19:01 1601 8192000000 10820922896 11209457664 1874069776 18.617 100 93 184 0 1914072592 13717936 26284880
20:20:03 9462 8192000000 10810217488 11214295040 1880764816 18.671 100 46 92 0 1903380000 8559008 14056176
20:21:14 8547 8192000000 10793705712 11226632192 1887754384 18.728 100 24 48 0 1901938192 7197936 6985872
20:22:29 7018 8192000000 10811112640 11243864064 1905086832 18.867 100 19 38 1 1915784240 5854528 4842880
20:23:39 55293 8192000000 10839921632 11265777664 1910395408 18.910 100 22 44 2 1922617424 5713504 6508512
20:25:35 1981 8192000000 10853193504 11273973760 1927778720 19.049 100 55 108 1 1950876480 7420608 15677152
20:26:38 7155 8192000000 10824065728 11248910336 1873822544 18.615 98 31 62 0 1889020400 5781632 9416224
20:27:46 10112 8192000000 10768743296 11199709184 1773593072 17.797 94 9 18 0 1780587472 4677424 2316976
20:29:02 17058 8192000000 10714761296 11125166080 1730063936 17.436 95 29 58 1 1744182784 5901504 8217344
20:30:22 5054 8192000000 10710573152 11124043776 1732505008 17.456 96 28 54 1 1745188448 4717616 7965824
20:31:28 8987 8192000000 10735101456 11158626304 1788580160 17.920 98 24 48 2 1800898304 5532400 6785744
20:32:37 55276 8192000000 10710133648 11138449408 1780135248 17.851 98 30 60 0 1794600240 5746672 8718320
20:34:37 6371 8192000000 10752243856 11185160192 1844479440 18.377 100 31 63 0 1859518528 6041072 8998016
20:35:45 8906 8192000000 10773524352 11205660672 1863912160 18.535 100 23 46 0 1876303920 5582656 6809104
20:37:01 56995 8192000000 10818425904 11242856448 1894307360 18.780 100 35 70 1 1910858368 5120496 11430512
20:39:04 13562 8192000000 10854914416 11276222464 1919419936 18.982 100 45 90 1 1939540272 7700752 12419584
20:40:23 25271 8192000000 10882154464 11309670400 1950140288 19.228 100 61 122 3 1980525312 12648544 17736480
20:41:53 24976 8192000000 10900112416 11333189632 1961417888 19.317 100 52 104 0 1994789680 17784848 15586944
20:43:22 12506 8192000000 10922153648 11352010752 1976345760 19.436 100 51 102 1 2014472768 22428496 15698512
20:44:39 6810 8192000000 10929457504 11361755136 1984743264 19.502 100 51 101 2 2019941472 20382208 14816000
20:45:51 13503 8192000000 10948711440 11381035008 2000249584 19.625 100 48 96 1 2044041680 29833952 13958144
20:47:09 13494 8192000000 10977427152 11410145280 2018045808 19.765 100 54 108 0 2060043264 25350256 16647200
20:48:27 14077 8192000000 10957628272 11397120000 1992447248 19.563 99 44 88 2 2036080400 30014256 13618896
20:49:48 11786 8192000000 10967334608 11400237056 2005280016 19.664 100 60 118 0 2056547104 33138272 18128816
20:51:12 1361 8192000000 11007875568 11423207424 2017503296 19.761 100 86 170 3 2090584800 46738400 26343104
20:52:17 1926 8192000000 11004130320 11422445568 2010193424 19.703 100 94 188 5 2089871088 51155264 28522400
20:53:24 3146 8192000000 10977114832 11394461696 1978698640 19.454 100 80 160 1 2056674224 53586000 24389584
20:54:34 7006 8192000000 10973430192 11394248704 1965915744 19.353 99 69 139 2 2044030240 55437808 22676688
20:55:45 3683 8192000000 10992522784 11408756736 1986317072 19.515 100 80 161 0 2074131632 62385616 25428944
20:56:55 1482 8192000000 11014609328 11427418112 1994863040 19.582 100 79 158 0 2089802640 69380288 25559312
20:58:04 10625 8192000000 10996086240 11432017920 1958346896 19.293 98 51 102 0 2052923264 76130752 18445616
20:59:19 17677 8192000000 10952641024 11405778944 1954167808 19.260 98 39 78 1 2032172848 64883280 13121760
21:00:43 17136 8192000000 10961615616 11412602880 1966797456 19.360 99 38 76 0 2044371264 64569584 13004224
21:02:15 21737 8192000000 10946721728 11404926976 1931728352 19.081 98 27 54 2 2002454032 61858992 8866688
21:03:39 29367 8192000000 10927978016 11385016320 1915889360 18.954 99 28 54 4 2005041088 78784768 10366960
21:05:15 46716 8192000000 10892085440 11360370688 1900925600 18.834 99 14 28 0 1963390496 57220448 5244448
21:07:13 45048 8192000000 10871259504 11299868672 1886714208 18.719 97 21 42 2 1902383248 10133680 5535360
21:09:00 94985 8192000000 10843600000 11298390016 1871858096 18.599 96 23 47 1 1884371360 6658752 5854512
21:11:42 135044 8192000000 10873080272 11339452416 1929704752 19.065 99 6 12 2 1949696544 18453472 1538320
21:14:58 81745 8192000000 10908404976 11363434496 1946503680 19.199 99 18 36 0 1967038448 15463152 5071616
21:17:28 54624 8192000000 10899686816 11384016896 1969223072 19.379 100 22 44 2 1995241424 19628208 6390144
21:19:24 58389 8192000000 10873729184 11348901888 1926586240 19.040 98 10 20 1 1940488304 10717872 3184192
21:21:22 61879 8192000000 10879558864 11347861504 1943868336 19.178 99 16 33 0 1959432880 10818176 4746368
21:23:25 85858 8192000000 10888002976 11367215104 1967811456 19.368 100 15 30 0 1982040880 10066800 4162624
21:26:05 32579 8192000000 10887434752 11366019072 1958366768 19.293 99 11 22 1 1969376624 8001312 3008544
21:27:38 61862 8192000000 10884785936 11367579648 1923434128 19.014 97 6 12 0 1932813600 7840144 1539328
21:29:40 91473 8192000000 10873356432 11351248896 1899116864 18.819 96 19 38 0 1925345520 20576416 5652240
21:32:23 43330 8192000000 10901605696 11361640448 1964610864 19.343 100 15 30 0 1980389232 11674016 4104352
21:34:09 71193 8192000000 10885858768 11352121344 1939497248 19.143 99 10 20 0 1955457136 13393216 2566672
21:36:30 20715 8192000000 10913943392 11376332800 1977183040 19.442 100 37 74 2 2001910736 14903632 9824064
21:37:59 13395 8192000000 10904205728 11341701120 1930464464 19.071 98 47 94 1 1951532496 7821952 13246080
21:39:15 112880 8192000000 10880624336 11352178688 1843568240 18.370 93 6 12 0 1861590288 16399808 1622240
21:42:10 120204 8192000000 10920222496 11372998656 1938182384 19.132 98 25 51 1 1960176416 15608400 6385632
21:45:12 14181 8192000000 10894497440 11360788480 1962985200 19.330 100 23 46 2 1979457120 9385200 7086720
21:46:26 50416 8192000000 10881220560 11363328000 1947765760 19.209 99 12 24 2 1964598208 13619616 3212832
21:48:18 72346 8192000000 10887135792 11360636928 1918338464 18.974 98 9 18 0 1932135184 11272208 2524512
21:50:33 53240 8192000000 10902648352 11386580992 1984862448 19.503 100 15 30 0 1999387760 10085760 4439552
21:52:45 85733 8192000000 10908338720 11376832512 1960447712 19.310 99 19 38 1 1988327984 22696224 5184048
21:55:13 64042 8192000000 10860582592 11358035968 1911833968 18.921 98 9 18 0 1934448976 20437952 2177056
21:57:31 42505 8192000000 10921000816 11380916224 1980478592 19.468 100 33 65 1 1999931408 9988064 9464752
21:59:13 68486 8192000000 10905396960 11380019200 1967955632 19.369 100 19 38 1 1986006736 12786368 5264736
22:01:22 78117 8192000000 10892861792 11353591808 1886049888 18.714 96 21 42 0 1909707072 17780624 5876560
22:03:40 48148 8192000000 10863043648 11325210624 1846640416 18.395 95 8 16 0 1862718304 13908400 2169488
22:05:33 86496 8192000000 10869719312 11340767232 1871635216 18.598 97 19 38 3 1897176224 20754320 4786688
22:08:05 119405 8192000000 10870406608 11361800192 1905957392 18.874 97 28 56 1 1922836880 9572240 7307248
22:11:18 69007 8192000000 10877310704 11354955776 1944535488 19.183 99 9 18 1 1955072352 7698000 2838864
22:13:32 76937 8192000000 10868175808 11348606976 1907453536 18.886 97 20 40 0 1923434784 10326240 5655008
22:16:50 74316 8192000000 10874305920 11354304512 1900716656 18.832 97 8 16 0 1911213616 8337488 2159472
22:19:04 66222 8192000000 10900400032 11391029248 1977609120 19.446 100 20 40 0 1999155312 15452016 6094176
22:21:11 33497 8192000000 10902943600 11376316416 1961481744 19.318 99 19 38 1 1974085936 7245568 5358624
22:22:54 38412 8192000000 10904133888 11369455616 1954676768 19.264 99 12 24 1 1965717856 7962576 3078512
22:24:35 27273 8192000000 10913698784 11372482560 1930551920 19.071 97 44 86 0 1955964752 13484528 11928304
22:26:04 58043 8192000000 10900618848 11365851136 1883764992 18.695 95 29 58 0 1904675280 12044064 8866224
22:28:02 26186 8192000000 10896563408 11362443264 1933181296 19.092 98 12 24 2 1947558416 10932240 3444880
22:29:31 31602 8192000000 10891511184 11345817600 1893648192 18.775 96 44 87 1 1918111200 12057216 12405792
22:32:14 77592 8192000000 10873962768 11364417536 1958325744 19.293 100 31 63 1 1977633808 10621920 8686144
22:35:17 55193 8192000000 10892713536 11380477952 1971979840 19.401 100 8 17 0 1980971728 6802944 2188944
22:37:13 38207 8192000000 9476597696 9803620352 622942464 7.066 32 13 26 0 629968240 3546512 3479264

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 23-08-2021 22:39:23 to 23-08-2021 22:39:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 160 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-08-2021 22:39:38 to 23-08-2021 22:39:40.

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          234936
MON$OLDEST_ACTIVE               236496
MON$OLDEST_SNAPSHOT             236496
MON$NEXT_TRANSACTION            236496
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                       2035872
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.33489 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33489 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33489 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 ---
:368: 
:369: mscs-test-161.budzdorov.ru    Mon Aug 23 22:37:55 2021
:370:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:371: 
:372: 
:373: mscs-test-161.budzdorov.ru    Mon Aug 23 22:37:55 2021
:374:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:375: 
:376: 
:377: mscs-test-161.budzdorov.ru    Mon Aug 23 22:37:57 2021
:378:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:379: 
:380: 
:381: mscs-test-161.budzdorov.ru    Mon Aug 23 22:38:00 2021
:382:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:383: 
--- end of diff output ---

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

23.08.21 22:39:41. 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-08-23 22:39:44 No severe PSQL-related problems occured
23.08.21 22:39:45. End of report.