Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.12.2021 07:48
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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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.33
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 16493172 kB
MemAvailable 31414932 kB
Buffers 0 kB
Cached 15148252 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.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-12-13 07:48:05
DTS_END                         2021-12-13 07:48:05.5510
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/41800
TRN_ID                          224883
ATT_ID                          618
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: 9037.18 22275 1084462
customer order: creation 161.19 413 19343
customer order: refuse 13.79 405 1655
order to supplier: creation 43.57 1486 5228
order to supplier: removal 3.50 132274 420
invoice (draft): creation 43.88 3810 5265
invoice (draft): removal 52.43 1061 6292
invoice accept: apply 39.14 22321 4697
invoice accept: cancel 47.18 945 5662
customer reserve: creation 3716.15 213 445938
customer reserve: removal 4634.36 77 556123
realization accept: apply 78.30 208 9396
realization accept: cancel 81.72 146 9807
payment from customer: creation 56.33 119 6760
payment from customer: removal 5.46 108 655
payment to supplier: creation 46.21 141 5545
payment to supplier: removal 3.09 104 371
service: total inventory turnovers 3.68 4094 441
service: total monetary turnovers 6.36 568 763
service: aggregate perf. data 0.80 18110 96
service: refresh index statistics 0.04 258893 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 13-12-2021 07:48:57 to 13-12-2021 07:49:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3235
TEST_TIME 2 4971
TEST_TIME 3 5417
TEST_TIME 4 4761
TEST_TIME 5 4897
TEST_TIME 6 5772
TEST_TIME 7 5732
TEST_TIME 8 5871
TEST_TIME 9 5798
TEST_TIME 10 5887
TEST_TIME 11 5921
TEST_TIME 12 6122
TEST_TIME 13 6251
TEST_TIME 14 7132
TEST_TIME 15 7331
TEST_TIME 16 7455
TEST_TIME 17 7453
TEST_TIME 18 7441
TEST_TIME 19 7283
TEST_TIME 20 7454
TEST_TIME 21 7534
TEST_TIME 22 7960
TEST_TIME 23 8132
TEST_TIME 24 8361
TEST_TIME 25 8866
TEST_TIME 26 9112
TEST_TIME 27 9633
TEST_TIME 28 10430
TEST_TIME 29 10957
TEST_TIME 30 11240
TEST_TIME 31 11020
TEST_TIME 32 10830
TEST_TIME 33 10719
TEST_TIME 34 10716
TEST_TIME 35 10557
TEST_TIME 36 10522
TEST_TIME 37 10310
TEST_TIME 38 10249
TEST_TIME 39 10120
TEST_TIME 40 10082
TEST_TIME 41 10066
TEST_TIME 42 10049
TEST_TIME 43 10163
TEST_TIME 44 10057
TEST_TIME 45 9949
TEST_TIME 46 9928
TEST_TIME 47 9805
TEST_TIME 48 9758
TEST_TIME 49 9568
TEST_TIME 50 9691
TEST_TIME 51 9613
TEST_TIME 52 9619
TEST_TIME 53 9635
TEST_TIME 54 9683
TEST_TIME 55 9690
TEST_TIME 56 9682
TEST_TIME 57 9768
TEST_TIME 58 9769
TEST_TIME 59 9756
TEST_TIME 60 9681
TEST_TIME 61 9834
TEST_TIME 62 9857
TEST_TIME 63 9929
TEST_TIME 64 9791
TEST_TIME 65 9732
TEST_TIME 66 9621
TEST_TIME 67 9656
TEST_TIME 68 9583
TEST_TIME 69 9537
TEST_TIME 70 9452
TEST_TIME 71 9402
TEST_TIME 72 9339
TEST_TIME 73 9345
TEST_TIME 74 9356
TEST_TIME 75 9318
TEST_TIME 76 9321
TEST_TIME 77 9414
TEST_TIME 78 9521
TEST_TIME 79 9469
TEST_TIME 80 9458
TEST_TIME 81 9482
TEST_TIME 82 9479
TEST_TIME 83 9514
TEST_TIME 84 9555
TEST_TIME 85 9460
TEST_TIME 86 9390
TEST_TIME 87 9350
TEST_TIME 88 9307
TEST_TIME 89 9281
TEST_TIME 90 9216
TEST_TIME 91 9215
TEST_TIME 92 9213
TEST_TIME 93 9192
TEST_TIME 94 9171
TEST_TIME 95 9145
TEST_TIME 96 9130
TEST_TIME 97 9184
TEST_TIME 98 9184
TEST_TIME 99 9202
TEST_TIME 100 9223
TEST_TIME 101 9273
TEST_TIME 102 9308
TEST_TIME 103 9291
TEST_TIME 104 9286
TEST_TIME 105 9260
TEST_TIME 106 9188
TEST_TIME 107 9117
TEST_TIME 108 9202
TEST_TIME 109 9163
TEST_TIME 110 9118
TEST_TIME 111 9063
TEST_TIME 112 9058
TEST_TIME 113 9083
TEST_TIME 114 9090
TEST_TIME 115 9062
TEST_TIME 116 9066
TEST_TIME 117 9062
TEST_TIME 118 9095
TEST_TIME 119 9111
TEST_TIME 120 9042

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-12-2021 07:49:01 to 13-12-2021 07:49:04.

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 1497643 1497643 0 0.00 0 40833 8 0 0
doc_list_biud 1497643 1497643 0 0.00 0 41573 36 0 0
sp_add_invoice_to_stock 4800 4697 103 2.14 31 248344 22321 0 103
sp_cancel_adding_invoice 5662 5662 0 0.00 4 57750 946 0 0
sp_cancel_client_order 1655 1655 0 0.00 5 13080 406 0 0
sp_cancel_customer_reserve 556123 556123 0 0.00 1 22755 77 0 0
sp_cancel_pay_from_customer 655 655 0 0.00 3 8943 108 0 0
sp_cancel_pay_to_supplier 371 371 0 0.00 3 6989 104 0 0
sp_cancel_supplier_invoice 6298 6292 6 0.09 1 29397 1061 0 6
sp_cancel_supplier_order 420 420 0 0.00 6 1266052 132275 0 0
sp_cancel_write_off 9807 9807 0 0.00 1 13981 146 0 0
sp_client_order 19343 19343 0 0.00 4 44931 414 0 0
sp_customer_reserve 445938 445938 0 0.00 0 105528 214 0 0
sp_fill_shopping_cart 29819 29819 0 0.00 1 23972 44 0 0
sp_fill_shopping_cart_clo_res 445923 445923 0 0.00 0 16716 17 0 0
sp_get_clo_for_invoice 4682 4682 0 0.00 0 23037 393 0 0
sp_kill_cost_storno 16495 16495 0 0.00 0 20798 72 0 0
sp_kill_qstorno_ret_qs2qd 1128765 1128765 0 0.00 0 29391 41 0 0
sp_kill_qty_storno 594037 594037 0 0.00 0 41573 89 0 0
sp_lock_selected_doc 19530 19530 0 0.00 0 10120 7 0 0
sp_make_cost_storno 26383 26383 0 0.00 0 40826 61 0 0
sp_multiply_rows_for_pdistr 26383 26383 0 0.00 0 11646 35 0 0
sp_multiply_rows_for_qdistr 19343 19343 0 0.00 0 44922 207 0 0
sp_pay_from_customer 6760 6760 0 0.00 4 28175 120 0 0
sp_pay_to_supplier 5545 5545 0 0.00 4 15717 141 0 0
sp_payment_common 12305 12305 0 0.00 1 6550 20 0 0
sp_qd_handle_on_cancel_clo 1655 1655 0 0.00 1 13065 349 0 0
sp_qd_handle_on_invoice_upd_sts 10344 10344 0 0.00 0 41573 428 0 0
sp_qd_handle_on_reserve_upd_sts 9396 9396 0 0.00 0 24571 60 0 0
sp_reserve_write_off 9396 9396 0 0.00 6 24986 209 0 0
sp_supplier_invoice 5265 5265 0 0.00 6 100209 3811 0 0
sp_supplier_order 5228 5228 0 0.00 6 31368 1487 0 0
srv_aggregate_perf_data 96 96 0 0.00 2381 153853 18110 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 931 164607 46694 0 0
srv_find_qd_qs_mism 1039183 1039183 0 0.00 0 20916 7 0 0
srv_make_invnt_saldo 441 441 0 0.00 91 112206 4094 0 0
srv_make_money_saldo 763 763 0 0.00 1 13699 568 0 0
srv_recalc_idx_stat 5 5 0 0.00 166926 338663 258894 0 0
t$perf-abend:sp_add_invoice_to_stock 103 103 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4698 4698 0 0.00 0 23974 206 0 0
t$perf-norm:sp_cancel_adding_invoice 360 360 0 0.00 0 2743 82 0 0
t$perf-norm:sp_cancel_client_order 1655 1655 0 0.00 0 23523 32 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 632 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 655 655 0 0.00 0 3885 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 371 371 0 0.00 0 1742 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 377 377 0 0.00 0 2531 21 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 22174 1830 0 0
t$perf-norm:sp_cancel_write_off 944 944 0 0.00 0 7704 21 0 0
t$perf-norm:sp_client_order 19343 19343 0 0.00 0 7876 22 0 0
t$perf-norm:sp_customer_reserve 948 948 0 0.00 0 6070 15 0 0
t$perf-norm:sp_pay_from_customer 6761 6761 0 0.00 0 8815 14 0 0
t$perf-norm:sp_pay_to_supplier 5545 5545 0 0.00 0 6364 9 0 0
t$perf-norm:sp_reserve_write_off 9397 9397 0 0.00 0 15082 12 0 0
t$perf-norm:sp_supplier_invoice 5265 5265 0 0.00 0 7645 21 0 0
t$perf-norm:sp_supplier_order 5228 5228 0 0.00 0 9319 23 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 531 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 170 5 0 0
t$perf-norm:srv_make_invnt_saldo 441 441 0 0.00 0 471 8 0 0
t$perf-norm:srv_make_money_saldo 763 763 0 0.00 0 1748 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 22 11 0 0
v_add_invoice_to_stock 9568 9362 206 2.15 0 10870 20 0 206
v_all_customers 35436 35436 0 0.00 0 106 1 0 0
v_all_suppliers 29364 29364 0 0.00 0 24 1 0 0
v_all_wares 38686 38686 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 720 720 0 0.00 0 3537 17 0 0
v_cancel_client_order 3310 3310 0 0.00 0 5495 12 0 0
v_cancel_customer_prepayment 1310 1310 0 0.00 0 2513 10 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 0 2709 11 0 0
v_cancel_payment_to_supplier 742 742 0 0.00 0 6983 23 0 0
v_cancel_supplier_invoice 766 754 12 1.56 0 1600 17 0 12
v_cancel_supplier_order 840 840 0 0.00 0 383 4 0 0
v_cancel_write_off 1888 1888 0 0.00 0 4463 16 0 0
v_max_id_clo_ord 5228 5228 0 0.00 0 1184 2 0 0
v_max_id_clo_res 948 948 0 0.00 0 7451 28 0 0
v_max_id_ord_sup 5247 5247 0 0.00 0 4198 4 0 0
v_max_non_paid_invoice 1338 1338 0 0.00 0 327 2 0 0
v_max_non_paid_realizn 3525 3525 0 0.00 0 1511 2 0 0
v_min_id_clo_ord 5228 5228 0 0.00 0 4170 7 0 0
v_min_id_clo_res 948 948 0 0.00 0 1152 3 0 0
v_min_id_ord_sup 5247 5247 0 0.00 0 7399 14 0 0
v_min_non_paid_invoice 5545 5545 0 0.00 0 6506 16 0 0
v_min_non_paid_realizn 6760 6760 0 0.00 0 4744 13 0 0
v_our_firm 9720 9720 0 0.00 0 16 1 0 0
v_reserve_write_off 18792 18792 0 0.00 0 5602 9 0 0
x_lock_depdocs_on_canc_invoice 11577 11577 0 0.00 0 26110 150 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 3666 384 0 0
x_make_qstorno_1000_1200 5228 5228 0 0.00 1 31358 1405 0 0
x_make_qstorno_1200_2000 5265 5265 0 0.00 3 99941 3640 0 0
x_make_qstorno_2100_3300 445938 445938 0 0.00 0 105513 194 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-12-2021 07:49:05 to 13-12-2021 07:49:09.

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:18:09 11 8192000000 8802163408 8814776320 7509984 0.091 3 1 2 0 7607600 61840 35776
05:19:10 40107 8192000000 9748681408 10050560000 816521216 9.063 100 91 181 0 847463792 8830784 22111792
05:20:55 13086 8192000000 9780985696 10090745856 854252688 9.443 100 91 182 1 889758352 14061040 21444624
05:22:16 15072 8192000000 9818364512 10138501120 881434368 9.714 100 87 174 0 927778736 26078368 20266000
05:23:36 14989 8192000000 9869650176 10202984448 918179920 10.078 100 82 164 0 979334736 42926272 18228544
05:24:54 10951 8192000000 9921265600 10266877952 954337872 10.434 100 77 154 1 1031579824 60807472 16434480
05:26:09 5205 8192000000 9959894000 10311176192 985566384 10.738 100 78 151 1 1067246800 66072208 15608208
05:27:17 3749 8192000000 9986194976 10346217472 1016257904 11.036 100 83 166 0 1102958304 69994928 16705472
05:28:23 2108 8192000000 10021859968 10386268160 1046748880 11.329 100 96 192 1 1135251904 68498352 20004672
05:29:26 2007 8192000000 10048859552 10418102272 1059105520 11.448 100 93 184 1 1155658256 77978736 18574000
05:30:29 11861 8192000000 10033417328 10411233280 1072401040 11.575 100 81 163 1 1149326336 59841216 17084080
05:31:42 6546 8192000000 9982515056 10383495168 1075135360 11.601 100 31 62 0 1102337504 19764112 7438032
05:32:49 10359 8192000000 10001623936 10401218560 1081994000 11.666 100 29 58 2 1106776896 18822704 5960192
05:34:02 20136 8192000000 9998702688 10393841664 1090734544 11.750 100 59 118 1 1112945248 8996384 13214320
05:35:23 2183 8192000000 10018168704 10421784576 1094153008 11.782 100 33 64 1 1137304864 36797664 6354192
05:36:27 11850 8192000000 10022222000 10436542464 1098813632 11.826 100 29 59 1 1134308160 28186464 7308064
05:37:41 7921 8192000000 10009633744 10402639872 1097784832 11.817 100 38 74 1 1117911488 10856192 9270464
05:38:50 18680 8192000000 9985624192 10397700096 1052400672 11.384 97 22 44 0 1083376144 26265856 4709616
05:40:08 7660 8192000000 9969337456 10366218240 1045991440 11.322 98 42 84 0 1065131360 9201344 9938576
05:41:20 4093 8192000000 9944895184 10318757888 1001943136 10.897 96 50 99 1 1027078448 13737952 11397360
05:42:26 58336 8192000000 9943689600 10333544448 959876608 10.488 93 24 48 0 970854240 6317360 4660272
05:44:28 9407 8192000000 9959922928 10347356160 1030937152 11.177 99 34 66 2 1060062000 22141392 6983456
05:45:40 7228 8192000000 9979380800 10377949184 1058424656 11.441 100 51 102 0 1091932560 22169184 11338720
05:46:50 11062 8192000000 9998219136 10393759744 1067491664 11.528 100 42 83 1 1111675984 34919280 9265040
05:48:05 84833 8192000000 10027679392 10419998720 1086054256 11.705 100 45 86 2 1120823760 26059392 8710112
05:50:35 18665 8192000000 10079213776 10479804416 1109603936 11.929 100 46 92 1 1187108432 68242720 9261776
05:51:59 35768 8192000000 10110436128 10518769664 1125937776 12.083 100 44 88 0 1203026320 67974560 9113984
05:53:44 17009 8192000000 10165195232 10575163392 1137983920 12.197 100 49 93 1 1266713488 117714960 11014608
05:55:14 22974 8192000000 10164682544 10585821184 1152565744 12.334 100 33 66 0 1277587808 116874672 8147392
05:56:53 10217 8192000000 10199612592 10610909184 1172735600 12.522 100 48 96 1 1295595152 112286944 10572608
05:58:10 3605 8192000000 10264275136 10666082304 1176649936 12.559 100 75 150 0 1366688704 173610160 16428608
05:59:19 2841 8192000000 10295027216 10698297344 1182304112 12.612 100 80 157 0 1403591056 203280192 18006752
06:00:29 5517 8192000000 10368528368 10770952192 1194612944 12.726 100 86 171 0 1468352608 254551840 19187824
06:01:39 2146 8192000000 10403855808 10806947840 1192632896 12.708 100 93 187 0 1501678944 287478464 21567584
06:02:46 4204 8192000000 10366795872 10793074688 1193928288 12.720 100 74 144 0 1467080224 256296528 16855408
06:03:55 3195 8192000000 10343276928 10766422016 1198497392 12.762 100 74 147 1 1441101248 225804528 16799328
06:05:04 930 8192000000 10403173008 10807308288 1200872368 12.784 100 89 177 1 1495834576 275380688 19581520
06:06:10 1353 8192000000 10439869056 10845212672 1190957968 12.692 100 87 172 2 1535015152 323624256 20432928
06:07:18 2512 8192000000 10482724512 10891198464 1195289200 12.733 100 79 156 1 1577734832 363732896 18712736
06:08:26 2300 8192000000 10510922048 10916687872 1184336848 12.631 99 85 170 0 1606260272 401552048 20371376
06:09:34 1799 8192000000 10555443008 10971766784 1185178224 12.638 100 79 158 2 1647953696 442680608 20094864
06:10:44 4613 8192000000 10560067792 11002589184 1188164896 12.666 100 68 135 0 1660739424 455164640 17409888
06:12:13 15483 8192000000 10572858960 11026006016 1177887728 12.570 100 43 86 0 1677013872 487281776 11844368
06:13:57 11005 8192000000 10481779152 10944983040 1159477104 12.398 100 38 76 0 1590227040 421058080 9691856
06:15:20 33162 8192000000 10369360032 10911592448 1132301824 12.143 99 20 40 4 1481540288 343820736 5417728
06:16:55 36644 8192000000 10097750784 10556497920 1138742400 12.204 99 32 63 1 1253439280 108024752 6672128
06:18:34 38656 8192000000 10067521696 10577887232 1135331856 12.172 100 11 22 1 1205868752 68142016 2394880
06:20:14 71874 8192000000 10072941280 10518110208 1107495088 11.909 98 36 72 2 1179756224 64565648 7695488
06:22:27 67511 8192000000 10076977936 10516578304 1110382768 11.936 98 48 94 0 1172924656 52994416 9547472
06:24:42 96854 8192000000 10072853552 10491715584 1081124784 11.658 95 23 46 1 1110146000 24206080 4815136
06:27:21 19824 8192000000 10102449664 10527072256 1114293344 11.973 99 49 98 2 1184313216 59469504 10550368
06:28:43 50572 8192000000 10098987072 10553528320 1110220624 11.935 99 36 72 0 1206913408 88283824 8408960
06:30:35 104166 8192000000 10063372896 10526965760 1105747264 11.892 99 14 28 0 1174648416 65805296 3095856
06:33:20 114385 8192000000 10051339504 10503471104 1094073056 11.781 97 24 46 2 1159930768 60853344 5004368
06:36:17 69822 8192000000 10093344464 10559168512 1126482384 12.088 100 11 22 0 1199161904 70521232 2158288
06:38:42 27866 8192000000 10034358528 10476736512 1123758448 12.062 99 24 49 2 1149056672 19357840 5940384
06:40:14 43942 8192000000 10082802816 10540621824 1133870576 12.158 100 22 46 0 1206042880 66979328 5192976
06:41:59 36503 8192000000 10077390160 10532192256 1129718416 12.119 99 18 36 1 1175781680 42459984 3603280
06:43:43 64660 8192000000 10122590000 10587426816 1133891456 12.158 99 28 56 1 1206248736 66780224 5577056
06:45:53 73366 8192000000 10078748992 10551394304 1134868240 12.167 99 16 32 1 1192875504 54714688 3292576
06:48:10 70134 8192000000 10118624080 10625499136 1133413936 12.154 98 19 38 0 1230195232 92895328 3885968
06:50:24 103666 8192000000 10078726144 10572935168 1096872448 11.808 95 19 38 0 1177504272 76443168 4188656
06:53:08 95006 8192000000 10084480064 10514149376 1119843168 12.026 98 47 90 1 1156668880 27834160 8991552
06:55:44 56382 8192000000 10044411040 10517598208 1109060848 11.924 98 17 34 1 1154197232 41788128 3348256
06:57:40 66950 8192000000 10041913024 10498310144 1078808976 11.636 95 11 22 0 1111705088 31127616 1768496
06:59:58 52703 8192000000 10066737392 10501738496 1091071488 11.753 96 20 40 0 1144615088 49270304 4273296
07:01:51 43193 8192000000 10080433472 10544648192 1110862672 11.941 98 21 41 0 1161604544 46425888 4315984
07:03:40 50683 8192000000 10150130720 10592104448 1126723264 12.090 99 30 61 1 1219174912 85193120 7258528
07:05:40 95598 8192000000 10075846592 10600906752 1127391072 12.097 99 10 20 0 1265725872 136203520 2131280
07:08:16 37143 8192000000 10158407776 10597580800 1113621120 11.967 98 40 80 1 1237513792 115749568 8143104
07:10:02 55406 8192000000 10105849552 10562457600 1124571744 12.070 99 34 69 0 1214789248 83051472 7166032
07:11:58 45451 8192000000 10096074928 10540724224 1119014912 12.018 99 29 56 2 1183860096 59119952 5725232
07:14:56 70767 8192000000 10068349568 10543951872 1117514256 12.004 99 27 54 0 1182571440 59653936 5403248
07:17:12 90246 8192000000 10066050640 10540900352 1114061024 11.971 98 11 22 0 1159019072 42596720 2361328
07:19:45 71061 8192000000 10063229424 10586087424 1102889392 11.865 97 9 18 0 1157321552 52557360 1874800
07:22:06 44344 8192000000 10068885184 10508787712 1104543808 11.881 97 16 32 0 1143642656 35974176 3124672
07:23:51 45909 8192000000 10127057776 10581958656 1137011392 12.187 99 28 56 0 1210626128 67348880 6265856
07:25:39 61357 8192000000 10080695856 10549489664 1110222848 11.935 97 14 28 0 1148255296 35295984 2736464
07:27:41 60771 8192000000 10137547504 10603134976 1082815424 11.674 94 17 34 1 1156131616 69491904 3824288
07:30:30 42790 8192000000 10093316672 10545184768 1128456064 12.107 99 28 56 1 1178949296 43968304 6524928
07:32:16 41625 8192000000 10097316704 10526273536 1123327456 12.058 99 34 68 0 1167279536 37283744 6668336
07:33:58 164607 8192000000 10075147360 10576576512 1133081632 12.150 99 29 58 0 1218291504 79193168 6016704
07:37:43 31081 8192000000 10046926128 10505408512 1122145760 12.047 99 10 20 1 1154001216 29563120 2292336
07:39:16 74977 8192000000 10122263760 10588086272 1141019728 12.225 100 17 34 1 1219716832 74929328 3767776
07:41:31 74276 8192000000 10073402368 10574884864 1119106800 12.019 98 11 22 0 1170523552 49138784 2277968
07:43:45 37624 8192000000 10102605488 10546216960 1134704480 12.166 99 28 56 0 1197313520 56525520 6083520
07:45:25 74741 8192000000 10084017792 10551521280 1137300816 12.190 99 15 30 0 1203315616 63206352 2808448
07:47:45 29022 8192000000 9046471920 9282994176 197753600 2.357 18 2 4 0 208071040 10030192 287248

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-12-2021 07:49:11 to 13-12-2021 07:49:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 103 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-12-2021 07:49:27 to 13-12-2021 07:49:29.

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          225197
MON$OLDEST_ACTIVE               225198
MON$OLDEST_SNAPSHOT             225198
MON$NEXT_TRANSACTION            225198
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                       2047520
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             775
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2685 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2685 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2685 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2685 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    Mon Dec 13 07:48:16 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
--- end of diff output ---

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

13.12.21 07:49:30. 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-12-13 07:49:32 No severe PSQL-related problems occured
13.12.21 07:49:33. End of report.