Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.10.2021 22:40
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 16174132 kB
MemAvailable 30864628 kB
Buffers 0 kB
Cached 15553564 kB
SwapCached 4364 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-10-31 22:37:55
DTS_END                         2021-10-31 22:37:55.4860
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/55678
TRN_ID                          233199
ATT_ID                          621
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: 9161.00 21191 1099320
customer order: creation 166.17 358 19940
customer order: refuse 14.76 339 1771
order to supplier: creation 43.55 1297 5226
order to supplier: removal 3.42 98704 410
invoice (draft): creation 45.09 3470 5411
invoice (draft): removal 53.98 743 6478
invoice accept: apply 40.02 17998 4802
invoice accept: cancel 48.62 709 5834
customer reserve: creation 3663.02 179 439562
customer reserve: removal 4795.77 54 575493
realization accept: apply 80.91 166 9709
realization accept: cancel 78.40 111 9408
payment from customer: creation 58.31 127 6997
payment from customer: removal 5.58 67 670
payment to supplier: creation 48.49 141 5819
payment to supplier: removal 3.24 79 389
service: total inventory turnovers 4.47 1065 536
service: total monetary turnovers 6.31 30 757
service: aggregate perf. data 0.87 20310 104
service: refresh index statistics 0.03 277863 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 31-10-2021 22:40:28 to 31-10-2021 22:40:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4572
TEST_TIME 2 3579
TEST_TIME 3 4288
TEST_TIME 4 4884
TEST_TIME 5 4939
TEST_TIME 6 5422
TEST_TIME 7 5406
TEST_TIME 8 5459
TEST_TIME 9 5457
TEST_TIME 10 5444
TEST_TIME 11 5482
TEST_TIME 12 5667
TEST_TIME 13 5828
TEST_TIME 14 5952
TEST_TIME 15 6042
TEST_TIME 16 6507
TEST_TIME 17 6919
TEST_TIME 18 7614
TEST_TIME 19 8036
TEST_TIME 20 8060
TEST_TIME 21 8416
TEST_TIME 22 8704
TEST_TIME 23 9180
TEST_TIME 24 9142
TEST_TIME 25 9659
TEST_TIME 26 9684
TEST_TIME 27 9836
TEST_TIME 28 10349
TEST_TIME 29 10262
TEST_TIME 30 10191
TEST_TIME 31 10374
TEST_TIME 32 10266
TEST_TIME 33 10060
TEST_TIME 34 9919
TEST_TIME 35 9807
TEST_TIME 36 9688
TEST_TIME 37 9633
TEST_TIME 38 9689
TEST_TIME 39 9621
TEST_TIME 40 9486
TEST_TIME 41 9403
TEST_TIME 42 9269
TEST_TIME 43 9347
TEST_TIME 44 9381
TEST_TIME 45 9281
TEST_TIME 46 9209
TEST_TIME 47 9245
TEST_TIME 48 9194
TEST_TIME 49 9208
TEST_TIME 50 9106
TEST_TIME 51 9163
TEST_TIME 52 9187
TEST_TIME 53 9113
TEST_TIME 54 9110
TEST_TIME 55 9044
TEST_TIME 56 9129
TEST_TIME 57 9200
TEST_TIME 58 9222
TEST_TIME 59 9160
TEST_TIME 60 9270
TEST_TIME 61 9266
TEST_TIME 62 9234
TEST_TIME 63 9286
TEST_TIME 64 9350
TEST_TIME 65 9360
TEST_TIME 66 9309
TEST_TIME 67 9317
TEST_TIME 68 9391
TEST_TIME 69 9411
TEST_TIME 70 9301
TEST_TIME 71 9372
TEST_TIME 72 9270
TEST_TIME 73 9309
TEST_TIME 74 9248
TEST_TIME 75 9215
TEST_TIME 76 9172
TEST_TIME 77 9148
TEST_TIME 78 9145
TEST_TIME 79 9076
TEST_TIME 80 9080
TEST_TIME 81 9093
TEST_TIME 82 9150
TEST_TIME 83 9185
TEST_TIME 84 9130
TEST_TIME 85 9088
TEST_TIME 86 9136
TEST_TIME 87 9150
TEST_TIME 88 9276
TEST_TIME 89 9315
TEST_TIME 90 9312
TEST_TIME 91 9333
TEST_TIME 92 9404
TEST_TIME 93 9416
TEST_TIME 94 9375
TEST_TIME 95 9308
TEST_TIME 96 9255
TEST_TIME 97 9238
TEST_TIME 98 9185
TEST_TIME 99 9125
TEST_TIME 100 9171
TEST_TIME 101 9102
TEST_TIME 102 9064
TEST_TIME 103 9130
TEST_TIME 104 9112
TEST_TIME 105 9096
TEST_TIME 106 9080
TEST_TIME 107 9051
TEST_TIME 108 9209
TEST_TIME 109 9252
TEST_TIME 110 9256
TEST_TIME 111 9262
TEST_TIME 112 9238
TEST_TIME 113 9257
TEST_TIME 114 9249
TEST_TIME 115 9322
TEST_TIME 116 9286
TEST_TIME 117 9237
TEST_TIME 118 9181
TEST_TIME 119 9162
TEST_TIME 120 9166

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-10-2021 22:40:32 to 31-10-2021 22:40:35.

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 1508016 1508016 0 0.00 0 18660 6 0 0
doc_list_biud 1508016 1508016 0 0.00 0 69055 26 0 0
sp_add_invoice_to_stock 4857 4802 55 1.13 47 171476 17998 0 55
sp_cancel_adding_invoice 5834 5834 0 0.00 5 54222 709 0 0
sp_cancel_client_order 1771 1771 0 0.00 4 11636 339 0 0
sp_cancel_customer_reserve 575493 575493 0 0.00 1 69057 55 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 2 3143 67 0 0
sp_cancel_pay_to_supplier 389 389 0 0.00 3 4001 79 0 0
sp_cancel_supplier_invoice 6486 6478 8 0.12 2 21634 743 0 8
sp_cancel_supplier_order 410 410 0 0.00 10 992833 98705 0 0
sp_cancel_write_off 9408 9408 0 0.00 1 9444 111 0 0
sp_client_order 19940 19940 0 0.00 3 67801 358 0 0
sp_customer_reserve 439562 439562 0 0.00 0 52162 179 0 0
sp_fill_shopping_cart 30575 30575 0 0.00 1 25625 43 0 0
sp_fill_shopping_cart_clo_res 439550 439550 0 0.00 0 11651 13 0 0
sp_get_clo_for_invoice 4790 4790 0 0.00 0 31153 329 0 0
sp_kill_cost_storno 16301 16301 0 0.00 0 18646 55 0 0
sp_kill_qstorno_ret_qs2qd 1167282 1167282 0 0.00 0 69046 30 0 0
sp_kill_qty_storno 613893 613893 0 0.00 0 69054 64 0 0
sp_lock_selected_doc 20153 20153 0 0.00 0 7105 5 0 0
sp_make_cost_storno 27315 27315 0 0.00 0 12664 52 0 0
sp_multiply_rows_for_pdistr 27315 27315 0 0.00 0 8094 31 0 0
sp_multiply_rows_for_qdistr 19940 19940 0 0.00 0 67791 169 0 0
sp_pay_from_customer 6997 6997 0 0.00 4 19494 127 0 0
sp_pay_to_supplier 5819 5819 0 0.00 4 19588 141 0 0
sp_payment_common 12816 12816 0 0.00 2 7478 27 0 0
sp_qd_handle_on_cancel_clo 1771 1771 0 0.00 0 11627 322 0 0
sp_qd_handle_on_invoice_upd_sts 10624 10624 0 0.00 0 16827 329 0 0
sp_qd_handle_on_reserve_upd_sts 9709 9709 0 0.00 0 8252 44 0 0
sp_reserve_write_off 9709 9709 0 0.00 6 14148 167 0 0
sp_supplier_invoice 5411 5411 0 0.00 6 80817 3471 0 0
sp_supplier_order 5226 5226 0 0.00 4 41221 1298 0 0
srv_aggregate_perf_data 104 104 0 0.00 2861 203808 20310 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2607 145981 49384 0 0
srv_find_qd_qs_mism 1054019 1054019 0 0.00 0 12364 5 0 0
srv_make_invnt_saldo 536 536 0 0.00 53 30416 1066 0 0
srv_make_money_saldo 757 757 0 0.00 1 4631 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 105445 534587 277864 0 0
t$perf-abend:sp_add_invoice_to_stock 55 55 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4802 4802 0 0.00 0 14900 205 0 0
t$perf-norm:sp_cancel_adding_invoice 372 372 0 0.00 0 5498 144 0 0
t$perf-norm:sp_cancel_client_order 1771 1771 0 0.00 0 9022 17 0 0
t$perf-norm:sp_cancel_customer_reserve 657 657 0 0.00 0 6620 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 4500 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 389 389 0 0.00 0 442 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 834 16 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 19833 1665 0 0
t$perf-norm:sp_cancel_write_off 988 988 0 0.00 0 7328 44 0 0
t$perf-norm:sp_client_order 19940 19940 0 0.00 0 12382 21 0 0
t$perf-norm:sp_customer_reserve 1013 1013 0 0.00 0 6561 16 0 0
t$perf-norm:sp_pay_from_customer 6997 6997 0 0.00 0 6966 7 0 0
t$perf-norm:sp_pay_to_supplier 5819 5819 0 0.00 0 3854 12 0 0
t$perf-norm:sp_reserve_write_off 9709 9709 0 0.00 0 19326 14 0 0
t$perf-norm:sp_supplier_invoice 5411 5411 0 0.00 0 5578 20 0 0
t$perf-norm:sp_supplier_order 5226 5226 0 0.00 0 10536 23 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 88 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 223 9 0 0
t$perf-norm:srv_make_invnt_saldo 536 536 0 0.00 0 9282 38 0 0
t$perf-norm:srv_make_money_saldo 757 757 0 0.00 0 1633 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 469 119 0 0
v_add_invoice_to_stock 9690 9580 110 1.13 0 4368 20 0 110
v_all_customers 36524 36524 0 0.00 0 18 1 0 0
v_all_suppliers 29996 29996 0 0.00 0 169 1 0 0
v_all_wares 39880 39880 0 0.00 0 89 1 0 0
v_cancel_adding_invoice 744 744 0 0.00 0 468 6 0 0
v_cancel_client_order 3542 3542 0 0.00 0 2286 4 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 3120 5 0 0
v_cancel_customer_reserve 1314 1314 0 0.00 0 2033 7 0 0
v_cancel_payment_to_supplier 778 778 0 0.00 0 564 4 0 0
v_cancel_supplier_invoice 810 794 16 1.97 0 630 13 0 16
v_cancel_supplier_order 820 820 0 0.00 0 3094 9 0 0
v_cancel_write_off 1976 1976 0 0.00 0 2217 13 0 0
v_max_id_clo_ord 5226 5226 0 0.00 0 514 2 0 0
v_max_id_clo_res 1013 1013 0 0.00 0 2323 10 0 0
v_max_id_ord_sup 5409 5409 0 0.00 0 5660 4 0 0
v_max_non_paid_invoice 1456 1456 0 0.00 0 1110 4 0 0
v_max_non_paid_realizn 3672 3672 0 0.00 0 4964 5 0 0
v_min_id_clo_ord 5226 5226 0 0.00 0 4022 9 0 0
v_min_id_clo_res 1013 1013 0 0.00 0 2912 8 0 0
v_min_id_ord_sup 5409 5409 0 0.00 0 6031 12 0 0
v_min_non_paid_invoice 5819 5819 0 0.00 0 7473 21 0 0
v_min_non_paid_realizn 6997 6997 0 0.00 0 5334 18 0 0
v_our_firm 10006 10006 0 0.00 0 14 1 0 0
v_reserve_write_off 19418 19418 0 0.00 0 3870 7 0 0
x_lock_depdocs_on_canc_invoice 11915 11915 0 0.00 0 15934 111 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 13580 418 0 0
x_make_qstorno_1000_1200 5226 5226 0 0.00 2 41207 1209 0 0
x_make_qstorno_1200_2000 5411 5411 0 0.00 3 75580 3315 0 0
x_make_qstorno_2100_3300 439562 439562 0 0.00 0 52142 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-10-2021 22:40:36 to 31-10-2021 22:40:40.

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:59 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:00 6363 8192000000 9875169616 10181410816 964417664 10.532 100 87 174 0 992840160 5442224 22980272
20:10:14 20648 8192000000 9943427328 10260582400 1018976880 11.062 100 90 180 0 1050309952 7889392 23443680
20:11:38 19872 8192000000 9959625984 10285604864 1042552736 11.289 100 88 176 0 1074770608 9648048 22569824
20:13:20 12319 8192000000 10016375552 10362470400 1105252688 11.887 100 73 146 0 1133462704 10545648 17664368
20:14:40 11921 8192000000 10077548512 10438701056 1163103744 12.432 100 66 133 0 1189952048 11455824 15392480
20:15:55 6264 8192000000 10126344304 10495639552 1212915744 12.896 100 80 160 1 1243483680 12189264 18378672
20:17:07 4158 8192000000 10156784192 10532995072 1246159232 13.203 100 77 154 0 1276358624 13295840 16903552
20:18:13 2627 8192000000 10193650448 10574864384 1278178672 13.496 100 87 173 1 1310843456 13151472 19513312
20:19:18 1516 8192000000 10217163280 10601537536 1299323968 13.689 100 87 176 0 1332213632 12733888 20155776
20:20:20 3857 8192000000 10194155056 10600116224 1304498304 13.736 100 42 84 3 1322194944 8141680 9554960
20:21:24 52974 8192000000 10205985968 10617364480 1312790784 13.811 100 37 74 1 1328229456 6513776 8924896
20:23:18 2657 8192000000 10219372880 10626535424 1317319440 13.852 100 39 78 3 1332519424 6460992 8738992
20:24:23 5435 8192000000 10214370064 10628558848 1322592848 13.900 100 22 45 1 1333166416 5765856 4807712
20:25:31 7707 8192000000 10218486928 10638655488 1327943552 13.949 100 16 32 0 1337473152 6037824 3491776
20:26:39 59995 8192000000 10220653488 10644561920 1333983200 14.003 100 26 52 2 1345557696 5360352 6214144
20:28:43 7674 8192000000 10208326112 10623627264 1290927056 13.613 97 35 70 0 1304912992 6235664 7750272
20:29:56 6249 8192000000 10165029664 10566717440 1230419440 13.058 96 26 51 1 1242625424 6405616 5800368
20:31:09 18184 8192000000 10109120080 10503684096 1154157856 12.349 92 32 64 2 1166317024 4992976 7166192
20:32:34 11827 8192000000 10135010368 10531700736 1235225648 13.102 99 29 58 0 1247907344 6015168 6666528
20:33:46 7861 8192000000 10160327520 10565767168 1257871088 13.310 99 25 50 0 1269133648 5376304 5886256
20:34:57 8991 8192000000 10175470176 10584932352 1274163088 13.460 99 21 42 1 1284462720 5329104 4970528
20:36:10 8481 8192000000 10187798064 10597978112 1295234784 13.652 100 18 36 1 1305046592 5704304 4107504
20:37:20 39195 8192000000 10208125712 10624139264 1316796144 13.848 100 24 48 0 1327164240 4680000 5688096
20:39:07 12292 8192000000 10252982944 10664460288 1345078928 14.103 100 48 94 0 1364726032 9486096 10161008
20:40:31 10261 8192000000 10269990864 10688319488 1361819312 14.254 100 43 86 1 1382841696 10296288 10726096
20:41:45 12537 8192000000 10294663088 10710298624 1377922256 14.398 100 52 104 1 1404142896 14438800 11781840
20:43:02 9315 8192000000 10297445136 10720063488 1388075808 14.489 100 47 94 0 1412908512 13507216 11325488
20:44:20 16013 8192000000 10303841264 10733088768 1398322752 14.580 100 43 86 1 1421536032 13499424 9713856
20:45:42 17022 8192000000 10314856224 10744463360 1406209616 14.650 100 39 76 0 1433457584 17459088 9788880
20:47:04 19591 8192000000 10332771312 10766020608 1415935632 14.737 100 50 100 2 1448300432 20019664 12345136
20:48:45 6027 8192000000 10359509408 10778808320 1423150400 14.801 100 63 126 2 1464541728 26232512 15158816
20:49:55 3375 8192000000 10361441056 10775068672 1419776048 14.771 99 71 142 3 1467494000 29807136 17910816
20:51:05 15267 8192000000 10357520368 10785382400 1409652336 14.681 99 55 110 0 1458420240 34747936 14019968
20:52:25 6187 8192000000 10362338304 10780450816 1407441248 14.661 100 78 155 2 1467292176 40273520 19577408
20:53:36 2606 8192000000 10373195552 10785034240 1410638032 14.690 100 89 176 6 1477478080 43985424 22854624
20:54:44 2646 8192000000 10353257648 10766643200 1390884144 14.514 100 70 137 2 1454923360 46336400 17702816
20:55:54 2708 8192000000 10330554976 10743783424 1370020064 14.327 100 72 145 2 1432732016 44408976 18302976
20:57:00 9525 8192000000 10328966096 10754908160 1386381440 14.474 100 36 72 1 1434610720 38798816 9430464
20:58:29 11226 8192000000 10317751376 10738618368 1375193776 14.374 100 44 88 2 1428028352 41192592 11641984
20:59:42 19105 8192000000 10303624400 10735460352 1352220928 14.167 99 28 57 2 1407406544 47441792 7743824
21:01:25 6540 8192000000 10272518608 10683281408 1322563712 13.900 99 46 88 3 1369342704 35597520 11181472
21:02:34 17803 8192000000 10281159312 10713366528 1356479920 14.206 100 27 54 3 1394586816 31550864 6556032
21:03:53 32475 8192000000 10297048448 10735255552 1359409088 14.232 100 17 33 1 1399778960 35608080 4761792
21:06:00 43941 8192000000 10275929344 10703454208 1341411968 14.070 99 41 82 1 1375407568 23701376 10294224
21:07:53 32726 8192000000 10268207728 10719973376 1356851152 14.209 100 11 22 0 1380955984 21276432 2828400
21:09:51 45712 8192000000 10243901040 10682347520 1313676256 13.819 97 22 43 2 1328246688 9479360 5091072
21:11:43 21070 8192000000 10257927888 10703212544 1360018272 14.238 100 25 50 0 1373582512 7826112 5738128
21:13:21 135736 8192000000 10275251616 10731294720 1348189776 14.131 98 28 56 0 1367185152 12660784 6334592
21:17:01 67151 8192000000 10281747456 10723987456 1363552976 14.269 99 21 40 0 1378534480 10895488 4086016
21:19:12 67436 8192000000 10283458416 10740363264 1380754528 14.423 100 14 28 1 1398235136 14050224 3430384
21:21:21 55364 8192000000 10291165072 10731884544 1380987568 14.425 100 48 95 0 1400577648 8976496 10613584
21:23:28 65626 8192000000 10288360464 10759524352 1385021248 14.461 100 8 16 0 1407444448 20425824 1997376
21:25:34 95591 8192000000 10317447344 10782285824 1406675360 14.654 100 17 34 0 1434023184 23411952 3935872
21:28:21 81281 8192000000 10297070400 10771570688 1400102160 14.596 100 16 31 2 1424991152 20817664 4071328
21:30:49 96569 8192000000 10311391040 10777018368 1408866704 14.674 100 3 6 0 1420567584 11068288 632592
21:33:40 83612 8192000000 10303945056 10756161536 1347001488 14.120 96 13 24 1 1362574000 12840768 2731744
21:36:04 82031 8192000000 10306198048 10766651392 1353756192 14.181 96 23 46 1 1374747584 15932560 5058832
21:39:02 40787 8192000000 10295115920 10746814464 1348215376 14.131 96 18 37 0 1362445040 10470160 3759504
21:40:43 85124 8192000000 10290805728 10746925056 1297597616 13.673 93 21 42 0 1319091136 17015488 4478032
21:43:09 81616 8192000000 10276320928 10717851648 1266789856 13.392 91 34 69 1 1292814528 18473744 7550928
21:45:32 145972 8192000000 10281157104 10725412864 1330496624 13.972 97 10 20 1 1353588560 20385056 2706880
21:49:08 37891 8192000000 10264296576 10721931264 1360824128 14.245 99 11 21 1 1371252480 7579568 2848784
21:50:51 55418 8192000000 10252858768 10708467712 1347032928 14.121 99 8 16 1 1358024384 9063888 1927568
21:53:02 30720 8192000000 10256080048 10701750272 1327428080 13.944 97 11 22 1 1336570304 6412592 2729632
21:54:33 55424 8192000000 10258238144 10711199744 1360558672 14.242 100 11 22 0 1372880176 9548864 2772640
21:56:52 89572 8192000000 10254849232 10722299904 1341366080 14.070 98 22 45 0 1361288496 14800144 5122272
21:59:22 77238 8192000000 10251773392 10706210816 1334970496 14.012 98 8 16 0 1347220688 10588176 1662016
22:01:56 77167 8192000000 10281321344 10728091648 1316913360 13.849 96 14 28 0 1340216016 19744096 3558560
22:04:15 87556 8192000000 10272278080 10731499520 1343704624 14.091 98 14 28 0 1367085824 19654368 3726832
22:06:44 31614 8192000000 10300931952 10735329280 1382964464 14.443 100 45 89 0 1403966368 11034240 9967664
22:08:17 70802 8192000000 10308711952 10773438464 1399095408 14.587 100 14 28 1 1419713744 17608112 3010224
22:10:34 72817 8192000000 10288014384 10733572096 1356646736 14.207 97 22 44 1 1371763104 10632768 4483600
22:13:23 39809 8192000000 10301396016 10760138752 1401032640 14.604 100 28 56 0 1419678432 12146640 6499152
22:15:23 60774 8192000000 10302713040 10766098432 1392356560 14.527 99 7 14 1 1408931984 15119808 1455616
22:17:26 28094 8192000000 10297364368 10754953216 1387497648 14.484 99 11 22 0 1402546944 13165376 1883920
22:18:54 62684 8192000000 10309223408 10767564800 1410569072 14.689 100 15 29 0 1427026640 13276352 3181216
22:21:08 87532 8192000000 10319560176 10781380608 1391863504 14.522 98 15 30 1 1407529776 12069856 3596416
22:24:03 35656 8192000000 10323386432 10776899584 1402011424 14.613 99 28 56 0 1422502688 13890896 6600368
22:26:19 145981 8192000000 10299639952 10754809856 1320848208 13.884 94 7 14 0 1333634880 11586128 1200544
22:29:46 35335 8192000000 10309652656 10745823232 1354552864 14.188 97 43 86 0 1378502848 14766352 9183632
22:31:25 32204 8192000000 10297801056 10731786240 1332979760 13.994 96 27 54 0 1349350880 10217872 6153248
22:33:03 132379 8192000000 10282218272 10726141952 1333247984 13.996 97 26 52 1 1353333600 13568352 6517264
22:36:16 66236 8192000000 10242650816 10700378112 1304935024 13.740 95 18 35 1 1321484880 11954176 4595680

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 31-10-2021 22:40:42 to 31-10-2021 22:40:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 55 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-10-2021 22:40:58 to 31-10-2021 22:41:00.

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          230479
MON$OLDEST_ACTIVE               233311
MON$OLDEST_SNAPSHOT             233311
MON$NEXT_TRANSACTION            233311
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                       2020160
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.33525 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33525 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33525 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33525 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 ---
:124: 
:125: mscs-test-161.budzdorov.ru    Sun Oct 31 22:37:59 2021
:126:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:127: 
:128: 
:129: mscs-test-161.budzdorov.ru    Sun Oct 31 22:38:18 2021
:130:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:131: 
:132: 
:133: mscs-test-161.budzdorov.ru    Sun Oct 31 22:38:22 2021
:134:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:135: 
--- end of diff output ---

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

31.10.21 22:41:01. 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-10-31 22:41:03 No severe PSQL-related problems occured
31.10.21 22:41:04. End of report.