Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.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 16291976 kB
MemAvailable 30948780 kB
Buffers 0 kB
Cached 15346536 kB
SwapCached 2580 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-17 22:37:46
DTS_END                         2021-08-17 22:37:46.3160
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(451:13) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/38500
TRN_ID                          241550
ATT_ID                          683
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: 9062.24 25788 1087469
customer order: creation 164.30 381 19716
customer order: refuse 14.17 363 1700
order to supplier: creation 44.59 1233 5351
order to supplier: removal 3.43 106583 411
invoice (draft): creation 44.52 3503 5342
invoice (draft): removal 52.51 773 6301
invoice accept: apply 40.85 18305 4902
invoice accept: cancel 48.06 769 5767
customer reserve: creation 3757.26 179 450871
customer reserve: removal 4597.90 62 551748
realization accept: apply 81.15 181 9738
realization accept: cancel 87.84 120 10541
payment from customer: creation 57.97 138 6956
payment from customer: removal 5.65 68 678
payment to supplier: creation 46.66 198 5599
payment to supplier: removal 3.52 119 422
service: total inventory turnovers 4.77 1423 572
service: total monetary turnovers 6.18 33 742
service: aggregate perf. data 0.90 18820 108
service: refresh index statistics 0.03 362516 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-08-2021 22:39:03 to 17-08-2021 22:39:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 393
TEST_TIME 2 1219
TEST_TIME 3 2014
TEST_TIME 4 2806
TEST_TIME 5 3432
TEST_TIME 6 3555
TEST_TIME 7 3898
TEST_TIME 8 4140
TEST_TIME 9 4641
TEST_TIME 10 4644
TEST_TIME 11 5534
TEST_TIME 12 5886
TEST_TIME 13 6514
TEST_TIME 14 6578
TEST_TIME 15 6723
TEST_TIME 16 6877
TEST_TIME 17 6927
TEST_TIME 18 7002
TEST_TIME 19 7068
TEST_TIME 20 7300
TEST_TIME 21 7873
TEST_TIME 22 8071
TEST_TIME 23 8496
TEST_TIME 24 8393
TEST_TIME 25 8701
TEST_TIME 26 8704
TEST_TIME 27 10231
TEST_TIME 28 10624
TEST_TIME 29 10382
TEST_TIME 30 10283
TEST_TIME 31 10075
TEST_TIME 32 9847
TEST_TIME 33 9628
TEST_TIME 34 9478
TEST_TIME 35 9484
TEST_TIME 36 9562
TEST_TIME 37 9517
TEST_TIME 38 9411
TEST_TIME 39 9460
TEST_TIME 40 9436
TEST_TIME 41 9376
TEST_TIME 42 9310
TEST_TIME 43 9178
TEST_TIME 44 9010
TEST_TIME 45 9110
TEST_TIME 46 9035
TEST_TIME 47 8983
TEST_TIME 48 8837
TEST_TIME 49 8822
TEST_TIME 50 8824
TEST_TIME 51 8831
TEST_TIME 52 8851
TEST_TIME 53 8855
TEST_TIME 54 8815
TEST_TIME 55 8926
TEST_TIME 56 8937
TEST_TIME 57 8991
TEST_TIME 58 9054
TEST_TIME 59 9075
TEST_TIME 60 9128
TEST_TIME 61 9144
TEST_TIME 62 9216
TEST_TIME 63 9279
TEST_TIME 64 9164
TEST_TIME 65 9094
TEST_TIME 66 9089
TEST_TIME 67 8999
TEST_TIME 68 8967
TEST_TIME 69 8980
TEST_TIME 70 9017
TEST_TIME 71 8984
TEST_TIME 72 8971
TEST_TIME 73 9013
TEST_TIME 74 9053
TEST_TIME 75 9018
TEST_TIME 76 9014
TEST_TIME 77 9111
TEST_TIME 78 9107
TEST_TIME 79 9235
TEST_TIME 80 9215
TEST_TIME 81 9223
TEST_TIME 82 9332
TEST_TIME 83 9291
TEST_TIME 84 9205
TEST_TIME 85 9129
TEST_TIME 86 9126
TEST_TIME 87 9207
TEST_TIME 88 9143
TEST_TIME 89 9104
TEST_TIME 90 9109
TEST_TIME 91 9107
TEST_TIME 92 9059
TEST_TIME 93 9109
TEST_TIME 94 9116
TEST_TIME 95 9124
TEST_TIME 96 9106
TEST_TIME 97 9153
TEST_TIME 98 9184
TEST_TIME 99 9200
TEST_TIME 100 9220
TEST_TIME 101 9171
TEST_TIME 102 9287
TEST_TIME 103 9269
TEST_TIME 104 9306
TEST_TIME 105 9364
TEST_TIME 106 9361
TEST_TIME 107 9336
TEST_TIME 108 9297
TEST_TIME 109 9247
TEST_TIME 110 9220
TEST_TIME 111 9196
TEST_TIME 112 9201
TEST_TIME 113 9175
TEST_TIME 114 9154
TEST_TIME 115 9137
TEST_TIME 116 9102
TEST_TIME 117 9078
TEST_TIME 118 9040
TEST_TIME 119 9085
TEST_TIME 120 9065

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-08-2021 22:39:06 to 17-08-2021 22:39:09.

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 1504656 1504656 0 0.00 0 29511 6 0 0
doc_list_biud 1504656 1504656 0 0.00 0 61939 29 0 0
sp_add_invoice_to_stock 5003 4902 101 2.01 35 221365 18305 0 101
sp_cancel_adding_invoice 5767 5767 0 0.00 4 76127 769 0 0
sp_cancel_client_order 1700 1700 0 0.00 4 27600 364 0 0
sp_cancel_customer_reserve 551748 551748 0 0.00 1 61944 63 0 0
sp_cancel_pay_from_customer 678 678 0 0.00 2 4266 68 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 3 14884 120 0 0
sp_cancel_supplier_invoice 6307 6301 6 0.09 1 25959 774 0 6
sp_cancel_supplier_order 411 411 0 0.00 9 1044906 106583 0 0
sp_cancel_write_off 10541 10541 0 0.00 1 32283 121 0 0
sp_client_order 19716 19716 0 0.00 4 70230 382 0 0
sp_customer_reserve 450871 450871 0 0.00 0 66078 179 0 0
sp_fill_shopping_cart 30401 30401 0 0.00 1 19037 41 0 0
sp_fill_shopping_cart_clo_res 450866 450866 0 0.00 0 25674 16 0 0
sp_get_clo_for_invoice 4889 4889 0 0.00 0 48255 398 0 0
sp_kill_cost_storno 17408 17408 0 0.00 0 24265 64 0 0
sp_kill_qstorno_ret_qs2qd 1120749 1120749 0 0.00 0 45009 34 0 0
sp_kill_qty_storno 591092 591092 0 0.00 0 61935 73 0 0
sp_lock_selected_doc 20303 20303 0 0.00 0 8025 4 0 0
sp_make_cost_storno 27179 27179 0 0.00 0 29496 55 0 0
sp_multiply_rows_for_pdistr 27179 27179 0 0.00 0 18529 37 0 0
sp_multiply_rows_for_qdistr 19716 19716 0 0.00 0 70217 157 0 0
sp_pay_from_customer 6956 6956 0 0.00 4 18134 139 0 0
sp_pay_to_supplier 5599 5599 0 0.00 5 26443 198 0 0
sp_payment_common 12555 12555 0 0.00 2 16479 36 0 0
sp_qd_handle_on_cancel_clo 1700 1700 0 0.00 0 11106 324 0 0
sp_qd_handle_on_invoice_upd_sts 10653 10653 0 0.00 0 40014 352 0 0
sp_qd_handle_on_reserve_upd_sts 9738 9738 0 0.00 0 15739 58 0 0
sp_reserve_write_off 9738 9738 0 0.00 6 15750 182 0 0
sp_supplier_invoice 5342 5342 0 0.00 7 93100 3503 0 0
sp_supplier_order 5351 5351 0 0.00 5 46169 1233 0 0
srv_aggregate_perf_data 109 108 1 0.91 2878 207081 18820 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 1950 150667 47580 0 0
srv_find_qd_qs_mism 1041053 1041053 0 0.00 0 15610 5 0 0
srv_make_invnt_saldo 572 572 0 0.00 29 256145 1424 0 0
srv_make_money_saldo 742 742 0 0.00 1 4976 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 263777 530210 362517 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 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 4902 4902 0 0.00 0 26008 182 0 0
t$perf-norm:sp_cancel_adding_invoice 425 425 0 0.00 0 6718 117 0 0
t$perf-norm:sp_cancel_client_order 1700 1700 0 0.00 0 6510 14 0 0
t$perf-norm:sp_cancel_customer_reserve 667 667 0 0.00 0 787 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 678 678 0 0.00 0 5192 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 1578 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 375 375 0 0.00 0 2379 25 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 24769 1503 0 0
t$perf-norm:sp_cancel_write_off 1001 1001 0 0.00 0 465 3 0 0
t$perf-norm:sp_client_order 19716 19716 0 0.00 0 10791 18 0 0
t$perf-norm:sp_customer_reserve 986 986 0 0.00 0 3549 11 0 0
t$perf-norm:sp_pay_from_customer 6956 6956 0 0.00 0 5195 12 0 0
t$perf-norm:sp_pay_to_supplier 5599 5599 0 0.00 0 8518 11 0 0
t$perf-norm:sp_reserve_write_off 9738 9738 0 0.00 0 19817 16 0 0
t$perf-norm:sp_supplier_invoice 5342 5342 0 0.00 0 4527 20 0 0
t$perf-norm:sp_supplier_order 5351 5351 0 0.00 0 7894 19 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 352 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 257 6 0 0
t$perf-norm:srv_make_invnt_saldo 572 572 0 0.00 0 948 22 0 0
t$perf-norm:srv_make_money_saldo 742 742 0 0.00 0 2631 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 9974 9772 202 2.02 0 17190 23 0 202
v_all_customers 36380 36380 0 0.00 0 67 1 0 0
v_all_suppliers 29272 29272 0 0.00 0 176 1 0 0
v_all_wares 39428 39428 0 0.00 0 68 1 0 0
v_cancel_adding_invoice 850 850 0 0.00 0 1161 6 0 0
v_cancel_client_order 3400 3400 0 0.00 0 26942 14 0 0
v_cancel_customer_prepayment 1356 1356 0 0.00 0 705 3 0 0
v_cancel_customer_reserve 1334 1334 0 0.00 0 970 5 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 1437 6 0 0
v_cancel_supplier_invoice 762 750 12 1.57 0 919 14 0 12
v_cancel_supplier_order 822 822 0 0.00 0 19129 31 0 0
v_cancel_write_off 2002 2002 0 0.00 0 19599 29 0 0
v_max_id_clo_ord 5351 5351 0 0.00 0 468 1 0 0
v_max_id_clo_res 986 986 0 0.00 0 2618 17 0 0
v_max_id_ord_sup 5335 5335 0 0.00 0 3147 4 0 0
v_max_non_paid_invoice 1649 1649 0 0.00 0 490 3 0 0
v_max_non_paid_realizn 3693 3693 0 0.00 0 3762 5 0 0
v_min_id_clo_ord 5351 5351 0 0.00 0 2284 5 0 0
v_min_id_clo_res 986 986 0 0.00 0 8934 30 0 0
v_min_id_ord_sup 5335 5335 0 0.00 0 8807 7 0 0
v_min_non_paid_invoice 5599 5599 0 0.00 0 16474 26 0 0
v_min_non_paid_realizn 6956 6956 0 0.00 0 8825 20 0 0
v_our_firm 9574 9574 0 0.00 0 67 1 0 0
v_reserve_write_off 19476 19476 0 0.00 0 6438 7 0 0
x_lock_depdocs_on_canc_invoice 11693 11693 0 0.00 0 18329 125 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 14959 443 0 0
x_make_qstorno_1000_1200 5351 5351 0 0.00 1 46152 1148 0 0
x_make_qstorno_1200_2000 5342 5342 0 0.00 3 93091 3359 0 0
x_make_qstorno_2100_3300 450871 450871 0 0.00 0 57059 160 0 0

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

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:50 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:51 29110 8192000000 10359906064 10678874112 1412963712 14.710 100 88 176 1 1447889392 6154016 28771664
20:10:27 25165 8192000000 10437028656 10768543744 1487875440 15.370 100 90 180 0 1525146240 8272576 28998224
20:11:56 18092 8192000000 10509889728 10856247296 1564805280 16.038 100 89 178 0 1602984608 10462512 27716816
20:13:19 13262 8192000000 10582215456 10940723200 1631883200 16.611 100 78 156 1 1667390112 12222160 23284752
20:14:34 7766 8192000000 10642038112 11008684032 1699383824 17.180 100 82 163 1 1737099920 14203232 23512864
20:15:47 3187 8192000000 10715146784 11090944000 1776495312 17.821 100 87 174 0 1814371024 13339344 24536368
20:16:52 4061 8192000000 10758495040 11140542464 1808699248 18.085 100 80 160 2 1842678352 12410368 21568736
20:17:58 1578 8192000000 10798893184 11187335168 1863194800 18.529 100 82 166 1 1898276720 12215312 22866608
20:19:01 36240 8192000000 10810325072 11213246464 1875464816 18.628 100 68 136 1 1905132400 9948432 19719152
20:20:38 5038 8192000000 10786665264 11216363520 1880542432 18.669 100 30 60 0 1899596784 10713376 8340976
20:21:45 9973 8192000000 10792619360 11225751552 1888635248 18.735 100 19 36 3 1899879792 5435024 5809520
20:22:57 11176 8192000000 10830578320 11252899840 1902757792 18.848 100 28 56 3 1917307200 6159344 8390064
20:24:12 28755 8192000000 10823895136 11257024512 1905077584 18.867 100 33 66 0 1921725792 7724448 8923760
20:25:43 5217 8192000000 10827483040 11258044416 1910542576 18.911 100 26 52 1 1924106944 6392096 7172272
20:26:50 8930 8192000000 10803196416 11233857536 1873036880 18.609 99 17 35 1 1883155776 5610528 4508368
20:27:59 13845 8192000000 10749722048 11178520576 1817907856 18.161 98 31 59 1 1832317168 5329856 9079456
20:29:53 5819 8192000000 10691994928 11104120832 1754880608 17.642 99 37 74 0 1776879376 11545232 10453536
20:31:05 6945 8192000000 10700336320 11123339264 1779382320 17.844 99 17 32 1 1790187120 5878816 4925984
20:32:12 5319 8192000000 10745280704 11163467776 1813396272 18.124 99 41 82 2 1831925168 5807584 12721312
20:33:18 32564 8192000000 10739880304 11176808448 1829457344 18.255 99 24 48 0 1841240896 5091552 6692000
20:34:51 5981 8192000000 10778381168 11203432448 1860728112 18.509 100 33 67 1 1876736144 6688656 9319376
20:35:57 5862 8192000000 10791975040 11218239488 1876311680 18.635 100 23 46 3 1888915376 5647200 6956496
20:37:04 18209 8192000000 10809507760 11241861120 1899017680 18.818 100 21 42 2 1910402624 5672352 5712592
20:38:24 118851 8192000000 10874391312 11301675008 1926682304 19.040 100 43 85 1 1947077648 7284944 13110400
20:41:27 29140 8192000000 10901384624 11333689344 1961920368 19.321 100 55 108 2 1996182688 17636144 16626176
20:43:03 14711 8192000000 10917459328 11350765568 1975047392 19.425 100 40 80 0 2005490432 18361264 12081776
20:44:21 3303 8192000000 10916948944 11343355904 1969707104 19.383 99 58 116 2 2006579072 19274864 17597104
20:45:28 4536 8192000000 10910090192 11343912960 1967930224 19.369 100 51 101 0 2003940896 21277408 14733264
20:46:39 9082 8192000000 10916643232 11353182208 1960250416 19.308 99 54 108 3 1999224464 22750640 16223408
20:47:54 6417 8192000000 10944759520 11383222272 1990099360 19.545 100 45 90 1 2033709248 30325296 13284592
20:49:02 6539 8192000000 10936403888 11357659136 1970152416 19.387 100 75 150 2 2025328720 32607008 22569296
20:50:13 5605 8192000000 10920494416 11346427904 1936125264 19.116 99 61 122 3 1993173600 38482752 18565584
20:51:23 2827 8192000000 10952392992 11367071744 1966154224 19.355 100 80 161 1 2029394576 39720352 23520000
20:52:31 1946 8192000000 10964803120 11381706752 1978551264 19.453 100 86 170 1 2050162992 45492384 26119344
20:53:40 2524 8192000000 10950706880 11358097408 1948247808 19.213 99 91 180 2 2027512656 51656848 27608000
20:54:53 6003 8192000000 10949538320 11369439232 1947925856 19.210 99 67 132 1 2020393696 51348160 21119680
20:56:11 3871 8192000000 10968222576 11372773376 1954589424 19.263 100 83 166 2 2035576912 54353456 26634032
20:57:19 4379 8192000000 10995324832 11407278080 1986354784 19.515 100 86 172 1 2076481632 63013072 27113776
20:58:28 8116 8192000000 11001327216 11436761088 2002099440 19.639 100 49 98 2 2086588400 67746144 16742816
20:59:44 24400 8192000000 10982058800 11434823680 1999823600 19.621 100 42 84 1 2072576864 58546944 14206320
21:01:14 15498 8192000000 10988601872 11449843712 1999315024 19.617 100 34 68 1 2073002784 61756160 11931600
21:03:09 36352 8192000000 10947605920 11408408576 1954521824 19.262 100 19 38 1 2031030976 68797584 7711568
21:04:46 60293 8192000000 10877136624 11347845120 1901082944 18.835 97 9 18 0 1942070000 37956112 3030944
21:06:48 10693 8192000000 10913662976 11359547392 1971427328 19.397 100 54 112 0 1995101312 8698928 14975056
21:08:01 150667 8192000000 10913255952 11378479104 1966790144 19.360 99 32 64 0 1991457776 15366976 9300656
21:11:33 127841 8192000000 10929557424 11373445120 1946643968 19.200 98 15 29 1 1969772896 19630432 3498496
21:14:42 54672 8192000000 10924777904 11391610880 1961821008 19.321 98 19 36 1 1982612640 16085376 4706256
21:16:50 41866 8192000000 10919892304 11399376896 1974242352 19.419 99 16 33 2 1991794288 12463104 5088832
21:18:33 48054 8192000000 10922043296 11402825728 1996444240 19.595 100 9 18 0 2011106848 11872928 2789680
21:20:40 73004 8192000000 10959500816 11410120704 2008072848 19.686 100 30 60 1 2026953008 9718592 9161568
21:22:53 40913 8192000000 10893219008 11371819008 1969831312 19.384 98 16 32 1 1982243376 8255920 4156144
21:24:36 34672 8192000000 10923796256 11409567744 2007553616 19.682 100 11 21 1 2025297344 14887552 2856176
21:26:12 53200 8192000000 10935753344 11421839360 1997482336 19.603 99 10 20 1 2011188672 10795680 2910656
21:28:17 54262 8192000000 10933090320 11412787200 1995344048 19.586 99 17 33 1 2007993264 7680224 4968992
21:30:12 31660 8192000000 10956758320 11423641600 1996298736 19.594 99 22 44 0 2011010432 8212560 6499136
21:31:52 82076 8192000000 10896599088 11375849472 1887798640 18.728 94 12 25 0 1904037552 12676064 3562848
21:34:15 19354 8192000000 10922739120 11389038592 1980679616 19.470 100 34 68 0 2001687568 11738464 9269488
21:35:35 71745 8192000000 10904067264 11392040960 1931533072 19.079 97 11 22 0 1947868704 12923248 3412384
21:37:47 82664 8192000000 10898580160 11372593152 1893333520 18.773 96 21 41 1 1908427136 9721328 5372288
21:40:12 106338 8192000000 10840373392 11305226240 1775377088 17.811 91 17 34 0 1789539616 9140768 5021760
21:42:59 71572 8192000000 10846667840 11334504448 1900927856 18.834 98 14 26 0 1916126864 11492992 3706016
21:45:12 38849 8192000000 10853609824 11324981248 1878164112 18.650 97 13 26 1 1890672576 8811552 3696912
21:47:20 74544 8192000000 10865453744 11333206016 1870471632 18.588 96 8 16 0 1880435680 7539888 2424160
21:49:38 31676 8192000000 10883376736 11329142784 1873105088 18.609 96 24 48 1 1892536944 12442944 6988912
21:51:33 68709 8192000000 10877715392 11340414976 1856800352 18.477 95 10 20 2 1873573104 13582688 3190064
21:53:55 75225 8192000000 10871368864 11338002432 1827618432 18.240 94 16 33 0 1849696304 17334592 4743280
21:57:16 54185 8192000000 10908669936 11381964800 1923472544 19.015 98 14 28 1 1944494688 16828288 4193856
21:59:12 44085 8192000000 10888386608 11358777344 1919200480 18.980 98 13 24 0 1938669136 16166784 3301872
22:01:09 102941 8192000000 10882011152 11349954560 1937946208 19.130 99 31 62 0 1958113024 10156256 10010560
22:03:57 71270 8192000000 10876613040 11369230336 1955856384 19.273 100 11 22 1 1970646208 11399248 3390576
22:06:18 77759 8192000000 10913155984 11391606784 1984282272 19.499 100 16 32 1 1997967520 9150512 4534736
22:08:37 98003 8192000000 10917052176 11398021120 1959950544 19.306 98 20 40 1 1974343824 8379632 6013648
22:11:16 52292 8192000000 10915758960 11401011200 1962563984 19.326 98 13 26 0 1980720224 15296864 2859376
22:13:09 35622 8192000000 10928995936 11406786560 2001576512 19.635 100 33 66 1 2017720992 7194960 8949520
22:14:45 34976 8192000000 10920247824 11402743808 1981962608 19.480 99 16 32 0 1999498224 13414944 4120672
22:16:21 70115 8192000000 10932341616 11408064512 1979639904 19.462 99 13 26 0 1993792208 10495456 3656848
22:18:31 71133 8192000000 10919888256 11420135424 1951309808 19.237 98 17 34 2 1977436640 21027040 5099792
22:20:51 74241 8192000000 10920554512 11407155200 1965542704 19.350 98 32 63 0 1992068896 17887248 8638944
22:23:08 34122 8192000000 10903300512 11375005696 1943045472 19.171 98 17 34 0 1956811824 8988192 4778160
22:24:47 81775 8192000000 10905691408 11370004480 1965977184 19.354 100 39 78 1 1987079376 10078016 11024176
22:28:05 22862 8192000000 10897988160 11363979264 1939130336 19.140 98 24 48 2 1956471664 10306704 7034624
22:29:30 52654 8192000000 10863715840 11353227264 1924452432 19.022 98 10 20 1 1940464224 12571744 3440048
22:31:28 62201 8192000000 10874153040 11351785472 1936332112 19.117 99 6 12 0 1945140256 7275920 1532224
22:33:31 72203 8192000000 10871712032 11347894272 1941709680 19.160 100 16 32 1 1957123184 10860176 4553328
22:37:21 28772 8192000000 9002332864 9274757120 143213616 1.718 8 3 6 0 145018048 823968 980464

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-08-2021 22:39:16 to 17-08-2021 22:39:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 101 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-08-2021 22:39:32 to 17-08-2021 22:39:34.

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          242284
MON$OLDEST_ACTIVE               242285
MON$OLDEST_SNAPSHOT             242285
MON$NEXT_TRANSACTION            242285
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                       2023552
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.33488 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33488 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33488 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33488 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 ---
:412: 
:413: mscs-test-161.budzdorov.ru    Tue Aug 17 22:37:53 2021
:414:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:415: 
--- end of diff output ---

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

17.08.21 22:39:35. 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-17 22:39:37 No severe PSQL-related problems occured
17.08.21 22:39:39. End of report.