Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.10.2021 13: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 15698372 kB
MemAvailable 30719912 kB
Buffers 0 kB
Cached 16035396 kB
SwapCached 3816 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 4.0.1
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-13 13:37:09
DTS_END                         2021-10-13 13:37:09.2690
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/56078
TRN_ID                          238247
ATT_ID                          643
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: 9011.63 18830 1081396
customer order: creation 181.98 398 21838
customer order: refuse 15.26 417 1831
order to supplier: creation 49.21 1490 5905
order to supplier: removal 3.16 120055 379
invoice (draft): creation 48.27 3846 5792
invoice (draft): removal 49.24 1017 5909
invoice accept: apply 43.52 20452 5222
invoice accept: cancel 43.67 924 5240
customer reserve: creation 3963.63 204 475636
customer reserve: removal 4321.11 68 518533
realization accept: apply 89.43 199 10732
realization accept: cancel 68.38 130 8206
payment from customer: creation 61.96 146 7435
payment from customer: removal 5.80 113 696
payment to supplier: creation 51.58 152 6189
payment to supplier: removal 3.71 76 445
service: total inventory turnovers 3.96 4145 475
service: total monetary turnovers 6.85 582 822
service: aggregate perf. data 0.89 16249 107
service: refresh index statistics 0.03 205930 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 13-10-2021 13:39:12 to 13-10-2021 13:39:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6027
TEST_TIME 2 5593
TEST_TIME 3 4771
TEST_TIME 4 5275
TEST_TIME 5 5332
TEST_TIME 6 5301
TEST_TIME 7 5564
TEST_TIME 8 5876
TEST_TIME 9 5933
TEST_TIME 10 5973
TEST_TIME 11 6131
TEST_TIME 12 5974
TEST_TIME 13 6113
TEST_TIME 14 6089
TEST_TIME 15 6239
TEST_TIME 16 6437
TEST_TIME 17 6634
TEST_TIME 18 6609
TEST_TIME 19 6732
TEST_TIME 20 7136
TEST_TIME 21 7412
TEST_TIME 22 7915
TEST_TIME 23 7862
TEST_TIME 24 8069
TEST_TIME 25 8746
TEST_TIME 26 8781
TEST_TIME 27 8957
TEST_TIME 28 9504
TEST_TIME 29 9532
TEST_TIME 30 10305
TEST_TIME 31 10421
TEST_TIME 32 10286
TEST_TIME 33 10202
TEST_TIME 34 10364
TEST_TIME 35 10572
TEST_TIME 36 10418
TEST_TIME 37 10511
TEST_TIME 38 10322
TEST_TIME 39 10320
TEST_TIME 40 10238
TEST_TIME 41 10119
TEST_TIME 42 9964
TEST_TIME 43 9916
TEST_TIME 44 9835
TEST_TIME 45 9738
TEST_TIME 46 9756
TEST_TIME 47 9746
TEST_TIME 48 9626
TEST_TIME 49 9670
TEST_TIME 50 9804
TEST_TIME 51 9768
TEST_TIME 52 9669
TEST_TIME 53 9677
TEST_TIME 54 9633
TEST_TIME 55 9573
TEST_TIME 56 9521
TEST_TIME 57 9425
TEST_TIME 58 9371
TEST_TIME 59 9281
TEST_TIME 60 9394
TEST_TIME 61 9346
TEST_TIME 62 9336
TEST_TIME 63 9371
TEST_TIME 64 9445
TEST_TIME 65 9451
TEST_TIME 66 9482
TEST_TIME 67 9539
TEST_TIME 68 9502
TEST_TIME 69 9527
TEST_TIME 70 9481
TEST_TIME 71 9654
TEST_TIME 72 9598
TEST_TIME 73 9698
TEST_TIME 74 9607
TEST_TIME 75 9502
TEST_TIME 76 9465
TEST_TIME 77 9434
TEST_TIME 78 9413
TEST_TIME 79 9377
TEST_TIME 80 9347
TEST_TIME 81 9334
TEST_TIME 82 9260
TEST_TIME 83 9300
TEST_TIME 84 9266
TEST_TIME 85 9256
TEST_TIME 86 9245
TEST_TIME 87 9279
TEST_TIME 88 9307
TEST_TIME 89 9300
TEST_TIME 90 9326
TEST_TIME 91 9300
TEST_TIME 92 9326
TEST_TIME 93 9365
TEST_TIME 94 9376
TEST_TIME 95 9365
TEST_TIME 96 9396
TEST_TIME 97 9307
TEST_TIME 98 9284
TEST_TIME 99 9254
TEST_TIME 100 9221
TEST_TIME 101 9193
TEST_TIME 102 9181
TEST_TIME 103 9160
TEST_TIME 104 9164
TEST_TIME 105 9132
TEST_TIME 106 9101
TEST_TIME 107 9096
TEST_TIME 108 9112
TEST_TIME 109 9169
TEST_TIME 110 9171
TEST_TIME 111 9206
TEST_TIME 112 9266
TEST_TIME 113 9242
TEST_TIME 114 9235
TEST_TIME 115 9176
TEST_TIME 116 9135
TEST_TIME 117 9089
TEST_TIME 118 9095
TEST_TIME 119 9088
TEST_TIME 120 9018

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-10-2021 13:39:16 to 13-10-2021 13:39:19.

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 1521843 1521843 0 0.00 0 28625 8 0 0
doc_list_biud 1521843 1521843 0 0.00 0 28291 30 0 0
sp_add_invoice_to_stock 5292 5222 70 1.32 41 178214 20452 0 70
sp_cancel_adding_invoice 5240 5240 0 0.00 6 61352 925 0 0
sp_cancel_client_order 1831 1831 0 0.00 5 14983 418 0 0
sp_cancel_customer_reserve 518533 518533 0 0.00 1 28438 68 0 0
sp_cancel_pay_from_customer 696 696 0 0.00 3 4473 114 0 0
sp_cancel_pay_to_supplier 445 445 0 0.00 3 2613 76 0 0
sp_cancel_supplier_invoice 5910 5909 1 0.01 2 23617 1017 0 1
sp_cancel_supplier_order 379 379 0 0.00 13 1189729 120056 0 0
sp_cancel_write_off 8206 8206 0 0.00 1 25850 131 0 0
sp_client_order 21838 21838 0 0.00 4 26384 398 0 0
sp_customer_reserve 475636 475636 0 0.00 0 76761 204 0 0
sp_fill_shopping_cart 33513 33513 0 0.00 1 12741 43 0 0
sp_fill_shopping_cart_clo_res 475626 475626 0 0.00 0 18746 16 0 0
sp_get_clo_for_invoice 5212 5212 0 0.00 0 27176 301 0 0
sp_kill_cost_storno 14587 14587 0 0.00 0 9103 72 0 0
sp_kill_qstorno_ret_qs2qd 1051560 1051560 0 0.00 0 28284 37 0 0
sp_kill_qty_storno 556042 556042 0 0.00 0 28290 81 0 0
sp_lock_selected_doc 21920 21920 0 0.00 0 7116 5 0 0
sp_make_cost_storno 29568 29568 0 0.00 0 28505 61 0 0
sp_multiply_rows_for_pdistr 29568 29568 0 0.00 0 7786 36 0 0
sp_multiply_rows_for_qdistr 21838 21838 0 0.00 0 25558 191 0 0
sp_pay_from_customer 7435 7435 0 0.00 5 21773 146 0 0
sp_pay_to_supplier 6189 6189 0 0.00 5 14437 153 0 0
sp_payment_common 13624 13624 0 0.00 1 21769 29 0 0
sp_qd_handle_on_cancel_clo 1831 1831 0 0.00 0 14976 388 0 0
sp_qd_handle_on_invoice_upd_sts 10452 10452 0 0.00 0 18078 400 0 0
sp_qd_handle_on_reserve_upd_sts 10732 10732 0 0.00 0 19578 62 0 0
sp_reserve_write_off 10732 10732 0 0.00 7 19615 200 0 0
sp_supplier_invoice 5792 5792 0 0.00 7 98570 3846 0 0
sp_supplier_order 5905 5905 0 0.00 5 102594 1490 0 0
srv_aggregate_perf_data 107 107 0 0.00 2845 176542 16249 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1335 160506 41643 0 0
srv_find_qd_qs_mism 1031575 1031575 0 0.00 0 26546 7 0 0
srv_make_invnt_saldo 475 475 0 0.00 42 107041 4145 0 0
srv_make_money_saldo 822 822 0 0.00 1 12254 583 0 0
srv_recalc_idx_stat 4 4 0 0.00 162916 319497 205931 0 0
t$perf-abend:sp_add_invoice_to_stock 70 70 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5222 5222 0 0.00 0 24812 180 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 0 14872 150 0 0
t$perf-norm:sp_cancel_client_order 1831 1831 0 0.00 0 2199 11 0 0
t$perf-norm:sp_cancel_customer_reserve 722 722 0 0.00 0 1814 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 696 696 0 0.00 0 668 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 445 445 0 0.00 0 461 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 431 431 0 0.00 0 1030 20 0 0
t$perf-norm:sp_cancel_supplier_order 379 379 0 0.00 0 18699 1554 0 0
t$perf-norm:sp_cancel_write_off 1067 1067 0 0.00 0 2378 11 0 0
t$perf-norm:sp_client_order 21838 21838 0 0.00 0 21419 24 0 0
t$perf-norm:sp_customer_reserve 1044 1044 0 0.00 0 9969 17 0 0
t$perf-norm:sp_pay_from_customer 7435 7435 0 0.00 0 7504 11 0 0
t$perf-norm:sp_pay_to_supplier 6189 6189 0 0.00 0 2758 8 0 0
t$perf-norm:sp_reserve_write_off 10732 10732 0 0.00 0 14345 11 0 0
t$perf-norm:sp_supplier_invoice 5792 5792 0 0.00 0 5249 18 0 0
t$perf-norm:sp_supplier_order 5905 5905 0 0.00 0 5362 21 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 536 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 486 15 0 0
t$perf-norm:srv_make_invnt_saldo 475 475 0 0.00 0 3076 23 0 0
t$perf-norm:srv_make_money_saldo 822 822 0 0.00 0 2490 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 33 10 0 0
v_add_invoice_to_stock 10552 10412 140 1.32 0 8157 19 0 140
v_all_customers 39500 39500 0 0.00 0 23 1 0 0
v_all_suppliers 32072 32072 0 0.00 0 160 1 0 0
v_all_wares 43672 43672 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 2123 11 0 0
v_cancel_client_order 3662 3662 0 0.00 0 3784 7 0 0
v_cancel_customer_prepayment 1392 1392 0 0.00 0 3842 10 0 0
v_cancel_customer_reserve 1444 1444 0 0.00 0 3039 10 0 0
v_cancel_payment_to_supplier 890 890 0 0.00 0 964 6 0 0
v_cancel_supplier_invoice 864 862 2 0.23 0 1098 15 0 2
v_cancel_supplier_order 758 758 0 0.00 0 1898 7 0 0
v_cancel_write_off 2134 2134 0 0.00 0 5318 19 0 0
v_max_id_clo_ord 5902 5902 0 0.00 0 301 1 0 0
v_max_id_clo_res 1044 1044 0 0.00 0 2792 11 0 0
v_max_id_ord_sup 5775 5775 0 0.00 0 2867 4 0 0
v_max_non_paid_invoice 1830 1830 0 0.00 0 2025 3 0 0
v_max_non_paid_realizn 4099 4099 0 0.00 0 3732 4 0 0
v_min_id_clo_ord 5902 5902 0 0.00 0 12730 11 0 0
v_min_id_clo_res 1044 1044 0 0.00 0 1870 6 0 0
v_min_id_ord_sup 5775 5775 0 0.00 0 6373 7 0 0
v_min_non_paid_invoice 6189 6189 0 0.00 0 7547 20 0 0
v_min_non_paid_realizn 7435 7435 0 0.00 0 21766 22 0 0
v_our_firm 10844 10844 0 0.00 0 21 1 0 0
v_reserve_write_off 21462 21462 0 0.00 0 17768 7 0 0
x_lock_depdocs_on_canc_invoice 10718 10718 0 0.00 0 16707 121 0 0
x_lock_depdocs_on_canc_sup_ord 379 379 0 0.00 0 4606 376 0 0
x_make_qstorno_1000_1200 5905 5905 0 0.00 2 102576 1388 0 0
x_make_qstorno_1200_2000 5792 5792 0 0.00 4 88610 3700 0 0
x_make_qstorno_2100_3300 475636 475636 0 0.00 0 76759 185 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 13-10-2021 13:39:20 to 13-10-2021 13:39:25.

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
11:07:13 1092 8192000000 8958997280 9023614976 3901568 0.047 2 1 2 0 4000880 63536 35776
11:08:15 2370 8192000000 9706669600 9996763136 782847184 8.722 100 87 174 0 812029568 7309392 21872992
11:09:31 22685 8192000000 9742607504 10045530112 812506256 9.023 100 88 176 0 845407376 11142336 21758784
11:10:57 8861 8192000000 9782866032 10096033792 848044336 9.380 100 87 174 1 883743472 15188448 20510688
11:12:08 13190 8192000000 9838180864 10163044352 883872688 9.738 100 82 164 0 934044976 31337248 18835040
11:13:25 10628 8192000000 9885475552 10227302400 929702576 10.192 100 68 136 3 993400528 48956448 14741504
11:14:39 7278 8192000000 9931381376 10283511808 963409408 10.522 100 65 130 1 1035706928 58166528 14130992
11:15:49 5339 8192000000 9977175232 10339430400 1002644336 10.904 100 77 154 0 1077686480 59156848 15885296
11:16:57 2380 8192000000 9996637024 10369486848 1030505184 11.173 100 70 140 1 1109661888 64561552 14595152
11:18:01 1510 8192000000 10048357920 10420248576 1055859056 11.417 100 90 179 1 1151694432 77357664 18477712
11:19:04 3219 8192000000 10048486960 10436374528 1064502800 11.500 100 62 121 0 1157233008 80639488 12090720
11:20:09 28335 8192000000 9996236624 10400342016 1069078288 11.543 100 37 74 1 1124194848 46359008 8757552
11:21:46 5745 8192000000 10008243424 10413060096 1074178272 11.592 100 44 88 0 1117820768 34094576 9547920
11:22:53 5391 8192000000 10002687872 10402267136 1083230752 11.678 100 35 70 0 1099629952 9112448 7286752
11:24:01 6422 8192000000 10012585488 10417635328 1084029632 11.686 100 32 63 1 1110463920 19326352 7107936
11:25:11 38500 8192000000 10012384768 10408296448 1086436400 11.709 100 47 96 0 1109736128 12683936 10615792
11:26:50 6029 8192000000 10008090096 10420260864 1093081728 11.772 100 41 82 2 1127343440 24703840 9557872
11:27:57 5729 8192000000 10037775072 10429349888 1089660480 11.739 100 54 108 1 1131353584 29963472 11729632
11:29:04 5222 8192000000 9994284064 10391359488 1055500208 11.413 98 40 79 1 1078099680 14174752 8424720
11:30:10 43665 8192000000 9964847936 10339934208 1011415920 10.989 97 60 118 1 1035218672 10238752 13564000
11:31:54 5309 8192000000 9948587632 10322116608 998472208 10.864 97 46 87 1 1044492112 36200112 9819792
11:32:59 7332 8192000000 9949681568 10326622208 1018384560 11.056 99 45 88 1 1037374736 9039920 9950256
11:34:09 7734 8192000000 9958855984 10343710720 1037146016 11.237 100 50 100 0 1068996064 20566032 11284016
11:35:22 57339 8192000000 9979221600 10362503168 1052946224 11.389 100 51 103 4 1086778560 22827776 11004560
11:37:24 47648 8192000000 9997532368 10392485888 1080014144 11.648 100 40 80 1 1109019712 20861168 8144400
11:39:21 24221 8192000000 10045740784 10443685888 1103213520 11.868 100 40 79 1 1150192272 38872016 8106736
11:40:48 11573 8192000000 10081850192 10481831936 1117411520 12.003 100 48 96 1 1191822160 64563888 9846752
11:42:02 55559 8192000000 10106157872 10520408064 1125235840 12.076 100 39 78 1 1226064624 92242560 8586224
11:43:59 4875 8192000000 10131244128 10533994496 1133662480 12.156 100 67 137 0 1239730544 92094176 13973888
11:45:09 4501 8192000000 10140369728 10552856576 1143677552 12.250 100 53 106 1 1248083456 92724400 11681504
11:46:18 4986 8192000000 10167164512 10571653120 1143568736 12.249 99 72 144 0 1266164432 106153616 16442080
11:47:27 9621 8192000000 10212613376 10627399680 1163881360 12.440 100 54 108 0 1319429824 143432432 12116032
11:48:42 9890 8192000000 10241658784 10662948864 1182601056 12.614 100 58 115 1 1349487216 153725136 13161024
11:49:59 24094 8192000000 10297304368 10715930624 1184842656 12.635 100 61 122 0 1390309152 191996032 13470464
11:51:33 5030 8192000000 10346960608 10751918080 1189350048 12.677 100 89 176 1 1447137392 238464960 19322384
11:52:43 4357 8192000000 10396361200 10800193536 1186808336 12.654 100 92 182 1 1505128608 297879296 20440976
11:53:51 3009 8192000000 10415922528 10826440704 1187599024 12.661 100 89 176 1 1523284016 315362064 20322928
11:54:59 2539 8192000000 10421980256 10827735040 1166864208 12.468 100 81 162 0 1518997520 333873184 18260128
11:56:09 2335 8192000000 10431778896 10841497600 1157738512 12.382 100 93 186 3 1537097536 358519776 20839248
11:57:17 1335 8192000000 10434437632 10865623040 1155442000 12.361 100 79 158 0 1527613920 353530880 18641040
11:58:24 2308 8192000000 10437638064 10858463232 1155450672 12.361 100 79 158 1 1536980112 363060768 18468672
11:59:33 2545 8192000000 10404659680 10849001472 1148564320 12.296 99 81 157 2 1494859680 327042768 19252592
12:00:37 4047 8192000000 10423379168 10840645632 1134663712 12.165 100 53 106 1 1513253904 364396800 14193392
12:01:49 6623 8192000000 10469816544 10905673728 1146464688 12.276 100 63 126 0 1561348240 399228944 15654608
12:03:02 2029 8192000000 10355323104 10769645568 1142208944 12.236 100 49 93 1 1453749056 300571216 10968896
12:04:09 20777 8192000000 10348246816 10828386304 1135166640 12.170 100 23 46 0 1459774576 318295072 6312864
12:05:31 25481 8192000000 10248660000 10740150272 1099827504 11.836 99 22 40 1 1391406192 286202736 5375952
12:07:00 22626 8192000000 10195690768 10667831296 1118228224 12.010 100 49 98 1 1313144576 183811552 11104800
12:08:24 71697 8192000000 10079102464 10569908224 1125761952 12.081 100 7 14 0 1168620992 41517888 1341152
12:10:50 33320 8192000000 10105573808 10577571840 1110435504 11.937 98 9 17 1 1183617504 71361648 1820352
12:12:24 129098 8192000000 10081809584 10573307904 1122940784 12.055 99 7 14 1 1179371952 54628368 1802800
12:15:52 39593 8192000000 10092701136 10540265472 1129631040 12.118 100 33 66 0 1181419136 44559984 7228112
12:17:32 61122 8192000000 10100030128 10524323840 1113121664 11.962 98 35 68 0 1152000368 31337216 7541488
12:19:37 87202 8192000000 10075435152 10508398592 1124442336 12.069 99 43 86 1 1193480896 59890112 9148448
12:22:17 56188 8192000000 10098389568 10520129536 1149689120 12.307 100 37 73 1 1196058432 38919296 7450016
12:24:16 27527 8192000000 10111809888 10556624896 1154287232 12.350 100 25 50 0 1221265328 61147840 5830256
12:25:44 39916 8192000000 10127094912 10587504640 1131332064 12.134 99 33 66 1 1235675408 96740000 7603344
12:27:33 55513 8192000000 10105105968 10582073344 1132254480 12.143 99 14 28 0 1208762720 73485120 3023120
12:29:29 55709 8192000000 10121953120 10575781888 1123527392 12.060 98 11 22 0 1202301952 76226208 2548352
12:31:53 41418 8192000000 10069082064 10567692288 1124660128 12.071 99 9 18 0 1189985968 63164960 2160880
12:33:35 62228 8192000000 10072707504 10519158784 1129923968 12.121 99 19 38 1 1168337328 34330256 4083104
12:35:39 52541 8192000000 10089259312 10560577536 1130934160 12.130 99 17 34 0 1203223472 68657856 3631456
12:37:58 33765 8192000000 10038991600 10477223936 1086270464 11.707 96 22 43 1 1113975664 23335552 4369648
12:39:35 38863 8192000000 10101720384 10555944960 1127209856 12.095 99 25 50 2 1196047424 63942448 4895120
12:41:17 67266 8192000000 10070411760 10533437440 1049784976 11.359 93 11 22 0 1102729632 50771456 2173200
12:43:27 38723 8192000000 10075082672 10560860160 1101965936 11.856 99 25 50 0 1179914800 72974768 4974096
12:45:07 46704 8192000000 10091834576 10547073024 1087670768 11.721 97 16 32 0 1154933024 63927776 3334480
12:46:59 96264 8192000000 10067453312 10553831424 1064541664 11.500 96 15 30 3 1176306960 108297520 3467776
12:49:41 26932 8192000000 10084763680 10504773632 1098425152 11.823 99 28 56 0 1156113984 52191824 5497008
12:51:13 37707 8192000000 10043658704 10464579584 1090068400 11.743 98 40 79 2 1117201600 18538064 8595136
12:52:59 82084 8192000000 10061453376 10513715200 1111832704 11.950 100 30 60 0 1167482416 49528256 6121456
12:55:26 60782 8192000000 10044309264 10503458816 1108624208 11.919 99 13 26 0 1152330208 41063536 2642464
12:57:33 47998 8192000000 10039707872 10522370048 1083106208 11.677 98 6 12 0 1146409696 61955888 1347600
12:59:21 65626 8192000000 10058376896 10553016320 1091211904 11.754 99 8 16 1 1188383664 95430640 1741120
13:02:13 36750 8192000000 10060519088 10505572352 1107758320 11.911 99 24 49 0 1152059568 39145856 5155392
13:03:52 50331 8192000000 10082693760 10514554880 1103468944 11.871 98 25 50 0 1160860416 52142864 5248608
13:05:52 46798 8192000000 10084279664 10520817664 1095790000 11.798 97 22 44 0 1143254768 42269680 5195088
13:07:48 20960 8192000000 10063316064 10486890496 1132124400 12.141 100 46 93 0 1162363424 21446960 8792064
13:09:10 25949 8192000000 10116763088 10571116544 1132332000 12.143 99 38 76 1 1198578800 58969424 7277376
13:10:38 82166 8192000000 10071666336 10516725760 1111191264 11.944 97 9 18 1 1139357136 26511408 1654464
13:13:01 32619 8192000000 10084749632 10547130368 1123188848 12.057 99 21 42 0 1189374176 61638304 4547024
13:14:34 106310 8192000000 10063379952 10519322624 1101002560 11.847 97 28 56 1 1146980368 40429744 5548064
13:17:21 58323 8192000000 10073788512 10543849472 1135003776 12.169 100 9 18 0 1185340864 48378000 1959088
13:19:19 61227 8192000000 10067143392 10539454464 1114073168 11.971 98 17 34 0 1142065360 24326736 3665456
13:21:28 56734 8192000000 10063437456 10525552640 1106177520 11.896 97 14 28 1 1155304976 46136128 2991328
13:23:27 36368 8192000000 10145316608 10588962816 1120086096 12.028 98 28 56 0 1193231264 67013072 6132096
13:25:14 76101 8192000000 10127191104 10606125056 1095674432 11.797 96 19 38 0 1233949008 133586784 4687792
13:27:31 68612 8192000000 10095665632 10532564992 1094701440 11.787 97 15 30 0 1163320928 65064592 3554896
13:30:13 44764 8192000000 10102772848 10542764032 1118700352 12.015 99 42 84 0 1176459920 48939104 8820464
13:32:01 160506 8192000000 10075551376 10526306304 1080189952 11.649 95 27 55 0 1153842864 67856704 5796208
13:35:46 67734 8192000000 10034339776 10522423296 1094676464 11.787 98 14 28 0 1149459312 51668720 3114128

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 13-10-2021 13:39:26 to 13-10-2021 13:39:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 70 v_add_invoice_to_stock 335544517
except 1 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-10-2021 13:39:43 to 13-10-2021 13:39:45.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          237100
MON$OLDEST_ACTIVE               238362
MON$OLDEST_SNAPSHOT             238362
MON$NEXT_TRANSACTION            238362
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-30 07:30:08.6030 GMT
MON$PAGES                       2068128
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             808
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2631 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2631 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2631 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2631 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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    Wed Oct 13 13:37:25 2021
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:39: 
:40: 
:41: mscs-test-161.budzdorov.ru    Wed Oct 13 13:37:30 2021
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:43: 
--- end of diff output ---

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

13.10.21 13:39:46. There are 3 logs in /var/tmp/logs-oltp40 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-13 13:39:48 No severe PSQL-related problems occured
13.10.21 13:39:49. End of report.