Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.08.2021 07:49
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 16180848 kB
MemAvailable 30909924 kB
Buffers 0 kB
Cached 15481380 kB
SwapCached 1324 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-08-28 07:49:26
DTS_END                         2021-08-28 07:49:26.2790
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/38826
TRN_ID                          235437
ATT_ID                          655
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: 8918.06 22471 1070167
customer order: creation 177.96 418 21355
customer order: refuse 15.10 372 1812
order to supplier: creation 48.39 1440 5807
order to supplier: removal 3.27 112436 392
invoice (draft): creation 46.72 3771 5606
invoice (draft): removal 50.66 911 6079
invoice accept: apply 42.13 20537 5055
invoice accept: cancel 44.91 846 5389
customer reserve: creation 3829.36 207 459523
customer reserve: removal 4361.98 66 523438
realization accept: apply 85.15 176 10218
realization accept: cancel 80.93 131 9711
payment from customer: creation 60.68 109 7282
payment from customer: removal 5.81 78 697
payment to supplier: creation 50.47 136 6056
payment to supplier: removal 3.50 73 420
service: total inventory turnovers 3.63 3952 435
service: total monetary turnovers 6.52 558 782
service: aggregate perf. data 0.88 14700 106
service: refresh index statistics 0.03 288497 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-08-2021 07:50:10 to 28-08-2021 07:50:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2223
TEST_TIME 2 4394
TEST_TIME 3 4981
TEST_TIME 4 5711
TEST_TIME 5 5870
TEST_TIME 6 5468
TEST_TIME 7 5318
TEST_TIME 8 5059
TEST_TIME 9 5170
TEST_TIME 10 5277
TEST_TIME 11 5144
TEST_TIME 12 5583
TEST_TIME 13 5708
TEST_TIME 14 5751
TEST_TIME 15 6023
TEST_TIME 16 6496
TEST_TIME 17 7355
TEST_TIME 18 7455
TEST_TIME 19 7449
TEST_TIME 20 7616
TEST_TIME 21 8058
TEST_TIME 22 8597
TEST_TIME 23 9262
TEST_TIME 24 9295
TEST_TIME 25 9725
TEST_TIME 26 10183
TEST_TIME 27 10084
TEST_TIME 28 10218
TEST_TIME 29 10060
TEST_TIME 30 9844
TEST_TIME 31 9828
TEST_TIME 32 9764
TEST_TIME 33 9638
TEST_TIME 34 9557
TEST_TIME 35 9493
TEST_TIME 36 9389
TEST_TIME 37 9421
TEST_TIME 38 9718
TEST_TIME 39 9673
TEST_TIME 40 9594
TEST_TIME 41 9558
TEST_TIME 42 9520
TEST_TIME 43 9350
TEST_TIME 44 9396
TEST_TIME 45 9272
TEST_TIME 46 9209
TEST_TIME 47 9116
TEST_TIME 48 9073
TEST_TIME 49 8949
TEST_TIME 50 9091
TEST_TIME 51 9188
TEST_TIME 52 9218
TEST_TIME 53 9264
TEST_TIME 54 9319
TEST_TIME 55 9303
TEST_TIME 56 9330
TEST_TIME 57 9349
TEST_TIME 58 9302
TEST_TIME 59 9470
TEST_TIME 60 9400
TEST_TIME 61 9286
TEST_TIME 62 9264
TEST_TIME 63 9210
TEST_TIME 64 9142
TEST_TIME 65 9063
TEST_TIME 66 9009
TEST_TIME 67 9090
TEST_TIME 68 9072
TEST_TIME 69 9171
TEST_TIME 70 9121
TEST_TIME 71 9091
TEST_TIME 72 9118
TEST_TIME 73 9313
TEST_TIME 74 9321
TEST_TIME 75 9309
TEST_TIME 76 9243
TEST_TIME 77 9294
TEST_TIME 78 9364
TEST_TIME 79 9272
TEST_TIME 80 9163
TEST_TIME 81 9110
TEST_TIME 82 9097
TEST_TIME 83 9116
TEST_TIME 84 9098
TEST_TIME 85 9069
TEST_TIME 86 9077
TEST_TIME 87 9032
TEST_TIME 88 8987
TEST_TIME 89 8949
TEST_TIME 90 8955
TEST_TIME 91 8907
TEST_TIME 92 8970
TEST_TIME 93 9017
TEST_TIME 94 9002
TEST_TIME 95 9000
TEST_TIME 96 9011
TEST_TIME 97 9112
TEST_TIME 98 9084
TEST_TIME 99 9045
TEST_TIME 100 9159
TEST_TIME 101 9191
TEST_TIME 102 9184
TEST_TIME 103 9139
TEST_TIME 104 9070
TEST_TIME 105 9038
TEST_TIME 106 9023
TEST_TIME 107 8965
TEST_TIME 108 8967
TEST_TIME 109 8922
TEST_TIME 110 8923
TEST_TIME 111 8894
TEST_TIME 112 8861
TEST_TIME 113 8924
TEST_TIME 114 8905
TEST_TIME 115 8885
TEST_TIME 116 8908
TEST_TIME 117 8915
TEST_TIME 118 8930
TEST_TIME 119 8967
TEST_TIME 120 8918

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-08-2021 07:50:14 to 28-08-2021 07:50:17.

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 1495223 1495223 0 0.00 0 15594 8 0 0
doc_list_biud 1495218 1495218 0 0.00 0 78227 30 0 0
sp_add_invoice_to_stock 5202 5055 147 2.82 32 195196 20537 0 147
sp_cancel_adding_invoice 5389 5389 0 0.00 5 49778 847 0 0
sp_cancel_client_order 1812 1812 0 0.00 5 9117 373 0 0
sp_cancel_customer_reserve 523438 523438 0 0.00 1 28643 66 0 0
sp_cancel_pay_from_customer 697 697 0 0.00 3 4481 78 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 2350 73 0 0
sp_cancel_supplier_invoice 6089 6079 10 0.16 2 78233 911 0 10
sp_cancel_supplier_order 392 392 0 0.00 5 1150228 112436 0 0
sp_cancel_write_off 9711 9711 0 0.00 1 8766 132 0 0
sp_client_order 21355 21355 0 0.00 4 15275 419 0 0
sp_customer_reserve 459523 459523 0 0.00 0 57084 208 0 0
sp_fill_shopping_cart 32747 32747 0 0.00 1 21965 45 0 0
sp_fill_shopping_cart_clo_res 459523 459523 0 0.00 0 15628 17 0 0
sp_get_clo_for_invoice 5055 5055 0 0.00 0 16947 365 0 0
sp_kill_cost_storno 16217 16217 0 0.00 0 10137 58 0 0
sp_kill_qstorno_ret_qs2qd 1063058 1063058 0 0.00 0 78226 36 0 0
sp_kill_qty_storno 562090 562090 0 0.00 0 78227 78 0 0
sp_lock_selected_doc 21171 21171 0 0.00 0 10089 6 0 0
sp_make_cost_storno 28611 28611 0 0.00 0 9750 52 0 0
sp_multiply_rows_for_pdistr 28611 28611 0 0.00 0 6774 31 0 0
sp_multiply_rows_for_qdistr 21355 21355 0 0.00 0 15207 210 0 0
sp_pay_from_customer 7282 7282 0 0.00 4 13269 110 0 0
sp_pay_to_supplier 6056 6056 0 0.00 5 9726 137 0 0
sp_payment_common 13338 13338 0 0.00 1 5645 17 0 0
sp_qd_handle_on_cancel_clo 1812 1812 0 0.00 0 9110 341 0 0
sp_qd_handle_on_invoice_upd_sts 10441 10441 0 0.00 0 29898 359 0 0
sp_qd_handle_on_reserve_upd_sts 10217 10217 0 0.00 0 14854 50 0 0
sp_reserve_write_off 10218 10218 0 0.00 7 15166 177 0 0
sp_supplier_invoice 5606 5606 0 0.00 7 103626 3772 0 0
sp_supplier_order 5807 5807 0 0.00 6 71850 1441 0 0
srv_aggregate_perf_data 106 106 0 0.00 2892 169943 14700 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1361 143847 41965 0 0
srv_find_qd_qs_mism 1021437 1021437 0 0.00 0 15594 7 0 0
srv_make_invnt_saldo 435 435 0 0.00 39 126122 3953 0 0
srv_make_money_saldo 782 782 0 0.00 1 15274 558 0 0
srv_recalc_idx_stat 4 4 0 0.00 130168 546786 288498 0 0
t$perf-abend:sp_add_invoice_to_stock 147 147 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5055 5055 0 0.00 0 28017 208 0 0
t$perf-norm:sp_cancel_adding_invoice 377 377 0 0.00 0 4914 95 0 0
t$perf-norm:sp_cancel_client_order 1812 1812 0 0.00 0 3112 13 0 0
t$perf-norm:sp_cancel_customer_reserve 710 710 0 0.00 0 5947 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 697 697 0 0.00 0 2460 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 201 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 828 12 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 17845 1474 0 0
t$perf-norm:sp_cancel_write_off 1074 1074 0 0.00 0 3372 11 0 0
t$perf-norm:sp_client_order 21355 21355 0 0.00 0 16508 20 0 0
t$perf-norm:sp_customer_reserve 1046 1046 0 0.00 0 1404 5 0 0
t$perf-norm:sp_pay_from_customer 7282 7282 0 0.00 0 10110 11 0 0
t$perf-norm:sp_pay_to_supplier 6056 6056 0 0.00 0 2952 7 0 0
t$perf-norm:sp_reserve_write_off 10218 10218 0 0.00 0 9954 12 0 0
t$perf-norm:sp_supplier_invoice 5606 5606 0 0.00 0 8586 20 0 0
t$perf-norm:sp_supplier_order 5807 5807 0 0.00 0 6148 15 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 657 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 246 5 0 0
t$perf-norm:srv_make_invnt_saldo 435 435 0 0.00 0 686 8 0 0
t$perf-norm:srv_make_money_saldo 782 782 0 0.00 0 695 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10398 10104 294 2.82 0 5695 18 0 294
v_all_customers 38952 38952 0 0.00 0 144 1 0 0
v_all_suppliers 31556 31556 0 0.00 0 112 1 0 0
v_all_wares 42704 42704 0 0.00 0 66 1 0 0
v_cancel_adding_invoice 754 754 0 0.00 0 1081 8 0 0
v_cancel_client_order 3624 3624 0 0.00 0 5874 5 0 0
v_cancel_customer_prepayment 1394 1394 0 0.00 0 4447 8 0 0
v_cancel_customer_reserve 1420 1420 0 0.00 0 4007 7 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 1770 8 0 0
v_cancel_supplier_invoice 860 840 20 2.32 0 3770 20 0 20
v_cancel_supplier_order 784 784 0 0.00 0 3584 10 0 0
v_cancel_write_off 2148 2148 0 0.00 0 5765 26 0 0
v_max_id_clo_ord 5805 5805 0 0.00 0 5825 3 0 0
v_max_id_clo_res 1046 1046 0 0.00 0 10645 26 0 0
v_max_id_ord_sup 5589 5589 0 0.00 0 6637 6 0 0
v_max_non_paid_invoice 1672 1672 0 0.00 0 182 2 0 0
v_max_non_paid_realizn 3841 3841 0 0.00 0 239 2 0 0
v_min_id_clo_ord 5805 5805 0 0.00 0 2625 7 0 0
v_min_id_clo_res 1046 1046 0 0.00 0 4683 19 0 0
v_min_id_ord_sup 5589 5589 0 0.00 0 8812 13 0 0
v_min_non_paid_invoice 6056 6056 0 0.00 0 5610 12 0 0
v_min_non_paid_realizn 7282 7282 0 0.00 0 5642 11 0 0
v_our_firm 10634 10634 0 0.00 0 20 1 0 0
v_reserve_write_off 20434 20434 0 0.00 0 5900 8 0 0
x_lock_depdocs_on_canc_invoice 11048 11048 0 0.00 0 16614 113 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 21553 464 0 0
x_make_qstorno_1000_1200 5807 5807 0 0.00 1 71835 1350 0 0
x_make_qstorno_1200_2000 5606 5606 0 0.00 3 103610 3608 0 0
x_make_qstorno_2100_3300 459523 459523 0 0.00 0 57084 188 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-08-2021 07:50:18 to 28-08-2021 07:50:22.

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
05:19:30 1 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:20:30 21156 8192000000 9717045760 10011672576 798944928 8.886 100 90 180 1 828500496 7910064 21645504
05:21:57 28126 8192000000 9762026624 10067918848 834456096 9.244 100 89 178 0 867480848 11728128 21296624
05:23:36 16816 8192000000 9802624256 10120650752 865507936 9.555 100 88 176 1 912857376 26361568 20987872
05:24:59 13490 8192000000 9864030928 10197032960 913218464 10.029 100 80 160 0 973387312 42026400 18142448
05:26:15 9643 8192000000 9908627984 10256646144 960686480 10.496 100 75 145 1 1026672848 50467984 15518384
05:27:29 5284 8192000000 9949125040 10304245760 999341328 10.872 100 73 144 1 1059689440 46290160 14057952
05:28:36 4256 8192000000 10009955264 10372239360 1019304864 11.065 100 82 164 0 1115681536 79241232 17135440
05:29:43 4068 8192000000 10012309392 10378039296 1043871888 11.302 100 81 158 1 1123567328 63853408 15842032
05:30:50 1443 8192000000 10027916672 10404368384 1062622656 11.482 100 90 181 1 1142075088 61042256 18410176
05:31:53 25039 8192000000 10068141328 10449723392 1074443712 11.594 100 82 164 1 1181345104 89861968 17039424
05:33:19 3894 8192000000 10014778224 10402361344 1085760928 11.702 100 63 126 2 1128112272 29179488 13171856
05:34:23 9393 8192000000 10006242432 10407841792 1092178672 11.763 100 30 59 2 1131212048 31926528 7106848
05:35:36 31654 8192000000 10017198976 10411900928 1095706560 11.797 100 50 100 1 1116803856 10008608 11088688
05:37:09 4646 8192000000 10023377232 10420957184 1103839600 11.874 100 50 101 1 1134940608 20082880 11018128
05:38:15 6773 8192000000 10023379104 10432741376 1104075088 11.876 100 34 67 2 1135658112 24098912 7484112
05:39:24 6176 8192000000 10024758752 10423762944 1097603568 11.815 99 45 90 0 1125327536 17806304 9917664
05:40:33 29239 8192000000 10033697664 10458816512 1101541968 11.852 100 25 50 1 1140375472 33962944 4870560
05:42:03 3445 8192000000 9974766864 10352525312 1033580400 11.203 97 58 112 3 1065735392 19433376 12721616
05:43:10 6767 8192000000 9929905264 10312261632 1012116000 10.996 98 23 46 2 1042820512 26107856 4596656
05:44:17 3602 8192000000 9928574272 10295414784 1003289424 10.910 98 52 101 3 1026263440 11327360 11646656
05:45:22 32990 8192000000 9929059408 10306637824 961831696 10.507 93 36 70 0 986943136 17579408 7532032
05:46:56 3496 8192000000 9960045744 10339033088 1040500128 11.269 100 55 106 1 1064331568 10754864 13076576
05:48:00 9569 8192000000 9969235424 10362998784 1055118656 11.410 100 43 85 0 1091594848 26856320 9619872
05:49:16 65719 8192000000 9993706256 10396467200 1079120016 11.639 100 40 80 0 1101034032 12979488 8934528
05:51:26 40888 8192000000 10060940176 10469064704 1099311248 11.831 100 56 112 0 1173040048 62833456 10895344
05:53:13 5230 8192000000 10088916448 10494918656 1122306576 12.049 100 54 108 0 1202932640 69345632 11280432
05:54:23 12505 8192000000 10098200080 10505961472 1134215184 12.161 100 52 103 0 1206797600 61670192 10912224
05:55:42 7059 8192000000 10163509920 10568015872 1148293104 12.293 100 57 114 0 1268493552 108348304 11852144
05:56:59 10485 8192000000 10187223392 10605314048 1154007872 12.347 100 41 82 0 1289886112 126853552 9024688
05:58:19 12743 8192000000 10234379408 10657288192 1165478592 12.455 100 44 86 0 1345908720 170874016 9556112
05:59:35 9383 8192000000 10263773120 10681544704 1163879632 12.440 99 51 100 2 1365215520 189563968 11771920
06:00:52 10755 8192000000 10331147088 10753384448 1178412016 12.575 100 66 132 0 1449741712 256287744 15041952
06:02:09 1683 8192000000 10357586048 10767040512 1187825024 12.663 100 86 171 0 1454537168 247443360 19268784
06:03:15 1466 8192000000 10386997392 10798120960 1181945072 12.608 100 88 174 0 1487748144 285310688 20492384
06:04:23 2176 8192000000 10394991152 10816499712 1188697696 12.671 100 77 154 0 1499857952 293054112 18106144
06:05:31 1359 8192000000 10377042032 10795679744 1180242448 12.592 100 89 175 1 1476367520 276428448 19696624
06:06:36 2448 8192000000 10411283296 10825334784 1165449408 12.454 100 74 147 0 1511669392 329237248 16982736
06:07:42 2788 8192000000 10344811632 10757758976 1132908768 12.149 97 78 155 1 1425806880 274889888 18008224
06:08:52 3513 8192000000 10385930480 10803761152 1144274736 12.256 100 59 113 0 1483936048 325470672 14190640
06:10:01 1615 8192000000 10392035600 10818101248 1138304304 12.200 100 84 167 2 1487444736 330322560 18817872
06:11:07 1971 8192000000 10364262560 10792660992 1141107424 12.226 100 65 126 5 1461285872 304774960 15403488
06:12:13 14276 8192000000 10251120000 10710953984 1107614080 11.910 100 25 50 4 1366162976 252301104 6247792
06:13:31 23117 8192000000 10175119200 10610393088 1094827552 11.789 99 18 36 0 1290716016 191587424 4301040
06:15:06 27438 8192000000 10142733280 10607058944 1116156528 11.991 100 12 24 1 1244610048 125428784 3024736
06:16:37 20762 8192000000 10078185760 10495774720 1099418128 11.832 98 30 60 0 1163931552 57351104 7162320
06:17:59 98260 8192000000 10053826048 10496294912 1088488496 11.728 98 37 74 2 1165931472 69636656 7806320
06:20:58 2560 8192000000 10069833280 10483204096 1120450096 12.031 100 78 157 1 1167582464 31729280 15403088
06:22:07 65720 8192000000 10077668432 10515554304 1118711360 12.015 99 19 38 3 1195621808 72771968 4138480
06:24:17 27640 8192000000 10151988416 10567450624 1111283520 11.945 98 39 78 0 1228188416 107472272 9432624
06:25:56 52182 8192000000 10157646784 10616397824 1119973568 12.027 99 34 67 0 1275338800 147487536 7877696
06:28:04 8217 8192000000 10069061904 10570174464 1116037536 11.990 99 16 32 1 1178878208 59364352 3476320
06:29:14 47291 8192000000 10078989984 10529415168 1135846784 12.176 100 16 32 0 1193497392 54175920 3474688
06:32:17 53702 8192000000 10100704560 10544975872 1142054928 12.235 100 15 29 1 1196242336 50007328 4180080
06:34:18 21393 8192000000 10065752784 10531467264 1134840672 12.167 100 37 74 0 1176335920 33047312 8447936
06:35:42 38206 8192000000 10087636336 10550984704 1116184880 11.991 98 18 36 0 1191180896 71225792 3770224
06:37:50 48442 8192000000 10149273888 10616578048 1128235968 12.105 99 13 27 1 1252874096 121603824 3034304
06:39:39 58129 8192000000 10095853168 10572161024 1125722352 12.081 99 10 20 0 1206574064 78536656 2315056
06:41:44 11615 8192000000 10100169040 10525437952 1133699376 12.156 100 52 102 1 1182663888 38529632 10434880
06:42:59 56192 8192000000 10065617888 10534825984 1096813344 11.807 96 29 57 3 1166225904 63770560 5642000
06:44:55 30455 8192000000 10080934096 10547863552 1101869616 11.855 97 36 72 1 1148395872 39366960 7159296
06:46:27 38847 8192000000 10107076528 10576084992 1120348640 12.030 99 40 80 1 1195498096 67014960 8134496
06:48:16 77751 8192000000 10065305360 10483367936 1116198384 11.991 98 40 77 0 1142493584 18227024 8068176
06:50:34 143846 8192000000 10087538784 10578124800 1112148640 11.953 97 29 56 2 1203271968 85316848 5806480
06:53:58 67329 8192000000 10124756832 10587365376 1121930688 12.045 99 14 28 1 1205059584 80203472 2925424
06:56:06 46278 8192000000 10076619776 10515435520 1087315744 11.717 97 13 26 0 1144053456 53889808 2847904
06:57:53 42565 8192000000 10067402384 10511122432 1112724624 11.958 99 22 44 0 1166406432 48976288 4705520
06:59:54 66103 8192000000 10139657824 10589581312 1091682144 11.759 97 31 62 0 1189954688 91419392 6853152
07:02:06 42313 8192000000 10076672736 10603466752 1115037168 11.980 100 9 16 0 1195783712 78877120 1869424
07:04:02 60820 8192000000 10045477824 10483367936 1087674528 11.721 97 9 18 0 1130469040 41182320 1612192
07:06:04 5559 8192000000 10098363264 10516463616 1122275056 12.048 100 62 123 2 1179964096 44511536 13177504
07:07:14 57892 8192000000 10064402576 10557657088 1109108192 11.924 98 18 36 0 1167714688 54919216 3687280
07:09:12 96853 8192000000 10070391664 10504560640 1098236384 11.821 97 29 58 3 1147761440 43472912 6052144
07:12:02 82968 8192000000 10087446016 10628550656 1115735584 11.987 100 9 18 0 1207643536 89987792 1920160
07:14:26 40068 8192000000 10077623088 10530054144 1109101264 11.924 99 10 20 0 1178288464 67169408 2017792
07:16:14 66402 8192000000 10045064160 10510958592 1113479312 11.965 99 13 25 1 1159248800 42874416 2895072
07:19:40 73058 8192000000 10102480416 10543927296 1121759440 12.044 98 31 62 2 1199846048 71690336 6396272
07:21:54 74090 8192000000 10095097936 10573680640 1112075840 11.952 97 19 38 1 1165243488 49035696 4131952
07:24:14 58917 8192000000 10127981232 10594689024 1150294304 12.312 100 22 44 1 1233011072 78426816 4289952
07:26:16 36324 8192000000 10089714496 10540339200 1153988176 12.347 100 32 61 0 1191523600 30194304 7341120
07:27:58 42387 8192000000 10144249344 10570268672 1131083952 12.132 98 41 82 1 1196523280 57156240 8283088
07:29:49 99499 8192000000 10130563680 10622009344 1135955520 12.177 98 10 20 0 1204753216 66860992 1936704
07:32:34 126498 8192000000 10139501952 10581032960 1155651936 12.363 100 43 86 1 1223131424 58066288 9413200
07:35:43 61752 8192000000 10087924016 10557526016 1108939136 11.922 97 8 16 0 1185272688 74840016 1493536
07:37:45 62999 8192000000 10084644336 10540089344 1132760544 12.147 99 14 28 0 1191780912 56092496 2927872
07:39:51 34734 8192000000 10071720704 10522841088 1123706512 12.062 99 34 68 0 1144526576 13653744 7166320
07:41:29 81459 8192000000 10087966544 10569244672 1104600624 11.881 97 27 54 0 1183537136 73125232 5811280
07:44:13 31562 8192000000 10060772096 10491310080 1085346032 11.698 97 27 53 4 1127426224 36213440 5866752
07:45:54 61746 8192000000 10075237264 10507960320 1055456960 11.413 94 26 52 0 1140686496 80178160 5051376
07:47:59 89623 8192000000 9966262000 10410418176 1008061184 10.957 90 18 36 0 1064562496 53171856 3329456

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-08-2021 07:50:24 to 28-08-2021 07:50:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 147 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-08-2021 07:50:40 to 28-08-2021 07:50:42.

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          234922
MON$OLDEST_ACTIVE               235577
MON$OLDEST_SNAPSHOT             235577
MON$NEXT_TRANSACTION            235577
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                       2032672
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             802
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2574 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2574 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2574 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2574 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sat Aug 28 07:49:36 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Sat Aug 28 07:49:36 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Sat Aug 28 07:49:36 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Sat Aug 28 07:49:36 2021
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:21: 
--- end of diff output ---

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

28.08.21 07:50:43. 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-08-28 07:50:46 No severe PSQL-related problems occured
28.08.21 07:50:47. End of report.