Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.03.2021 13:27
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 df9f488d08424df294c459d6c283e9c3
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-03-04 16:07
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 4794.26
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 16757116 kB
MemAvailable 31558208 kB
Buffers 0 kB
Cached 14869308 kB
SwapCached 0 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.0
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-03-13 13:26:54
DTS_END                         2021-03-13 13:26:54.2230
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/54440
TRN_ID                          228218
ATT_ID                          592
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: 9018.48 23868 1082217
customer order: creation 170.28 380 20433
customer order: refuse 13.34 381 1601
order to supplier: creation 45.51 1446 5461
order to supplier: removal 3.36 133939 403
invoice (draft): creation 44.80 3918 5376
invoice (draft): removal 51.58 1102 6189
invoice accept: apply 40.75 19902 4890
invoice accept: cancel 46.26 973 5551
customer reserve: creation 3769.84 194 452381
customer reserve: removal 4551.93 76 546231
realization accept: apply 82.23 193 9868
realization accept: cancel 74.73 139 8968
payment from customer: creation 57.18 114 6862
payment from customer: removal 5.65 83 678
payment to supplier: creation 46.93 129 5632
payment to supplier: removal 3.56 87 427
service: total inventory turnovers 3.45 4584 414
service: total monetary turnovers 6.21 703 745
service: aggregate perf. data 0.86 17775 103
service: refresh index statistics 0.03 291238 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 13-03-2021 13:27:24 to 13-03-2021 13:27:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2206
TEST_TIME 2 3754
TEST_TIME 3 3793
TEST_TIME 4 4201
TEST_TIME 5 4509
TEST_TIME 6 4466
TEST_TIME 7 4652
TEST_TIME 8 5477
TEST_TIME 9 5603
TEST_TIME 10 5826
TEST_TIME 11 6058
TEST_TIME 12 6430
TEST_TIME 13 6710
TEST_TIME 14 6840
TEST_TIME 15 7101
TEST_TIME 16 7157
TEST_TIME 17 7232
TEST_TIME 18 7188
TEST_TIME 19 7616
TEST_TIME 20 7701
TEST_TIME 21 8009
TEST_TIME 22 8061
TEST_TIME 23 8371
TEST_TIME 24 8429
TEST_TIME 25 8700
TEST_TIME 26 8574
TEST_TIME 27 8587
TEST_TIME 28 8636
TEST_TIME 29 8616
TEST_TIME 30 8670
TEST_TIME 31 8967
TEST_TIME 32 10110
TEST_TIME 33 10611
TEST_TIME 34 10865
TEST_TIME 35 10839
TEST_TIME 36 10728
TEST_TIME 37 10586
TEST_TIME 38 10501
TEST_TIME 39 10407
TEST_TIME 40 10281
TEST_TIME 41 10192
TEST_TIME 42 10234
TEST_TIME 43 10176
TEST_TIME 44 10232
TEST_TIME 45 10204
TEST_TIME 46 10163
TEST_TIME 47 10178
TEST_TIME 48 10303
TEST_TIME 49 10215
TEST_TIME 50 10068
TEST_TIME 51 9894
TEST_TIME 52 9722
TEST_TIME 53 9707
TEST_TIME 54 9633
TEST_TIME 55 9603
TEST_TIME 56 9550
TEST_TIME 57 9520
TEST_TIME 58 9508
TEST_TIME 59 9479
TEST_TIME 60 9434
TEST_TIME 61 9358
TEST_TIME 62 9310
TEST_TIME 63 9393
TEST_TIME 64 9393
TEST_TIME 65 9359
TEST_TIME 66 9311
TEST_TIME 67 9312
TEST_TIME 68 9513
TEST_TIME 69 9543
TEST_TIME 70 9499
TEST_TIME 71 9558
TEST_TIME 72 9550
TEST_TIME 73 9514
TEST_TIME 74 9551
TEST_TIME 75 9618
TEST_TIME 76 9617
TEST_TIME 77 9571
TEST_TIME 78 9492
TEST_TIME 79 9450
TEST_TIME 80 9410
TEST_TIME 81 9332
TEST_TIME 82 9255
TEST_TIME 83 9228
TEST_TIME 84 9250
TEST_TIME 85 9189
TEST_TIME 86 9137
TEST_TIME 87 9096
TEST_TIME 88 9112
TEST_TIME 89 9059
TEST_TIME 90 9076
TEST_TIME 91 9045
TEST_TIME 92 9023
TEST_TIME 93 9106
TEST_TIME 94 9150
TEST_TIME 95 9108
TEST_TIME 96 9115
TEST_TIME 97 9156
TEST_TIME 98 9188
TEST_TIME 99 9244
TEST_TIME 100 9229
TEST_TIME 101 9246
TEST_TIME 102 9255
TEST_TIME 103 9218
TEST_TIME 104 9141
TEST_TIME 105 9097
TEST_TIME 106 9082
TEST_TIME 107 9022
TEST_TIME 108 8998
TEST_TIME 109 8958
TEST_TIME 110 8946
TEST_TIME 111 8991
TEST_TIME 112 8961
TEST_TIME 113 8970
TEST_TIME 114 8968
TEST_TIME 115 8976
TEST_TIME 116 8958
TEST_TIME 117 9010
TEST_TIME 118 8992
TEST_TIME 119 8991
TEST_TIME 120 9022

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-03-2021 13:27:28 to 13-03-2021 13:27:31.

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 1501549 1501549 0 0.00 0 34240 7 0 0
doc_list_biud 1501548 1501548 0 0.00 0 41493 35 0 0
sp_add_invoice_to_stock 5004 4890 114 2.27 34 179823 19903 0 114
sp_cancel_adding_invoice 5551 5551 0 0.00 5 75598 974 0 0
sp_cancel_client_order 1601 1601 0 0.00 5 12166 381 0 0
sp_cancel_customer_reserve 546231 546231 0 0.00 1 41495 76 0 0
sp_cancel_pay_from_customer 678 678 0 0.00 2 4048 84 0 0
sp_cancel_pay_to_supplier 427 427 0 0.00 3 1836 88 0 0
sp_cancel_supplier_invoice 6193 6189 4 0.06 2 24628 1103 0 4
sp_cancel_supplier_order 403 403 0 0.00 11 1350257 133939 0 0
sp_cancel_write_off 8968 8968 0 0.00 1 7904 140 0 0
sp_client_order 20433 20433 0 0.00 4 18903 380 0 0
sp_customer_reserve 452381 452381 0 0.00 0 96429 195 0 0
sp_fill_shopping_cart 31249 31249 0 0.00 1 22187 46 0 0
sp_fill_shopping_cart_clo_res 452376 452376 0 0.00 0 24046 17 0 0
sp_get_clo_for_invoice 4885 4885 0 0.00 0 33237 387 0 0
sp_kill_cost_storno 15624 15624 0 0.00 0 6391 71 0 0
sp_kill_qstorno_ret_qs2qd 1108022 1108022 0 0.00 0 41491 41 0 0
sp_kill_qty_storno 583696 583696 0 0.00 0 41493 90 0 0
sp_lock_selected_doc 20290 20290 0 0.00 0 9020 4 0 0
sp_make_cost_storno 27247 27247 0 0.00 0 10605 59 0 0
sp_multiply_rows_for_pdistr 27247 27247 0 0.00 0 7212 35 0 0
sp_multiply_rows_for_qdistr 20433 20433 0 0.00 0 13224 189 0 0
sp_pay_from_customer 6862 6862 0 0.00 4 14102 114 0 0
sp_pay_to_supplier 5632 5632 0 0.00 5 10724 129 0 0
sp_payment_common 12494 12494 0 0.00 1 6901 19 0 0
sp_qd_handle_on_cancel_clo 1601 1601 0 0.00 0 12157 344 0 0
sp_qd_handle_on_invoice_upd_sts 10436 10436 0 0.00 0 20979 462 0 0
sp_qd_handle_on_reserve_upd_sts 9868 9868 0 0.00 0 20163 58 0 0
sp_reserve_write_off 9868 9868 0 0.00 7 20209 194 0 0
sp_supplier_invoice 5376 5376 0 0.00 7 144064 3919 0 0
sp_supplier_order 5461 5461 0 0.00 6 38672 1447 0 0
srv_aggregate_perf_data 103 103 0 0.00 2597 276815 17775 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1166 151211 44267 0 0
srv_find_qd_qs_mism 1035833 1035833 0 0.00 0 34237 6 0 0
srv_make_invnt_saldo 414 414 0 0.00 57 117161 4585 0 0
srv_make_money_saldo 745 745 0 0.00 1 15269 704 0 0
srv_recalc_idx_stat 4 4 0 0.00 126703 452055 291238 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4890 4890 0 0.00 0 8402 199 0 0
t$perf-norm:sp_cancel_adding_invoice 393 393 0 0.00 1 7849 107 0 0
t$perf-norm:sp_cancel_client_order 1601 1601 0 0.00 0 1102 10 0 0
t$perf-norm:sp_cancel_customer_reserve 659 659 0 0.00 0 1821 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 678 678 0 0.00 0 936 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 427 427 0 0.00 0 485 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 2856 31 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 26337 2143 0 0
t$perf-norm:sp_cancel_write_off 985 985 0 0.00 0 6090 13 0 0
t$perf-norm:sp_client_order 20433 20433 0 0.00 0 13559 24 0 0
t$perf-norm:sp_customer_reserve 955 955 0 0.00 0 635 4 0 0
t$perf-norm:sp_pay_from_customer 6862 6862 0 0.00 0 4637 11 0 0
t$perf-norm:sp_pay_to_supplier 5632 5632 0 0.00 0 7689 15 0 0
t$perf-norm:sp_reserve_write_off 9868 9868 0 0.00 0 7271 12 0 0
t$perf-norm:sp_supplier_invoice 5376 5376 0 0.00 0 3596 20 0 0
t$perf-norm:sp_supplier_order 5461 5461 0 0.00 0 11548 24 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 829 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 128 6 0 0
t$perf-norm:srv_make_invnt_saldo 414 414 0 0.00 0 1010 13 0 0
t$perf-norm:srv_make_money_saldo 745 745 0 0.00 0 7502 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 9992 9764 228 2.28 0 4792 17 0 228
v_all_customers 37272 37272 0 0.00 0 19 1 0 0
v_all_suppliers 29778 29778 0 0.00 0 19 1 0 0
v_all_wares 40864 40864 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 786 786 0 0.00 0 2735 16 0 0
v_cancel_client_order 3202 3202 0 0.00 0 5128 10 0 0
v_cancel_customer_prepayment 1356 1356 0 0.00 0 2505 9 0 0
v_cancel_customer_reserve 1318 1318 0 0.00 0 4292 8 0 0
v_cancel_payment_to_supplier 854 854 0 0.00 0 1034 8 0 0
v_cancel_supplier_invoice 794 786 8 1.00 0 4559 21 0 8
v_cancel_supplier_order 806 806 0 0.00 0 4782 15 0 0
v_cancel_write_off 1970 1970 0 0.00 0 6832 26 0 0
v_max_id_clo_ord 5452 5452 0 0.00 0 209 1 0 0
v_max_id_clo_res 955 955 0 0.00 0 1927 15 0 0
v_max_id_ord_sup 5365 5365 0 0.00 0 6133 6 0 0
v_max_non_paid_invoice 1560 1560 0 0.00 0 50 2 0 0
v_max_non_paid_realizn 3614 3614 0 0.00 0 4688 6 0 0
v_min_id_clo_ord 5452 5452 0 0.00 0 2607 5 0 0
v_min_id_clo_res 955 955 0 0.00 0 2577 12 0 0
v_min_id_ord_sup 5365 5365 0 0.00 0 9247 11 0 0
v_min_non_paid_invoice 5632 5632 0 0.00 0 4675 10 0 0
v_min_non_paid_realizn 6862 6862 0 0.00 0 6032 14 0 0
v_our_firm 10088 10088 0 0.00 0 21 1 0 0
v_reserve_write_off 19736 19736 0 0.00 0 6438 7 0 0
x_lock_depdocs_on_canc_invoice 11347 11347 0 0.00 0 13490 126 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 35630 513 0 0
x_make_qstorno_1000_1200 5461 5461 0 0.00 2 38541 1355 0 0
x_make_qstorno_1200_2000 5376 5376 0 0.00 3 136911 3741 0 0
x_make_qstorno_2100_3300 452381 452381 0 0.00 0 96428 174 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-03-2021 13:27:32 to 13-03-2021 13:27:36.

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
10:56:57 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:57:59 26892 8192000000 9730266432 10026983424 809662384 8.994 100 80 160 1 836473648 7532384 19278880
10:59:31 10755 8192000000 9764221776 10072092672 842177808 9.322 100 88 176 1 875710032 12172112 21360112
11:00:46 14186 8192000000 9804931200 10122436608 869698656 9.597 100 80 158 0 910263968 22361040 18204272
11:02:08 18358 8192000000 9852295280 10182279168 911740000 10.015 100 74 147 0 958588752 30566544 16282208
11:03:39 8335 8192000000 9910354560 10252308480 953671216 10.427 100 84 166 3 1018755696 47876832 17207648
11:04:50 5500 8192000000 9979165920 10334617600 987909664 10.761 100 81 162 0 1091857824 87252384 16695776
11:05:59 4003 8192000000 9995776272 10356428800 1017017168 11.043 100 81 162 1 1108620096 74768544 16834384
11:07:05 3406 8192000000 10005734464 10371731456 1043767728 11.301 100 84 168 3 1121614496 61368848 16477920
11:08:11 2230 8192000000 10020504416 10387484672 1059090192 11.448 100 91 181 2 1133226576 56214752 17921632
11:09:14 28049 8192000000 10006238112 10391085056 1067130320 11.525 100 57 110 1 1122483552 43798016 11555216
11:10:43 7005 8192000000 9987032976 10377654272 1079915184 11.647 100 42 83 0 1109938752 20685296 9338272
11:11:51 9544 8192000000 9992769280 10394279936 1086048352 11.705 100 19 38 1 1119212384 29233312 3930720
11:13:07 3661 8192000000 10028693968 10416480256 1100742736 11.845 100 42 84 1 1138693520 29324896 8625888
11:14:13 10989 8192000000 10026817824 10414481408 1097466032 11.814 100 78 156 2 1128871568 13647376 17758160
11:15:24 5680 8192000000 10017745072 10417389568 1097813104 11.817 100 39 78 1 1122984688 16863504 8308080
11:16:30 6134 8192000000 10006770416 10407866368 1099159584 11.830 100 30 60 1 1125125600 19063168 6902848
11:17:37 6472 8192000000 10009935392 10408923136 1092551536 11.767 99 26 54 2 1112488128 13695920 6240672
11:18:46 49801 8192000000 9984045664 10400337920 1076814352 11.617 100 34 68 1 1106928368 22599696 7514320
11:20:40 5852 8192000000 9976302384 10358214656 1018944640 11.062 97 55 110 2 1060155200 27941184 13269376
11:21:50 5697 8192000000 9942300640 10332098560 1017052816 11.044 99 37 74 2 1048925920 24130096 7743008
11:23:01 3485 8192000000 9928584992 10309771264 1010113792 10.976 99 47 96 0 1041167984 20392016 10662176
11:24:09 55740 8192000000 9942703280 10343116800 1021497616 11.086 98 24 47 1 1047842976 20835984 5509376
11:26:12 11698 8192000000 9981899792 10388340736 1071505344 11.566 100 41 82 3 1097255248 16676992 9072912
11:27:31 32224 8192000000 10047258048 10443505664 1095375792 11.794 100 46 91 1 1145087328 40445408 9266128
11:29:09 16154 8192000000 10063278064 10459385856 1111261104 11.944 100 50 98 0 1166052944 44708448 10083392
11:30:31 28455 8192000000 10143412576 10557050880 1124200144 12.067 100 48 93 2 1249219424 114992704 10026576
11:32:05 36275 8192000000 10157258704 10563518464 1138185088 12.198 100 57 114 1 1252529168 101903088 12440992
11:33:54 12614 8192000000 10198402928 10606469120 1147453936 12.286 100 68 136 2 1315044992 152693168 14897888
11:35:13 24012 8192000000 10233775264 10642931712 1154246400 12.349 100 71 142 0 1339290048 170341792 14701856
11:36:41 22458 8192000000 10198291376 10627072000 1160746544 12.410 100 38 76 3 1315409680 146107808 8555328
11:38:08 6244 8192000000 10233803296 10660847616 1169614176 12.493 100 69 135 0 1336058224 150906704 15537344
11:39:26 3187 8192000000 10255456640 10664267776 1174936064 12.543 99 78 154 1 1359513808 168354688 16223056
11:40:35 1795 8192000000 10310565328 10721505280 1187475536 12.660 100 80 159 0 1409581088 204732816 17372736
11:41:42 3582 8192000000 10327251872 10755940352 1182212416 12.611 100 77 149 3 1426844368 227692672 16939280
11:42:53 8311 8192000000 10310767952 10736668672 1178121072 12.573 99 62 124 0 1410818656 217990880 14706704
11:44:05 5053 8192000000 10348451440 10760175616 1157288976 12.378 98 78 154 2 1431952752 256222352 18441424
11:45:15 2875 8192000000 10407287728 10819158016 1169507632 12.492 100 81 162 0 1505083728 316322352 19253744
11:46:23 1164 8192000000 10381841072 10791841792 1178131616 12.573 100 75 148 3 1480126320 284767888 17226816
11:47:32 1377 8192000000 10346078000 10749747200 1170083744 12.498 100 90 175 0 1445119440 254825760 20209936
11:48:41 1743 8192000000 10376501936 10776924160 1148993888 12.300 99 78 156 2 1457283504 289847952 18441664
11:49:50 3221 8192000000 10327876000 10758758400 1149197280 12.302 100 72 144 2 1433650448 266564192 17888976
11:51:01 2247 8192000000 10361586720 10766872576 1148052048 12.291 100 95 189 0 1445715664 275884768 21778848
11:52:08 2712 8192000000 10366987472 10774966272 1160825200 12.411 100 62 122 3 1462482336 287332608 14324528
11:53:18 10561 8192000000 10446094048 10870349824 1139835424 12.214 97 46 92 1 1523924080 372208752 11879904
11:54:43 17659 8192000000 10521627520 10966204416 1162352768 12.425 100 41 80 1 1605924208 432727232 10844208
11:56:03 5800 8192000000 10542934704 10989371392 1150957280 12.318 100 44 88 0 1641163008 478487312 11718416
11:57:45 5797 8192000000 10531946384 10980626432 1133687648 12.156 99 47 91 2 1637778528 492239024 11851856
11:58:52 14564 8192000000 10233200208 10749313024 1143211008 12.246 100 20 40 0 1392733088 244399456 5122624
12:00:11 54879 8192000000 10111314304 10598436864 1155324592 12.359 100 19 38 1 1255660208 96470624 3864992
12:02:08 77093 8192000000 10079525744 10543992832 1121212560 12.038 98 11 22 0 1160996368 37337504 2446304
12:05:10 45648 8192000000 10093887712 10546647040 1139642400 12.212 100 22 44 0 1188812864 44546560 4623904
12:07:01 62328 8192000000 10122255136 10559164416 1146926496 12.281 100 22 43 0 1210659296 59421568 4311232
12:09:08 43887 8192000000 10153850576 10626580480 1153357056 12.341 100 10 20 0 1209431776 53857728 2216992
12:12:05 96652 8192000000 10149789168 10615099392 1145439552 12.267 99 22 44 1 1203520320 53738416 4342352
12:14:45 97280 8192000000 10069608800 10523664384 1117936864 12.007 97 16 32 0 1149519616 28029712 3553040
12:17:24 124524 8192000000 10093019040 10558173184 1146175472 12.274 100 38 74 3 1186734736 32697504 7861760
12:20:31 57824 8192000000 10120692592 10566352896 1134217248 12.161 98 31 62 1 1192990896 52466496 6307152
12:22:36 55868 8192000000 10118799072 10589716480 1135468400 12.173 98 23 46 1 1215216080 74049808 5697872
12:24:35 59233 8192000000 10094493840 10572935168 1146708576 12.279 99 16 32 0 1214195504 63803312 3683616
12:26:43 48638 8192000000 10147607088 10623078400 1143534208 12.249 98 12 24 0 1219789200 73902944 2352048
12:29:57 38061 8192000000 10086601568 10542284800 1149081968 12.301 99 17 35 0 1193380352 40474304 3824080
12:31:35 57356 8192000000 10170075216 10624700416 1143621584 12.250 98 20 40 1 1226206224 78659440 3925200
12:33:54 50428 8192000000 10143485456 10612154368 1107016736 11.904 96 17 34 1 1245269824 134565504 3687584
12:35:59 40042 8192000000 10086814016 10530062336 1110568912 11.938 97 28 57 0 1151312912 34637600 6106400
12:37:40 56141 8192000000 10075064560 10545967104 1084710704 11.692 95 19 38 1 1135529488 47123392 3695392
12:39:37 41183 8192000000 10126403104 10569588736 1130339632 12.125 99 32 64 1 1206532640 69442800 6750208
12:41:19 61017 8192000000 10070847248 10549788672 1129063776 12.113 99 17 34 0 1179341840 46701632 3576432
12:44:09 137442 8192000000 10066411936 10548236288 1112544128 11.956 99 28 56 1 1156004944 37996688 5464128
12:47:41 64111 8192000000 10082245776 10550607872 1102486912 11.861 98 8 16 0 1159119168 54852400 1779856
12:50:23 49351 8192000000 10042450512 10524930048 1106079296 11.895 99 10 20 0 1146087312 37878160 2129856
12:52:14 71581 8192000000 10054181952 10517041152 1090642944 11.749 98 16 32 0 1145512512 51354128 3515440
12:54:26 114484 8192000000 10053349456 10532954112 1080298576 11.650 97 9 18 0 1134901184 52709216 1893392
12:57:21 53538 8192000000 10081161632 10527637504 1082188176 11.668 96 25 51 0 1132335328 45060336 5086816
12:59:21 60206 8192000000 10065350784 10530406400 1115649536 11.986 99 20 40 1 1187644384 67699312 4295536
13:01:26 13872 8192000000 10079434256 10497683456 1112020928 11.952 99 56 109 1 1157982384 34156592 11804864
13:02:41 48280 8192000000 10090693328 10590879744 1124305360 12.068 100 26 52 0 1209757328 80255712 5196256
13:05:47 151210 8192000000 10096053136 10574389248 1125137392 12.075 99 10 20 0 1172844384 45522720 2184272
13:09:20 118235 8192000000 10097779392 10544934912 1120382928 12.031 98 24 48 0 1152786784 27607328 4796528
13:12:33 37201 8192000000 10061631104 10554359808 1137726288 12.194 100 4 8 0 1176249536 37674336 848912
13:14:27 73053 8192000000 10095111920 10565357568 1115839584 11.988 97 12 24 0 1170183664 51622224 2721856
13:16:40 65047 8192000000 10084393936 10581331968 1101694480 11.854 96 24 48 0 1167355696 60226000 5435216
13:19:20 75975 8192000000 10122255312 10595860480 1117780048 12.006 98 18 36 1 1184478064 62351712 4346304
13:21:36 40786 8192000000 10103027888 10553962496 1118226096 12.010 98 21 42 0 1169940752 47036384 4678272
13:23:22 46688 8192000000 10074555952 10506838016 1118577456 12.014 99 18 36 0 1167745488 45544720 3623312
13:25:14 85291 8192000000 10090682928 10559778816 1075989168 11.609 95 41 82 0 1129040912 45201104 7850640

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-03-2021 13:27:38 to 13-03-2021 13:27:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 114 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-03-2021 13:27:54 to 13-03-2021 13:27:56.

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          225506
MON$OLDEST_ACTIVE               228369
MON$OLDEST_SNAPSHOT             228369
MON$NEXT_TRANSACTION            228369
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                       2120576
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             785
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16: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 ---
:20: 
:21: mscs-test-161.budzdorov.ru    Sat Mar 13 13:26:56 2021
:22:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:23: 
:24: 
:25: mscs-test-161.budzdorov.ru    Sat Mar 13 13:26:56 2021
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:27: 
:28: 
:29: mscs-test-161.budzdorov.ru    Sat Mar 13 13:26:56 2021
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:31: 
:32: 
:33: mscs-test-161.budzdorov.ru    Sat Mar 13 13:26:56 2021
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:35: 
--- end of diff output ---

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

13.03.21 13:27:57. 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-03-13 13:27:59 No severe PSQL-related problems occured
13.03.21 13:28:01. End of report.