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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16265536 kB
MemAvailable 30795296 kB
Buffers 0 kB
Cached 15421864 kB
SwapCached 2468 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-11-04 22:37:57
DTS_END                         2021-11-04 22:37:57.5910
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/34016
TRN_ID                          234646
ATT_ID                          706
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: 9204.12 23697 1104494
customer order: creation 168.73 362 20248
customer order: refuse 14.49 326 1739
order to supplier: creation 44.32 1317 5318
order to supplier: removal 3.32 103305 398
invoice (draft): creation 44.66 3126 5359
invoice (draft): removal 52.20 819 6264
invoice accept: apply 40.73 18409 4887
invoice accept: cancel 47.56 749 5707
customer reserve: creation 3814.78 180 457773
customer reserve: removal 4677.90 57 561348
realization accept: apply 82.76 171 9931
realization accept: cancel 85.96 110 10315
payment from customer: creation 57.49 124 6899
payment from customer: removal 6.11 77 733
payment to supplier: creation 48.40 133 5808
payment to supplier: removal 3.12 125 374
service: total inventory turnovers 4.65 1066 558
service: total monetary turnovers 6.18 25 742
service: aggregate perf. data 0.74 21499 89
service: refresh index statistics 0.03 321960 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-11-2021 22:39:01 to 04-11-2021 22:39:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2557
TEST_TIME 2 2387
TEST_TIME 3 3829
TEST_TIME 4 4086
TEST_TIME 5 4299
TEST_TIME 6 4186
TEST_TIME 7 4074
TEST_TIME 8 4264
TEST_TIME 9 4623
TEST_TIME 10 4579
TEST_TIME 11 5106
TEST_TIME 12 5408
TEST_TIME 13 5620
TEST_TIME 14 6268
TEST_TIME 15 6300
TEST_TIME 16 6373
TEST_TIME 17 6658
TEST_TIME 18 7208
TEST_TIME 19 7168
TEST_TIME 20 7861
TEST_TIME 21 8032
TEST_TIME 22 8861
TEST_TIME 23 9150
TEST_TIME 24 9286
TEST_TIME 25 9980
TEST_TIME 26 10642
TEST_TIME 27 10528
TEST_TIME 28 10669
TEST_TIME 29 10326
TEST_TIME 30 10301
TEST_TIME 31 10121
TEST_TIME 32 9987
TEST_TIME 33 10165
TEST_TIME 34 10088
TEST_TIME 35 9972
TEST_TIME 36 9784
TEST_TIME 37 9744
TEST_TIME 38 9730
TEST_TIME 39 9551
TEST_TIME 40 9652
TEST_TIME 41 9522
TEST_TIME 42 9471
TEST_TIME 43 9376
TEST_TIME 44 9337
TEST_TIME 45 9312
TEST_TIME 46 9326
TEST_TIME 47 9254
TEST_TIME 48 9265
TEST_TIME 49 9205
TEST_TIME 50 9244
TEST_TIME 51 9192
TEST_TIME 52 9353
TEST_TIME 53 9298
TEST_TIME 54 9389
TEST_TIME 55 9348
TEST_TIME 56 9320
TEST_TIME 57 9440
TEST_TIME 58 9476
TEST_TIME 59 9423
TEST_TIME 60 9500
TEST_TIME 61 9623
TEST_TIME 62 9532
TEST_TIME 63 9408
TEST_TIME 64 9372
TEST_TIME 65 9351
TEST_TIME 66 9314
TEST_TIME 67 9245
TEST_TIME 68 9197
TEST_TIME 69 9190
TEST_TIME 70 9108
TEST_TIME 71 9115
TEST_TIME 72 9115
TEST_TIME 73 9095
TEST_TIME 74 9075
TEST_TIME 75 9130
TEST_TIME 76 9126
TEST_TIME 77 9134
TEST_TIME 78 9220
TEST_TIME 79 9187
TEST_TIME 80 9233
TEST_TIME 81 9263
TEST_TIME 82 9256
TEST_TIME 83 9289
TEST_TIME 84 9350
TEST_TIME 85 9313
TEST_TIME 86 9225
TEST_TIME 87 9250
TEST_TIME 88 9213
TEST_TIME 89 9181
TEST_TIME 90 9168
TEST_TIME 91 9143
TEST_TIME 92 9138
TEST_TIME 93 9175
TEST_TIME 94 9171
TEST_TIME 95 9164
TEST_TIME 96 9179
TEST_TIME 97 9173
TEST_TIME 98 9254
TEST_TIME 99 9285
TEST_TIME 100 9268
TEST_TIME 101 9288
TEST_TIME 102 9331
TEST_TIME 103 9322
TEST_TIME 104 9321
TEST_TIME 105 9347
TEST_TIME 106 9364
TEST_TIME 107 9303
TEST_TIME 108 9270
TEST_TIME 109 9231
TEST_TIME 110 9200
TEST_TIME 111 9160
TEST_TIME 112 9140
TEST_TIME 113 9156
TEST_TIME 114 9202
TEST_TIME 115 9229
TEST_TIME 116 9214
TEST_TIME 117 9212
TEST_TIME 118 9209
TEST_TIME 119 9212
TEST_TIME 120 9209
TEST_TIME 121 9133

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-11-2021 22:39:05 to 04-11-2021 22:39:08.

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 1528069 1528069 0 0.00 0 34663 6 0 0
doc_list_biud 1528069 1528069 0 0.00 0 32238 27 0 0
sp_add_invoice_to_stock 5007 4887 120 2.39 51 174703 18409 0 120
sp_cancel_adding_invoice 5707 5707 0 0.00 3 85322 749 0 0
sp_cancel_client_order 1739 1739 0 0.00 4 9382 326 0 0
sp_cancel_customer_reserve 561348 561348 0 0.00 1 32241 57 0 0
sp_cancel_pay_from_customer 733 733 0 0.00 2 4051 77 0 0
sp_cancel_pay_to_supplier 374 374 0 0.00 3 6323 126 0 0
sp_cancel_supplier_invoice 6278 6264 14 0.22 2 15291 819 0 14
sp_cancel_supplier_order 398 398 0 0.00 5 1219884 103306 0 0
sp_cancel_write_off 10315 10315 0 0.00 1 19616 111 0 0
sp_client_order 20248 20248 0 0.00 3 32727 363 0 0
sp_customer_reserve 457773 457773 0 0.00 0 74046 180 0 0
sp_fill_shopping_cart 30917 30917 0 0.00 1 10429 36 0 0
sp_fill_shopping_cart_clo_res 457756 457756 0 0.00 0 18426 15 0 0
sp_get_clo_for_invoice 4869 4869 0 0.00 0 18098 323 0 0
sp_kill_cost_storno 17129 17129 0 0.00 0 8167 53 0 0
sp_kill_qstorno_ret_qs2qd 1139673 1139673 0 0.00 0 32227 31 0 0
sp_kill_qty_storno 600571 600571 0 0.00 0 32237 67 0 0
sp_lock_selected_doc 20492 20492 0 0.00 0 6756 6 0 0
sp_make_cost_storno 27507 27507 0 0.00 0 34524 57 0 0
sp_multiply_rows_for_pdistr 27507 27507 0 0.00 0 11953 30 0 0
sp_multiply_rows_for_qdistr 20248 20248 0 0.00 0 17140 163 0 0
sp_pay_from_customer 6899 6899 0 0.00 4 51888 125 0 0
sp_pay_to_supplier 5808 5808 0 0.00 4 17379 134 0 0
sp_payment_common 12707 12707 0 0.00 1 51850 36 0 0
sp_qd_handle_on_cancel_clo 1739 1739 0 0.00 0 9019 291 0 0
sp_qd_handle_on_invoice_upd_sts 10576 10576 0 0.00 0 31935 319 0 0
sp_qd_handle_on_reserve_upd_sts 9931 9931 0 0.00 0 17213 46 0 0
sp_reserve_write_off 9931 9931 0 0.00 6 19991 171 0 0
sp_supplier_invoice 5359 5359 0 0.00 7 68348 3126 0 0
sp_supplier_order 5318 5318 0 0.00 4 33044 1318 0 0
srv_aggregate_perf_data 89 89 0 0.00 2271 169222 21499 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2364 140800 51841 0 0
srv_find_qd_qs_mism 1057425 1057425 0 0.00 0 32019 5 0 0
srv_make_invnt_saldo 558 558 0 0.00 50 29323 1067 0 0
srv_make_money_saldo 742 742 0 0.00 1 2492 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 257921 454125 321960 0 0
t$perf-abend:sp_add_invoice_to_stock 120 120 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4887 4887 0 0.00 0 32473 168 0 0
t$perf-norm:sp_cancel_adding_invoice 388 388 0 0.00 1 3111 77 0 0
t$perf-norm:sp_cancel_client_order 1739 1739 0 0.00 0 2383 12 0 0
t$perf-norm:sp_cancel_customer_reserve 692 692 0 0.00 0 12215 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 733 733 0 0.00 0 662 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 374 374 0 0.00 0 1038 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 664 13 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 28261 1446 0 0
t$perf-norm:sp_cancel_write_off 966 966 0 0.00 0 2015 8 0 0
t$perf-norm:sp_client_order 20248 20248 0 0.00 0 6712 15 0 0
t$perf-norm:sp_customer_reserve 1000 1000 0 0.00 0 4441 8 0 0
t$perf-norm:sp_pay_from_customer 6899 6899 0 0.00 0 9881 11 0 0
t$perf-norm:sp_pay_to_supplier 5808 5808 0 0.00 0 8926 8 0 0
t$perf-norm:sp_reserve_write_off 9931 9931 0 0.00 0 22332 15 0 0
t$perf-norm:sp_supplier_invoice 5359 5359 0 0.00 0 20538 21 0 0
t$perf-norm:sp_supplier_order 5318 5318 0 0.00 0 9762 18 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 292 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 3 0 0 0
t$perf-norm:srv_make_invnt_saldo 558 558 0 0.00 0 4338 24 0 0
t$perf-norm:srv_make_money_saldo 742 742 0 0.00 0 2797 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 15 5 0 0
v_add_invoice_to_stock 9978 9738 240 2.40 0 15202 23 0 240
v_all_customers 36648 36648 0 0.00 0 17 1 0 0
v_all_suppliers 30128 30128 0 0.00 0 16 1 0 0
v_all_wares 40494 40494 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 776 776 0 0.00 0 15276 34 0 0
v_cancel_client_order 3478 3478 0 0.00 0 4724 6 0 0
v_cancel_customer_prepayment 1466 1466 0 0.00 0 1714 4 0 0
v_cancel_customer_reserve 1384 1384 0 0.00 0 1523 4 0 0
v_cancel_payment_to_supplier 748 748 0 0.00 0 6314 25 0 0
v_cancel_supplier_invoice 832 804 28 3.36 0 2608 19 0 28
v_cancel_supplier_order 796 796 0 0.00 0 3757 10 0 0
v_cancel_write_off 1932 1932 0 0.00 0 8879 22 0 0
v_max_id_clo_ord 5316 5316 0 0.00 0 215 1 0 0
v_max_id_clo_res 1000 1000 0 0.00 0 2639 14 0 0
v_max_id_ord_sup 5354 5354 0 0.00 0 5722 6 0 0
v_max_non_paid_invoice 1414 1414 0 0.00 0 1903 5 0 0
v_max_non_paid_realizn 3799 3799 0 0.00 0 4315 8 0 0
v_min_id_clo_ord 5316 5316 0 0.00 0 8222 9 0 0
v_min_id_clo_res 1000 1000 0 0.00 0 5693 26 0 0
v_min_id_ord_sup 5354 5354 0 0.00 0 6976 8 0 0
v_min_non_paid_invoice 5808 5808 0 0.00 0 12346 22 0 0
v_min_non_paid_realizn 6899 6899 0 0.00 0 51847 30 0 0
v_our_firm 10046 10046 0 0.00 0 11 1 0 0
v_reserve_write_off 19862 19862 0 0.00 0 17326 8 0 0
x_lock_depdocs_on_canc_invoice 11569 11569 0 0.00 0 19465 119 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 3595 304 0 0
x_make_qstorno_1000_1200 5318 5318 0 0.00 1 33007 1243 0 0
x_make_qstorno_1200_2000 5359 5359 0 0.00 3 68330 2998 0 0
x_make_qstorno_2100_3300 457773 457773 0 0.00 0 74043 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-11-2021 22:39:09 to 04-11-2021 22:39:13.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:01 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:01 28675 8192000000 9896365888 10206990336 978339584 10.668 100 88 176 1 1007228208 5901456 22987168
20:10:36 26025 8192000000 9956879360 10283945984 1039510256 11.260 100 85 170 0 1069810368 8392944 21907168
20:12:06 18643 8192000000 9999743472 10337079296 1083183088 11.678 100 87 174 1 1115234240 10410176 21640976
20:13:29 13525 8192000000 10048452192 10401128448 1135514816 12.173 100 82 164 1 1166073728 11213104 19345808
20:14:47 9439 8192000000 10098759264 10461822976 1178916688 12.580 100 79 158 0 1210571824 13573264 18081872
20:16:02 6890 8192000000 10126913344 10502139904 1219543296 12.957 100 74 147 1 1248738368 12622960 16572112
20:17:11 6178 8192000000 10164224208 10543415296 1250599200 13.244 100 71 142 0 1279519248 13030992 15889056
20:18:19 2633 8192000000 10203499152 10585108480 1286010480 13.568 100 89 178 2 1319893520 13784096 20098944
20:19:24 2121 8192000000 10214354896 10598027264 1299576000 13.691 100 93 188 0 1335315600 14279376 21460224
20:20:27 2827 8192000000 10209883520 10607697920 1304713072 13.738 100 56 114 4 1326494992 8896336 12885584
20:21:31 5657 8192000000 10201193248 10614059008 1313610208 13.819 100 27 54 3 1327524336 7615424 6298704
20:22:40 37205 8192000000 10231313136 10640781312 1324555952 13.918 100 41 82 0 1340884480 6721360 9607168
20:24:24 5632 8192000000 10224806368 10643525632 1335549280 14.017 100 27 54 0 1347833728 5798192 6486256
20:25:33 9312 8192000000 10223030240 10646532096 1336859184 14.029 100 18 36 1 1347261808 6241840 4160784
20:26:43 62265 8192000000 10222233472 10644201472 1312760432 13.811 98 38 76 2 1327894144 6131600 9002112
20:28:47 3352 8192000000 10220503344 10633043968 1323201344 13.906 100 33 67 0 1338176640 7664752 7310544
20:29:51 6524 8192000000 10177780960 10590187520 1278663328 13.501 99 21 42 1 1289021120 5526304 4831488
20:30:58 65759 8192000000 10154546368 10562306048 1246914816 13.210 98 22 44 1 1258036096 6182208 4939072
20:33:05 7310 8192000000 10152158288 10558058496 1254652512 13.281 99 33 66 2 1268532208 5672416 8207280
20:34:13 6438 8192000000 10161697696 10571583488 1272640656 13.446 100 26 52 1 1285895408 6506720 6748032
20:35:22 10590 8192000000 10172298816 10587099136 1286853712 13.576 100 12 24 0 1295553312 5241408 3458192
20:36:36 30267 8192000000 10175075584 10596016128 1290750016 13.611 100 20 40 1 1300630400 5216320 4664064
20:38:10 46390 8192000000 10218945760 10626334720 1311656192 13.801 100 28 56 1 1323908160 5982064 6269904
20:40:01 28595 8192000000 10260897968 10674274304 1341239216 14.069 100 48 94 2 1370439056 18694064 10505776
20:41:39 39778 8192000000 10285675616 10700275712 1363064656 14.265 100 51 102 1 1393423632 19150784 11208192
20:43:42 25595 8192000000 10303638512 10722766848 1381517024 14.430 100 44 88 1 1415291456 23105728 10668704
20:45:11 2515 8192000000 10325275792 10735673344 1385094944 14.462 100 67 134 1 1427735616 27557216 15083456
20:46:20 2894 8192000000 10331563984 10746183680 1396061184 14.560 100 74 146 1 1442531104 28664768 17805152
20:47:28 6595 8192000000 10349462928 10765987840 1409884592 14.683 100 67 134 2 1458399840 31343024 17172224
20:48:40 17239 8192000000 10346618480 10765455360 1395605104 14.556 99 58 114 1 1443776736 33055936 15115696
20:50:00 2364 8192000000 10346864624 10759786496 1396413952 14.563 100 74 148 1 1455473888 39113392 19946544
20:51:10 2871 8192000000 10358803872 10773549056 1402646480 14.619 100 83 163 1 1467574688 44250432 20677776
20:52:20 6184 8192000000 10350104128 10767790080 1397558544 14.573 99 66 133 3 1454165296 39053616 17553136
20:53:31 10598 8192000000 10357511984 10774425600 1400167920 14.596 100 71 142 3 1463854352 45548464 18137968
20:54:54 2878 8192000000 10367356768 10778357760 1399679136 14.592 100 88 177 0 1473486976 51162624 22645216
20:56:00 5824 8192000000 10327707920 10749108224 1382375360 14.438 100 46 91 2 1437877568 43380688 12121520
20:57:12 8677 8192000000 10332386800 10766385152 1384157344 14.454 100 43 86 2 1445365008 49104640 12103024
20:58:25 2853 8192000000 10317986000 10736984064 1363614960 14.270 100 48 96 1 1421376016 45137904 12623152
20:59:31 26794 8192000000 10283578928 10724937728 1329299872 13.961 98 23 46 0 1380182736 43981760 6901104
21:01:00 20654 8192000000 10299562480 10737762304 1349957568 14.147 99 23 44 1 1396649424 40408544 6283312
21:02:33 28324 8192000000 10284924080 10733502464 1345105680 14.103 99 17 34 1 1382108784 32064928 4938176
21:04:04 73563 8192000000 10274713456 10730397696 1321294496 13.888 96 9 18 0 1343080560 19298368 2487696
21:06:19 118396 8192000000 10226821312 10660413440 1295131440 13.651 96 26 51 1 1312574720 11410368 6032912
21:09:18 52780 8192000000 10271400496 10717827072 1367087488 14.301 100 26 52 1 1384331152 11490640 5753024
21:11:12 42153 8192000000 10263419776 10721419264 1359649888 14.234 99 12 23 0 1378907936 16584896 2673152
21:12:59 140799 8192000000 10275352928 10731110400 1338723136 14.046 97 13 26 0 1355951872 14434784 2793952
21:16:21 72932 8192000000 10273370352 10742226944 1366361776 14.294 99 15 30 0 1383691248 13871184 3458288
21:18:37 81418 8192000000 10286347040 10748559360 1388366384 14.491 100 23 45 0 1405293088 11805744 5120960
21:20:59 58092 8192000000 10281218400 10739277824 1359269664 14.231 98 29 58 1 1378331456 12676976 6384816
21:23:23 68468 8192000000 10278846784 10731114496 1358919184 14.228 99 9 18 0 1376286544 15362624 2004736
21:26:16 106874 8192000000 10285287120 10756579328 1343225936 14.086 97 9 18 0 1365174352 19787232 2161184
21:29:15 55535 8192000000 10290793264 10738618368 1367497168 14.305 99 22 44 1 1385180832 12910896 4772768
21:31:15 72246 8192000000 10287135184 10740662272 1380405920 14.420 100 13 26 0 1392484816 9327680 2751216
21:33:32 56110 8192000000 10304967920 10763784192 1381286848 14.428 99 30 60 1 1404087216 16067632 6732736
21:35:45 93735 8192000000 10308770864 10759393280 1349766784 14.145 96 18 36 1 1370676896 16858880 4051232
21:38:39 86518 8192000000 10289541840 10739372032 1353060496 14.175 97 31 62 0 1368471536 8665264 6745776
21:41:46 29195 8192000000 10272368576 10723532800 1357030608 14.211 98 29 58 0 1372487104 9378992 6077504
21:43:16 76203 8192000000 10257010880 10713350144 1343501744 14.089 98 18 36 1 1355013728 7571840 3940144
21:45:47 57750 8192000000 10241547136 10699358208 1318417888 13.862 97 15 29 1 1332428720 10399344 3611488
21:48:02 70742 8192000000 10248723104 10697580544 1325259280 13.924 97 15 30 0 1338952336 10326624 3366432
21:50:13 65751 8192000000 10269703408 10717503488 1337610608 14.036 97 15 30 0 1355676944 15285232 2781104
21:52:23 87392 8192000000 10259169696 10707746816 1293082176 13.632 95 14 28 2 1316332272 19707232 3542864
21:54:55 110684 8192000000 10262100240 10701860864 1292633040 13.628 95 19 38 2 1315065888 17757312 4675536
21:57:46 102783 8192000000 10254673168 10700296192 1299166640 13.688 96 18 36 0 1313150336 9968384 4015312
22:00:29 72628 8192000000 10280659952 10746654720 1366708992 14.298 100 32 64 2 1392093344 18817696 6566656
22:02:42 92441 8192000000 10284906416 10724921344 1362472048 14.260 99 32 63 2 1379142320 9693632 6976640
22:05:17 25429 8192000000 10285300272 10729058304 1359951200 14.237 99 33 66 0 1381309232 13480960 7877072
22:06:47 69574 8192000000 10272120640 10729107456 1350203856 14.149 98 11 22 1 1370658752 18020496 2434400
22:08:56 57961 8192000000 10272294416 10736586752 1349757024 14.145 98 9 18 0 1369833968 18079248 1997696
22:10:54 51410 8192000000 10265828160 10715074560 1335226560 14.014 97 12 24 1 1347061504 9409104 2425840
22:12:49 52387 8192000000 10281308672 10739929088 1360862528 14.245 98 26 52 1 1378417264 11163840 6390896
22:14:42 53436 8192000000 10290965168 10743181312 1357865760 14.218 98 35 71 1 1381430976 15465872 8099344
22:16:50 63452 8192000000 10289758960 10727968768 1334612624 14.009 97 32 64 0 1351303232 9810880 6879728
22:18:53 55401 8192000000 10285743824 10740879360 1367647072 14.306 99 27 54 0 1386909152 13382944 5879136
22:20:49 47193 8192000000 10305979888 10750910464 1374324096 14.366 99 25 50 2 1392974880 13174352 5476432
22:22:37 83698 8192000000 10275456336 10719584256 1303047984 13.723 94 17 34 0 1320186816 13672240 3466592
22:25:01 71148 8192000000 10270969760 10714030080 1320695280 13.883 96 31 62 0 1335114720 7432064 6987376
22:27:23 57002 8192000000 10281203120 10732249088 1360331776 14.240 99 13 26 0 1376741984 13025376 3384832
22:29:20 42278 8192000000 10278552352 10738204672 1371804352 14.343 100 11 22 1 1394187616 19571616 2811648
22:31:03 72063 8192000000 10268446688 10728386560 1364043680 14.274 99 10 20 1 1382570976 15554656 2972640
22:33:55 30345 8192000000 10263254912 10705367040 1332635552 13.991 98 39 78 0 1351244144 9472768 9135824
22:35:28 67748 8192000000 10270487024 10712633344 1311603968 13.801 96 17 34 0 1324764064 9496000 3664096
22:37:38 28834 8192000000 9224222912 9498517504 368952992 4.309 27 2 4 0 375056432 5693776 409664

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-11-2021 22:39:15 to 04-11-2021 22:39:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 120 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-11-2021 22:39:31 to 04-11-2021 22:39:33.

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          232432
MON$OLDEST_ACTIVE               234930
MON$OLDEST_SNAPSHOT             234930
MON$NEXT_TRANSACTION            234930
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                       2002752
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.33528 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33528 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33528 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33528 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 ---
:36: 
:37: mscs-test-161.budzdorov.ru    Thu Nov  4 22:38:08 2021
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:39: 
:40: 
:41: mscs-test-161.budzdorov.ru    Thu Nov  4 22:38:11 2021
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:43: 
--- end of diff output ---

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

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