Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.08.2021 01: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 16164896 kB
MemAvailable 31016440 kB
Buffers 0 kB
Cached 15463464 kB
SwapCached 2572 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-04 01:37:40
DTS_END                         2021-08-04 01:37:40.6160
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(376:13) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/45330
TRN_ID                          241311
ATT_ID                          672
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: 9120.33 19552 1094440
customer order: creation 169.05 389 20286
customer order: refuse 14.28 374 1713
order to supplier: creation 45.79 1363 5495
order to supplier: removal 3.26 108417 391
invoice (draft): creation 45.70 3795 5484
invoice (draft): removal 52.29 843 6275
invoice accept: apply 41.57 17213 4988
invoice accept: cancel 48.26 862 5791
customer reserve: creation 3734.00 173 448080
customer reserve: removal 4675.31 59 561037
realization accept: apply 82.20 197 9864
realization accept: cancel 79.86 109 9583
payment from customer: creation 58.41 147 7009
payment from customer: removal 5.50 89 660
payment to supplier: creation 48.93 165 5871
payment to supplier: removal 3.37 61 404
service: total inventory turnovers 4.95 1200 594
service: total monetary turnovers 6.75 31 810
service: aggregate perf. data 0.84 15743 101
service: refresh index statistics 0.03 239805 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-08-2021 01:38:41 to 04-08-2021 01:38:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3439
TEST_TIME 2 4227
TEST_TIME 3 4347
TEST_TIME 4 4008
TEST_TIME 5 3979
TEST_TIME 6 4577
TEST_TIME 7 4769
TEST_TIME 8 4848
TEST_TIME 9 4831
TEST_TIME 10 5185
TEST_TIME 11 5446
TEST_TIME 12 5443
TEST_TIME 13 5607
TEST_TIME 14 5727
TEST_TIME 15 6097
TEST_TIME 16 6593
TEST_TIME 17 6741
TEST_TIME 18 7140
TEST_TIME 19 7086
TEST_TIME 20 7169
TEST_TIME 21 7969
TEST_TIME 22 8353
TEST_TIME 23 8318
TEST_TIME 24 8683
TEST_TIME 25 8874
TEST_TIME 26 9338
TEST_TIME 27 9324
TEST_TIME 28 9678
TEST_TIME 29 10556
TEST_TIME 30 10800
TEST_TIME 31 10478
TEST_TIME 32 10525
TEST_TIME 33 10631
TEST_TIME 34 10538
TEST_TIME 35 10440
TEST_TIME 36 10215
TEST_TIME 37 10191
TEST_TIME 38 10158
TEST_TIME 39 10087
TEST_TIME 40 10009
TEST_TIME 41 10131
TEST_TIME 42 9982
TEST_TIME 43 9916
TEST_TIME 44 9794
TEST_TIME 45 9710
TEST_TIME 46 9628
TEST_TIME 47 9803
TEST_TIME 48 9665
TEST_TIME 49 9613
TEST_TIME 50 9468
TEST_TIME 51 9343
TEST_TIME 52 9278
TEST_TIME 53 9326
TEST_TIME 54 9266
TEST_TIME 55 9213
TEST_TIME 56 9153
TEST_TIME 57 9153
TEST_TIME 58 9030
TEST_TIME 59 9020
TEST_TIME 60 8950
TEST_TIME 61 9035
TEST_TIME 62 9067
TEST_TIME 63 9064
TEST_TIME 64 9082
TEST_TIME 65 9129
TEST_TIME 66 9186
TEST_TIME 67 9163
TEST_TIME 68 9143
TEST_TIME 69 9256
TEST_TIME 70 9273
TEST_TIME 71 9332
TEST_TIME 72 9341
TEST_TIME 73 9300
TEST_TIME 74 9290
TEST_TIME 75 9188
TEST_TIME 76 9173
TEST_TIME 77 9179
TEST_TIME 78 9116
TEST_TIME 79 9139
TEST_TIME 80 9066
TEST_TIME 81 9051
TEST_TIME 82 9034
TEST_TIME 83 8949
TEST_TIME 84 9001
TEST_TIME 85 8959
TEST_TIME 86 9020
TEST_TIME 87 9006
TEST_TIME 88 8991
TEST_TIME 89 9030
TEST_TIME 90 9031
TEST_TIME 91 9077
TEST_TIME 92 9071
TEST_TIME 93 9108
TEST_TIME 94 9095
TEST_TIME 95 9122
TEST_TIME 96 9170
TEST_TIME 97 9119
TEST_TIME 98 9217
TEST_TIME 99 9186
TEST_TIME 100 9265
TEST_TIME 101 9185
TEST_TIME 102 9138
TEST_TIME 103 9104
TEST_TIME 104 9092
TEST_TIME 105 9055
TEST_TIME 106 9043
TEST_TIME 107 9052
TEST_TIME 108 8995
TEST_TIME 109 8987
TEST_TIME 110 8951
TEST_TIME 111 8956
TEST_TIME 112 8958
TEST_TIME 113 8958
TEST_TIME 114 8940
TEST_TIME 115 8993
TEST_TIME 116 8961
TEST_TIME 117 8927
TEST_TIME 118 9022
TEST_TIME 119 9145
TEST_TIME 120 9121

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 04-08-2021 01:38:44 to 04-08-2021 01:38:48.

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 1509596 1509596 0 0.00 0 18075 6 0 0
doc_list_biud 1509595 1509595 0 0.00 0 71326 28 0 0
sp_add_invoice_to_stock 5125 4988 137 2.67 60 185449 17214 0 137
sp_cancel_adding_invoice 5791 5791 0 0.00 4 78842 863 0 0
sp_cancel_client_order 1713 1713 0 0.00 4 15353 375 0 0
sp_cancel_customer_reserve 561037 561037 0 0.00 1 49503 59 0 0
sp_cancel_pay_from_customer 660 660 0 0.00 3 5529 89 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 3 1008 61 0 0
sp_cancel_supplier_invoice 6284 6275 9 0.14 1 71335 843 0 9
sp_cancel_supplier_order 391 391 0 0.00 8 1041450 108418 0 0
sp_cancel_write_off 9583 9583 0 0.00 1 12291 109 0 0
sp_client_order 20286 20286 0 0.00 3 22253 390 0 0
sp_customer_reserve 448080 448080 0 0.00 0 36744 174 0 0
sp_fill_shopping_cart 31260 31260 0 0.00 1 16761 45 0 0
sp_fill_shopping_cart_clo_res 448078 448078 0 0.00 0 16067 14 0 0
sp_get_clo_for_invoice 4986 4986 0 0.00 0 30470 309 0 0
sp_kill_cost_storno 16438 16438 0 0.00 0 9303 61 0 0
sp_kill_qstorno_ret_qs2qd 1138323 1138323 0 0.00 0 71323 32 0 0
sp_kill_qty_storno 599639 599639 0 0.00 0 71325 70 0 0
sp_lock_selected_doc 20497 20497 0 0.00 0 8869 6 0 0
sp_make_cost_storno 27729 27729 0 0.00 0 17429 68 0 0
sp_multiply_rows_for_pdistr 27729 27729 0 0.00 0 18069 32 0 0
sp_multiply_rows_for_qdistr 20286 20286 0 0.00 0 13744 157 0 0
sp_pay_from_customer 7009 7009 0 0.00 4 18249 147 0 0
sp_pay_to_supplier 5871 5871 0 0.00 4 17058 165 0 0
sp_payment_common 12880 12880 0 0.00 1 16698 41 0 0
sp_qd_handle_on_cancel_clo 1713 1713 0 0.00 0 10183 345 0 0
sp_qd_handle_on_invoice_upd_sts 10776 10776 0 0.00 0 27757 347 0 0
sp_qd_handle_on_reserve_upd_sts 9864 9864 0 0.00 0 13880 62 0 0
sp_reserve_write_off 9864 9864 0 0.00 6 18323 197 0 0
sp_supplier_invoice 5484 5484 0 0.00 8 84261 3796 0 0
sp_supplier_order 5495 5495 0 0.00 5 35865 1364 0 0
srv_aggregate_perf_data 101 101 0 0.00 2375 164367 15743 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1238 132819 44804 0 0
srv_find_qd_qs_mism 1047648 1047648 0 0.00 0 15895 5 0 0
srv_make_invnt_saldo 594 594 0 0.00 48 86154 1200 0 0
srv_make_money_saldo 810 810 0 0.00 1 1466 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 115415 417240 239805 0 0
t$perf-abend:sp_add_invoice_to_stock 137 137 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4988 4988 0 0.00 0 17814 178 0 0
t$perf-norm:sp_cancel_adding_invoice 428 428 0 0.00 0 11956 131 0 0
t$perf-norm:sp_cancel_client_order 1713 1713 0 0.00 0 9058 17 0 0
t$perf-norm:sp_cancel_customer_reserve 690 690 0 0.00 0 1678 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 660 660 0 0.00 0 1408 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 1200 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 378 378 0 0.00 0 536 13 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 22522 1701 0 0
t$perf-norm:sp_cancel_write_off 984 984 0 0.00 0 4977 16 0 0
t$perf-norm:sp_client_order 20286 20286 0 0.00 0 9989 20 0 0
t$perf-norm:sp_customer_reserve 1019 1019 0 0.00 0 2469 10 0 0
t$perf-norm:sp_pay_from_customer 7009 7009 0 0.00 0 10826 12 0 0
t$perf-norm:sp_pay_to_supplier 5871 5871 0 0.00 0 6934 14 0 0
t$perf-norm:sp_reserve_write_off 9864 9864 0 0.00 0 4907 11 0 0
t$perf-norm:sp_supplier_invoice 5484 5484 0 0.00 0 4129 19 0 0
t$perf-norm:sp_supplier_order 5495 5495 0 0.00 0 8491 20 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 292 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 1920 30 0 0
t$perf-norm:srv_make_invnt_saldo 594 594 0 0.00 0 669 21 0 0
t$perf-norm:srv_make_money_saldo 810 810 0 0.00 0 3389 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 10 4 0 0
v_add_invoice_to_stock 10244 9970 274 2.67 0 5236 19 0 274
v_all_customers 36776 36776 0 0.00 0 21 1 0 0
v_all_suppliers 29978 29978 0 0.00 0 29 1 0 0
v_all_wares 40572 40572 0 0.00 0 31 1 0 0
v_cancel_adding_invoice 856 856 0 0.00 0 1542 10 0 0
v_cancel_client_order 3426 3426 0 0.00 0 3447 7 0 0
v_cancel_customer_prepayment 1320 1320 0 0.00 0 871 6 0 0
v_cancel_customer_reserve 1380 1380 0 0.00 0 5101 16 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 280 3 0 0
v_cancel_supplier_invoice 774 756 18 2.32 0 1061 17 0 18
v_cancel_supplier_order 782 782 0 0.00 0 1612 6 0 0
v_cancel_write_off 1968 1968 0 0.00 0 5951 25 0 0
v_max_id_clo_ord 5495 5495 0 0.00 0 718 2 0 0
v_max_id_clo_res 1019 1019 0 0.00 0 4067 26 0 0
v_max_id_ord_sup 5479 5479 0 0.00 0 4716 5 0 0
v_max_non_paid_invoice 1856 1856 0 0.00 0 939 3 0 0
v_max_non_paid_realizn 3871 3871 0 0.00 0 1486 3 0 0
v_min_id_clo_ord 5495 5495 0 0.00 0 7498 9 0 0
v_min_id_clo_res 1019 1019 0 0.00 0 4636 14 0 0
v_min_id_ord_sup 5479 5479 0 0.00 0 6962 9 0 0
v_min_non_paid_invoice 5871 5871 0 0.00 0 16578 34 0 0
v_min_non_paid_realizn 7009 7009 0 0.00 0 16691 28 0 0
v_our_firm 10072 10072 0 0.00 0 21 1 0 0
v_reserve_write_off 19728 19728 0 0.00 0 4154 6 0 0
x_lock_depdocs_on_canc_invoice 11688 11688 0 0.00 0 14247 124 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 3523 369 0 0
x_make_qstorno_1000_1200 5495 5495 0 0.00 2 35830 1271 0 0
x_make_qstorno_1200_2000 5484 5484 0 0.00 4 84245 3638 0 0
x_make_qstorno_2100_3300 448079 448079 0 0.00 0 36744 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-08-2021 01:38:49 to 04-08-2021 01:38:53.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:07:44 2 8192000000 8800474608 8811675648 6730224 0.082 2 1 2 0 6849600 56816 62560
23:08:45 22086 8192000000 10376320800 10697228288 1427171200 14.836 100 88 175 1 1461802480 5993904 28637376
23:10:09 12793 8192000000 10442878384 10771894272 1488778848 15.378 100 88 175 2 1525436528 8225984 28431696
23:11:32 15657 8192000000 10494914560 10840436736 1556190720 15.963 100 82 164 0 1591579584 10050432 25338432
23:12:56 16057 8192000000 10593270896 10951323648 1648363472 16.751 100 81 163 1 1684465744 12184480 23917792
23:14:14 8401 8192000000 10635956704 11003596800 1698197216 17.170 100 84 167 2 1736742576 14218144 24327216
23:15:25 4742 8192000000 10682243520 11054276608 1749196816 17.595 100 80 159 3 1785041312 13070576 22773920
23:16:31 2116 8192000000 10739817472 11117809664 1796420016 17.985 100 89 178 0 1834763216 13141776 25201424
23:17:36 1607 8192000000 10785733312 11170025472 1842317536 18.360 100 92 183 5 1881393920 13575824 25500560
23:18:38 7629 8192000000 10800235728 11202818048 1868973360 18.576 100 61 122 4 1896823440 11607376 16242704
23:19:48 4880 8192000000 10799051120 11209748480 1874043680 18.617 100 42 82 5 1892423392 6726976 11652736
23:20:55 8537 8192000000 10782558384 11212804096 1878193616 18.651 100 18 36 0 1889182560 6259152 4729792
23:22:06 6059 8192000000 10796900240 11227287552 1887840848 18.728 100 22 43 0 1900199552 5968960 6389744
23:23:13 41812 8192000000 10829528800 11244552192 1897118368 18.803 100 24 46 0 1910647888 5498656 8030864
23:25:17 4052 8192000000 10839277968 11264266240 1912191808 18.924 100 35 68 4 1928323328 5422800 10708720
23:26:25 5332 8192000000 10809711088 11249881088 1904343040 18.861 100 36 72 1 1921919120 7169696 10406384
23:27:31 13664 8192000000 10759253840 11189166080 1789154384 17.925 94 18 36 4 1800094352 6016560 4923408
23:28:45 41232 8192000000 10743354208 11140825088 1811304656 18.107 99 56 112 1 1833019936 4859696 16855584
23:30:26 6993 8192000000 10734364288 11146268672 1809922176 18.095 100 35 70 1 1827212176 7325856 9964144
23:31:34 5766 8192000000 10698473312 11113672704 1784768368 17.889 100 16 32 1 1793854176 4638752 4447056
23:32:40 5727 8192000000 10739485552 11157549056 1824325344 18.213 100 37 75 3 1841084784 5683776 11075664
23:33:49 49587 8192000000 10761362480 11189964800 1849683280 18.420 100 26 52 1 1863795952 6239744 7872928
23:35:41 9422 8192000000 10776766912 11202666496 1861719088 18.517 100 15 30 0 1871291312 5154928 4417296
23:36:56 13698 8192000000 10801609360 11229446144 1877972608 18.649 100 19 37 1 1888851744 5832000 5047136
23:38:14 8700 8192000000 10842997520 11262218240 1914672576 18.944 100 40 78 3 1932586864 7242048 10672240
23:39:36 52794 8192000000 10876667424 11300343808 1932646768 19.088 100 34 68 1 1951631600 9327696 9657136
23:41:40 12297 8192000000 10883327072 11316948992 1950231232 19.228 100 46 92 1 1976650592 13543088 12876272
23:43:05 3401 8192000000 10907133632 11337285632 1945229200 19.188 99 50 98 2 1981441440 22197184 14015056
23:44:12 18609 8192000000 10912424608 11353366528 1976813104 19.439 100 28 56 1 2004334048 19216672 8304272
23:45:35 4368 8192000000 10954972416 11375091712 1991851600 19.558 100 65 130 0 2037636256 26384672 19399984
23:46:42 6513 8192000000 10941178336 11370627072 1982017136 19.481 99 64 129 1 2030216432 28408672 19790624
23:47:53 2091 8192000000 10950086752 11380436992 1994635104 19.580 100 58 115 1 2039739392 27701616 17402672
23:48:59 4260 8192000000 10958646320 11395809280 1999487648 19.619 100 47 93 1 2045502608 31912112 14102848
23:50:10 2793 8192000000 10975954848 11394936832 1997177968 19.600 100 81 160 1 2060868144 39955392 23734784
23:51:20 5017 8192000000 10979360848 11396460544 1975027120 19.425 99 75 151 2 2042627216 44445184 23154912
23:52:28 2212 8192000000 10988967184 11409149952 2000935440 19.630 100 87 171 2 2077219104 50171136 26112528
23:53:34 1233 8192000000 10970018720 11371405312 1970551744 19.390 100 95 190 0 2048994352 50115600 28327008
23:54:40 2363 8192000000 10949825904 11357044736 1951217456 19.236 100 77 153 2 2026897200 51882848 23796896
23:55:51 13642 8192000000 10932656512 11363090432 1918732800 18.977 98 52 103 1 1988662624 53404096 16525728
23:57:08 2559 8192000000 10971803616 11374227456 1958701312 19.296 100 86 168 0 2049787952 63523936 27562704
23:58:16 5211 8192000000 10934813008 11344695296 1922936816 19.010 100 76 152 0 2011242480 64616512 23689152
23:59:26 5358 8192000000 10925831744 11344359424 1932196752 19.084 99 61 118 3 1991074736 40042752 18835232
00:00:38 7066 8192000000 10944125568 11362963456 1947892544 19.210 100 57 114 1 2017275184 50987840 18394800
00:01:49 17947 8192000000 10933127088 11370196992 1951732336 19.240 100 32 64 0 2010467120 47286176 11448608
00:03:13 17077 8192000000 10935809616 11383259136 1966734384 19.360 100 29 57 1 2018295968 41980704 9580880
00:04:33 11251 8192000000 10941944016 11390181376 1972061936 19.402 100 40 80 2 2028930176 43387920 13480320
00:05:47 29837 8192000000 10925918736 11395223552 1962766192 19.328 100 19 38 1 2019230976 49525616 6939168
00:07:18 38746 8192000000 10899343072 11364941824 1954890400 19.265 99 15 30 1 1983209872 23493520 4825952
00:08:58 13041 8192000000 10934283744 11358486528 1965933152 19.353 99 46 92 2 1993063168 14225360 12904656
00:10:12 29447 8192000000 10887855232 11350339584 1959341360 19.301 100 11 22 1 1973920368 11688656 2890352
00:11:42 76217 8192000000 10901988288 11369603072 1970333072 19.388 100 11 22 0 1986469056 13265008 2870976
00:13:59 70193 8192000000 10926742320 11372560384 1976593152 19.438 100 17 34 0 1997658096 15783840 5281104
00:16:11 67772 8192000000 10910959104 11382153216 1933189072 19.092 97 24 48 1 1951657328 11284576 7183680
00:18:22 100715 8192000000 10891460928 11346153472 1922262720 19.005 97 11 22 1 1937481680 12437600 2781360
00:21:06 5066 8192000000 10908756832 11361447936 1977070688 19.441 100 26 53 1 1993673392 9078816 7523888
00:22:16 28917 8192000000 10919902976 11366674432 1968206928 19.371 99 48 96 0 1994294336 12244288 13843120
00:23:46 39808 8192000000 10919378640 11361468416 1973008416 19.409 99 37 72 2 1996542160 12274416 11259328
00:25:36 18480 8192000000 10903351536 11380596736 1981705728 19.478 100 4 8 0 1991436912 8838512 892672
00:27:00 59858 8192000000 10887989216 11354595328 1944137552 19.180 98 7 14 1 1963660544 17535392 1987600
00:29:19 115507 8192000000 10904274144 11380367360 1951498400 19.238 98 29 58 1 1976545232 16054992 8991840
00:32:16 66209 8192000000 10907283200 11380908032 1962510624 19.326 99 11 22 2 1977014128 11765360 2738144
00:34:22 64200 8192000000 10885276864 11349295104 1907083536 18.883 96 14 28 1 1924042128 12166656 4791936
00:36:50 66201 8192000000 10887353296 11347742720 1905272464 18.869 97 15 30 0 1922270352 13065872 3932016
00:38:58 61898 8192000000 10866392720 11330195456 1862769792 18.526 95 6 12 1 1870943888 6911488 1262608
00:41:18 45580 8192000000 10875141808 11339395072 1941731360 19.161 100 29 58 2 1963714144 13307328 8675456
00:43:12 59512 8192000000 10866731824 11327270912 1919773376 18.985 99 14 29 1 1932754304 9507216 3473712
00:45:18 108432 8192000000 10890400944 11374579712 1946812048 19.201 100 17 34 2 1980928192 28729520 5386624
00:48:23 54067 8192000000 10873007760 11352018944 1891356624 18.757 97 9 18 1 1909189488 15779760 2053104
00:50:19 25237 8192000000 10897645024 11353296896 1960826992 19.313 100 39 78 1 1980875392 8998736 11049664
00:51:58 87429 8192000000 10888026544 11342368768 1934265808 19.101 99 28 54 3 1954643648 12520640 7857200
00:54:26 53465 8192000000 10881186256 11358134272 1940984032 19.155 99 17 34 0 1958015552 12528112 4503408
00:56:24 54828 8192000000 10886271344 11366293504 1946928944 19.202 99 12 24 1 1958087792 7873968 3284880
00:58:20 96962 8192000000 10888888992 11369570304 1937881008 19.130 98 14 28 1 1956495888 14037136 4577744
01:01:06 102016 8192000000 10886800064 11369959424 1901655408 18.840 96 10 20 1 1918471552 14260928 2555216
01:03:49 64686 8192000000 10883778064 11345612800 1864529056 18.540 95 18 36 0 1881318848 12007248 4782544
01:05:54 58515 8192000000 10879853456 11354685440 1906791456 18.881 97 18 36 0 1922099728 9866720 5441552
01:07:59 65698 8192000000 10897389648 11369086976 1945064400 19.187 99 19 36 0 1962837136 12673200 5099536
01:10:08 103618 8192000000 10897454400 11370295296 1934940752 19.106 98 14 28 1 1949180736 10333168 3906816
01:13:07 75499 8192000000 10900500208 11360329728 1896456192 18.798 96 9 18 0 1914826944 15708752 2662000
01:15:23 77500 8192000000 10915549120 11394248704 1958305168 19.293 99 22 44 3 1980907968 16276592 6326208
01:17:42 132819 8192000000 10904644640 11385339904 1966034992 19.354 100 9 17 0 1993619616 24865888 2718736
01:20:56 71172 8192000000 10875601280 11359391744 1929279520 19.061 97 21 41 2 1944943104 9754576 5909008
01:23:07 35200 8192000000 10869617712 11348975616 1941900656 19.162 99 10 20 0 1952939024 8473552 2564816
01:24:46 89197 8192000000 10854441520 11323502592 1867597792 18.565 95 12 23 2 1878364864 7450864 3316208
01:27:26 43095 8192000000 10879110624 11352756224 1960140032 19.307 100 26 52 2 1976741472 9145488 7455952
01:29:21 72241 8192000000 10843496928 11314933760 1883927712 18.697 97 12 24 0 1894974128 7669488 3376928
01:31:34 60842 8192000000 10822482224 11303702528 1826264800 18.229 94 29 58 0 1845561888 10973200 8323888
01:33:57 44695 8192000000 10877682976 11327762432 1896606128 18.799 98 30 60 1 1921076544 15916896 8553520
01:35:52 70945 8192000000 10869538480 11362177024 1928435920 19.054 100 11 22 1 1956620416 24846080 3338416

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-08-2021 01:38:55 to 04-08-2021 01:39:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 137 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 04-08-2021 01:39:11 to 04-08-2021 01:39:12.

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          240686
MON$OLDEST_ACTIVE               241529
MON$OLDEST_SNAPSHOT             241529
MON$NEXT_TRANSACTION            241529
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                       2050976
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.33487 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33487 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33487 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33487 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 ---
:113: 
:114: mscs-test-161.budzdorov.ru    Wed Aug  4 01:37:52 2021
:115:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:116: 
:117: 
:118: mscs-test-161.budzdorov.ru    Wed Aug  4 01:37:54 2021
:119:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:120: 
:121: 
:122: mscs-test-161.budzdorov.ru    Wed Aug  4 01:37:54 2021
:123:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:124: 
:125: 
:126: mscs-test-161.budzdorov.ru    Wed Aug  4 01:37:54 2021
:127:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:128: 
:129: 
:130: mscs-test-161.budzdorov.ru    Wed Aug  4 01:37:54 2021
:131:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:132: 
:133: 
:134: mscs-test-161.budzdorov.ru    Wed Aug  4 01:37:54 2021
:135:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:136: 
:137: 
:138: mscs-test-161.budzdorov.ru    Wed Aug  4 01:37:54 2021
:139:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:140: 
:141: 
:142: mscs-test-161.budzdorov.ru    Wed Aug  4 01:37:54 2021
:143:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:144: 
:145: 
:146: mscs-test-161.budzdorov.ru    Wed Aug  4 01:37:54 2021
:147:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:148: 
:149: 
:150: mscs-test-161.budzdorov.ru    Wed Aug  4 01:37:54 2021
:151:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:152: 
--- end of diff output ---

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

04.08.21 01:39:13. 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-04 01:39:16 No severe PSQL-related problems occured
04.08.21 01:39:17. End of report.