Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.09.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 15998228 kB
MemAvailable 30843296 kB
Buffers 0 kB
Cached 15661048 kB
SwapCached 340 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-09-08 07:49:01
DTS_END                         2021-09-08 07:49:01.4250
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(50:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/43772
TRN_ID                          242721
ATT_ID                          714
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: 9094.98 23665 1091398
customer order: creation 182.84 396 21941
customer order: refuse 15.54 318 1865
order to supplier: creation 47.73 1399 5728
order to supplier: removal 3.25 106452 390
invoice (draft): creation 49.17 3510 5900
invoice (draft): removal 47.86 954 5743
invoice accept: apply 44.73 20838 5368
invoice accept: cancel 43.22 936 5186
customer reserve: creation 4086.20 209 490344
customer reserve: removal 4270.45 63 512454
realization accept: apply 88.28 191 10593
realization accept: cancel 80.30 125 9636
payment from customer: creation 62.46 123 7495
payment from customer: removal 5.95 80 714
payment to supplier: creation 52.18 138 6262
payment to supplier: removal 3.53 116 424
service: total inventory turnovers 4.17 3532 500
service: total monetary turnovers 6.27 597 752
service: aggregate perf. data 0.83 21441 99
service: refresh index statistics 0.03 311884 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-09-2021 07:49:54 to 08-09-2021 07:49:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9629
TEST_TIME 2 7795
TEST_TIME 3 7858
TEST_TIME 4 7972
TEST_TIME 5 7880
TEST_TIME 6 8121
TEST_TIME 7 7864
TEST_TIME 8 8170
TEST_TIME 9 8080
TEST_TIME 10 8480
TEST_TIME 11 8184
TEST_TIME 12 8071
TEST_TIME 13 7869
TEST_TIME 14 8052
TEST_TIME 15 8064
TEST_TIME 16 8299
TEST_TIME 17 8643
TEST_TIME 18 8810
TEST_TIME 19 8997
TEST_TIME 20 8963
TEST_TIME 21 9034
TEST_TIME 22 9725
TEST_TIME 23 10264
TEST_TIME 24 10337
TEST_TIME 25 10191
TEST_TIME 26 10541
TEST_TIME 27 10559
TEST_TIME 28 11006
TEST_TIME 29 11270
TEST_TIME 30 11549
TEST_TIME 31 11382
TEST_TIME 32 11326
TEST_TIME 33 11148
TEST_TIME 34 10863
TEST_TIME 35 10714
TEST_TIME 36 10482
TEST_TIME 37 10621
TEST_TIME 38 10495
TEST_TIME 39 10397
TEST_TIME 40 10550
TEST_TIME 41 10520
TEST_TIME 42 10349
TEST_TIME 43 10251
TEST_TIME 44 10209
TEST_TIME 45 10041
TEST_TIME 46 9922
TEST_TIME 47 9965
TEST_TIME 48 9831
TEST_TIME 49 9649
TEST_TIME 50 9802
TEST_TIME 51 9770
TEST_TIME 52 9673
TEST_TIME 53 9743
TEST_TIME 54 9798
TEST_TIME 55 9784
TEST_TIME 56 9795
TEST_TIME 57 9771
TEST_TIME 58 9792
TEST_TIME 59 9737
TEST_TIME 60 9825
TEST_TIME 61 9777
TEST_TIME 62 9890
TEST_TIME 63 9773
TEST_TIME 64 9730
TEST_TIME 65 9684
TEST_TIME 66 9708
TEST_TIME 67 9618
TEST_TIME 68 9523
TEST_TIME 69 9551
TEST_TIME 70 9466
TEST_TIME 71 9506
TEST_TIME 72 9552
TEST_TIME 73 9580
TEST_TIME 74 9609
TEST_TIME 75 9539
TEST_TIME 76 9556
TEST_TIME 77 9570
TEST_TIME 78 9542
TEST_TIME 79 9634
TEST_TIME 80 9587
TEST_TIME 81 9503
TEST_TIME 82 9441
TEST_TIME 83 9393
TEST_TIME 84 9325
TEST_TIME 85 9337
TEST_TIME 86 9310
TEST_TIME 87 9300
TEST_TIME 88 9239
TEST_TIME 89 9222
TEST_TIME 90 9272
TEST_TIME 91 9225
TEST_TIME 92 9261
TEST_TIME 93 9290
TEST_TIME 94 9289
TEST_TIME 95 9277
TEST_TIME 96 9310
TEST_TIME 97 9274
TEST_TIME 98 9313
TEST_TIME 99 9383
TEST_TIME 100 9345
TEST_TIME 101 9352
TEST_TIME 102 9323
TEST_TIME 103 9269
TEST_TIME 104 9238
TEST_TIME 105 9240
TEST_TIME 106 9185
TEST_TIME 107 9160
TEST_TIME 108 9119
TEST_TIME 109 9092
TEST_TIME 110 9086
TEST_TIME 111 9077
TEST_TIME 112 9086
TEST_TIME 113 9084
TEST_TIME 114 9052
TEST_TIME 115 9063
TEST_TIME 116 9146
TEST_TIME 117 9168
TEST_TIME 118 9169
TEST_TIME 119 9174
TEST_TIME 120 9099

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-09-2021 07:49:58 to 08-09-2021 07:50:01.

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 1542722 1542722 0 0.00 0 19365 8 0 0
doc_list_biud 1542721 1542721 0 0.00 0 24277 27 0 0
sp_add_invoice_to_stock 5458 5368 90 1.64 47 237225 20839 0 90
sp_cancel_adding_invoice 5186 5186 0 0.00 6 97830 937 0 0
sp_cancel_client_order 1865 1865 0 0.00 5 9195 318 0 0
sp_cancel_customer_reserve 512454 512454 0 0.00 1 17084 63 0 0
sp_cancel_pay_from_customer 714 714 0 0.00 3 4179 80 0 0
sp_cancel_pay_to_supplier 424 424 0 0.00 3 12086 116 0 0
sp_cancel_supplier_invoice 5755 5743 12 0.20 3 21176 955 0 12
sp_cancel_supplier_order 390 390 0 0.00 8 1235810 106452 0 0
sp_cancel_write_off 9636 9636 0 0.00 1 9973 125 0 0
sp_client_order 21941 21941 0 0.00 4 20389 397 0 0
sp_customer_reserve 490344 490344 0 0.00 0 73922 210 0 0
sp_fill_shopping_cart 33567 33567 0 0.00 1 14674 41 0 0
sp_fill_shopping_cart_clo_res 490330 490330 0 0.00 0 18731 17 0 0
sp_get_clo_for_invoice 5354 5354 0 0.00 0 31472 384 0 0
sp_kill_cost_storno 15960 15960 0 0.00 0 6779 60 0 0
sp_kill_qstorno_ret_qs2qd 1040677 1040677 0 0.00 0 21169 35 0 0
sp_kill_qty_storno 551221 551221 0 0.00 0 24277 75 0 0
sp_lock_selected_doc 21949 21949 0 0.00 0 13216 4 0 0
sp_make_cost_storno 29704 29704 0 0.00 0 17643 59 0 0
sp_multiply_rows_for_pdistr 29704 29704 0 0.00 0 10175 34 0 0
sp_multiply_rows_for_qdistr 21941 21941 0 0.00 0 14919 209 0 0
sp_pay_from_customer 7495 7495 0 0.00 4 17829 124 0 0
sp_pay_to_supplier 6262 6262 0 0.00 4 20550 139 0 0
sp_payment_common 13757 13757 0 0.00 1 8919 25 0 0
sp_qd_handle_on_cancel_clo 1865 1865 0 0.00 0 9186 290 0 0
sp_qd_handle_on_invoice_upd_sts 10540 10540 0 0.00 0 24277 375 0 0
sp_qd_handle_on_reserve_upd_sts 10593 10593 0 0.00 0 21493 54 0 0
sp_reserve_write_off 10593 10593 0 0.00 7 21649 191 0 0
sp_supplier_invoice 5900 5900 0 0.00 7 90478 3510 0 0
sp_supplier_order 5728 5728 0 0.00 5 38375 1399 0 0
srv_aggregate_perf_data 99 99 0 0.00 3152 406633 21441 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 2763 115703 40731 0 0
srv_find_qd_qs_mism 1039527 1039527 0 0.00 0 19363 8 0 0
srv_make_invnt_saldo 500 500 0 0.00 46 83634 3532 0 0
srv_make_money_saldo 752 752 0 0.00 1 14705 597 0 0
srv_recalc_idx_stat 4 4 0 0.00 128125 519922 311885 0 0
t$perf-abend:sp_add_invoice_to_stock 90 90 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5368 5368 0 0.00 0 25438 178 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 0 3541 112 0 0
t$perf-norm:sp_cancel_client_order 1865 1865 0 0.00 0 11114 20 0 0
t$perf-norm:sp_cancel_customer_reserve 723 723 0 0.00 0 1050 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 714 714 0 0.00 0 2377 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 424 424 0 0.00 0 5702 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 1040 17 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 38610 1610 0 0
t$perf-norm:sp_cancel_write_off 1093 1093 0 0.00 0 4206 12 0 0
t$perf-norm:sp_client_order 21941 21941 0 0.00 0 9408 19 0 0
t$perf-norm:sp_customer_reserve 1063 1063 0 0.00 0 4501 9 0 0
t$perf-norm:sp_pay_from_customer 7495 7495 0 0.00 0 4728 11 0 0
t$perf-norm:sp_pay_to_supplier 6262 6262 0 0.00 0 10286 14 0 0
t$perf-norm:sp_reserve_write_off 10593 10593 0 0.00 0 8391 10 0 0
t$perf-norm:sp_supplier_invoice 5900 5900 0 0.00 0 10935 20 0 0
t$perf-norm:sp_supplier_order 5728 5728 0 0.00 0 6330 16 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 710 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 13 1 0 0
t$perf-norm:srv_make_invnt_saldo 500 500 0 0.00 0 2042 11 0 0
t$perf-norm:srv_make_money_saldo 752 752 0 0.00 0 2400 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 13 5 0 0
v_add_invoice_to_stock 10884 10704 180 1.65 0 13912 21 0 180
v_all_customers 39516 39516 0 0.00 0 19 1 0 0
v_all_suppliers 31986 31986 0 0.00 0 65 1 0 0
v_all_wares 43882 43882 0 0.00 0 66 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 1788 9 0 0
v_cancel_client_order 3730 3730 0 0.00 0 3974 6 0 0
v_cancel_customer_prepayment 1428 1428 0 0.00 0 534 4 0 0
v_cancel_customer_reserve 1446 1446 0 0.00 0 242 4 0 0
v_cancel_payment_to_supplier 848 848 0 0.00 0 12053 24 0 0
v_cancel_supplier_invoice 804 780 24 2.98 0 368 12 0 24
v_cancel_supplier_order 780 780 0 0.00 0 1058 5 0 0
v_cancel_write_off 2186 2186 0 0.00 0 5806 20 0 0
v_max_id_clo_ord 5728 5728 0 0.00 0 312 1 0 0
v_max_id_clo_res 1063 1063 0 0.00 0 3978 18 0 0
v_max_id_ord_sup 5898 5898 0 0.00 0 14667 6 0 0
v_max_non_paid_invoice 1895 1895 0 0.00 0 371 2 0 0
v_max_non_paid_realizn 4168 4168 0 0.00 0 1811 3 0 0
v_min_id_clo_ord 5728 5728 0 0.00 0 2852 6 0 0
v_min_id_clo_res 1063 1063 0 0.00 0 2691 8 0 0
v_min_id_ord_sup 5898 5898 0 0.00 0 2529 4 0 0
v_min_non_paid_invoice 6262 6262 0 0.00 0 8587 19 0 0
v_min_non_paid_realizn 7495 7495 0 0.00 0 8804 17 0 0
v_our_firm 11020 11020 0 0.00 0 15 1 0 0
v_reserve_write_off 21186 21186 0 0.00 0 12052 9 0 0
x_lock_depdocs_on_canc_invoice 10539 10539 0 0.00 0 15235 121 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 26469 444 0 0
x_make_qstorno_1000_1200 5728 5728 0 0.00 2 38370 1316 0 0
x_make_qstorno_1200_2000 5900 5900 0 0.00 3 90471 3362 0 0
x_make_qstorno_2100_3300 490344 490344 0 0.00 0 64211 190 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-09-2021 07:50:02 to 08-09-2021 07:50:06.

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:05 3 8192000000 8800477632 8812486656 5617264 0.068 2 2 3 0 5782528 103904 61360
05:20:06 25412 8192000000 9721327888 10018947072 796281408 8.859 100 86 172 0 824980864 7627152 21072304
05:21:47 20840 8192000000 9763214480 10073436160 838277952 9.282 100 88 175 0 872673920 13548496 20847472
05:23:12 18408 8192000000 9814179360 10138271744 875833536 9.658 100 83 166 1 919834496 24935792 19065168
05:24:32 15528 8192000000 9862753456 10198069248 915899664 10.056 100 75 146 2 966566144 35103616 15562864
05:25:51 8138 8192000000 9916779616 10259341312 956967008 10.459 100 83 165 1 1027893696 53197312 17729376
05:27:01 4490 8192000000 9956471936 10309509120 991447536 10.796 100 82 163 0 1075221552 66670400 17103616
05:28:07 2780 8192000000 10013900272 10372919296 1028226736 11.151 100 88 176 1 1128265568 82467216 17571616
05:29:11 1449 8192000000 10027654176 10397675520 1055974752 11.418 100 88 176 0 1137238992 62879232 18385008
05:30:13 26802 8192000000 10034899616 10410225664 1065999920 11.514 100 73 147 0 1152241632 71061392 15180320
05:31:40 6469 8192000000 9991207120 10381090816 1069652144 11.549 100 44 88 1 1107431312 28261008 9518160
05:32:47 7329 8192000000 10000742480 10395811840 1077247488 11.621 100 41 84 0 1122948464 37143072 8557904
05:33:58 50393 8192000000 10000775008 10392231936 1082090256 11.667 100 56 114 1 1102599456 7868640 12640560
05:35:49 4269 8192000000 10012815440 10418425856 1082916400 11.675 100 33 66 0 1117983200 28870272 6196528
05:36:55 8288 8192000000 10010385408 10421317632 1089303248 11.736 100 39 75 1 1119532816 21222848 9006720
05:38:04 6985 8192000000 10018921008 10415415296 1093406848 11.775 100 39 76 1 1131687120 30632656 7647616
05:39:11 47896 8192000000 9990733536 10403692544 1074035328 11.591 99 32 60 2 1112008096 31560944 6411824
05:41:00 4282 8192000000 9975160304 10367975424 1059464768 11.451 100 58 115 0 1085146400 12336688 13344944
05:42:06 5937 8192000000 9920682784 10305781760 990837392 10.790 98 42 86 2 1009116048 9223520 9055136
05:43:12 10332 8192000000 9915345376 10297581568 997587600 10.855 98 28 56 1 1026080928 21951376 6541952
05:44:23 15444 8192000000 9912924944 10316820480 992386224 10.805 97 11 21 1 1012685600 17901056 2398320
05:45:41 6243 8192000000 9976210080 10365026304 1040545552 11.270 100 53 108 0 1071094752 18491120 12058080
05:46:51 9875 8192000000 9977826928 10375081984 1056720000 11.425 100 35 70 0 1084002048 19031024 8251024
05:48:02 68163 8192000000 9978908816 10390802432 1064943936 11.504 100 18 36 0 1094230224 25785984 3500304
05:50:24 4729 8192000000 10030565984 10427211776 1099120480 11.829 100 47 92 1 1144269344 35820576 9328288
05:51:38 35290 8192000000 10075337904 10476838912 1115483328 11.984 100 51 102 1 1176718192 50460128 10774736
05:53:21 7577 8192000000 10114786704 10520391680 1134438832 12.163 100 60 119 0 1225538992 78806752 12293408
05:54:35 10845 8192000000 10139895824 10550702080 1147224480 12.283 100 51 102 0 1249780448 91315344 11240624
05:55:52 4693 8192000000 10169366320 10576601088 1139328432 12.209 99 72 142 1 1271482032 117163488 14990112
05:56:59 7919 8192000000 10223796640 10630299648 1162693712 12.428 100 68 136 0 1330031056 153011488 14325856
05:58:14 4886 8192000000 10204476688 10616901632 1137619488 12.193 98 61 122 2 1293926320 142997920 13308912
05:59:24 18842 8192000000 10215172800 10649079808 1160001376 12.403 100 68 134 1 1333710160 157966336 15742448
06:00:50 6379 8192000000 10230703728 10645417984 1164394768 12.444 100 43 87 0 1330537712 156598048 9544896
06:02:03 3670 8192000000 10266769184 10682023936 1155879552 12.365 100 62 122 2 1379106304 209300336 13926416
06:03:10 4590 8192000000 10285835600 10711285760 1156234352 12.368 99 65 128 1 1396808368 225567344 15006672
06:04:23 4681 8192000000 10296995792 10701496320 1158505824 12.389 100 81 161 0 1400323264 224336816 17480624
06:05:33 2977 8192000000 10348760032 10752516096 1164260912 12.443 100 85 169 0 1450075520 266983280 18831328
06:06:45 2763 8192000000 10349731168 10761252864 1155893840 12.365 100 82 162 0 1445472384 271634720 17943824
06:07:53 4201 8192000000 10348448240 10753630208 1143602928 12.249 99 78 156 1 1438507024 276822496 18081600
06:09:01 12166 8192000000 10334626112 10759729152 1100207296 11.840 96 41 81 1 1421803472 310220464 11375712
06:10:16 3166 8192000000 10408796608 10812403712 1129233168 12.114 100 76 151 0 1499582544 352153600 18195776
06:11:25 9770 8192000000 10364749904 10783121408 1138679920 12.203 100 38 76 2 1468634464 320225824 9728720
06:12:37 13884 8192000000 10262817600 10741030912 1134516176 12.164 100 26 51 2 1391165888 249856112 6793600
06:13:53 15863 8192000000 10221161968 10647945216 1123519120 12.060 99 35 71 2 1315309392 183204384 8585888
06:15:10 19263 8192000000 10248682496 10702962688 1136612752 12.184 100 30 59 0 1360581152 217016640 6951760
06:16:34 33635 8192000000 10251918704 10699505664 1119849744 12.026 99 16 33 1 1363165360 238501072 4814544
06:18:16 24864 8192000000 10086591824 10506432512 1122387168 12.050 100 41 83 0 1172358208 40080016 9891024
06:19:43 61672 8192000000 10054949296 10473308160 1115093568 11.981 99 23 43 0 1164373936 45084096 4196272
06:21:48 111851 8192000000 10080586896 10507403264 1127871200 12.101 99 21 42 1 1171015280 39014480 4129600
06:25:27 35184 8192000000 10131567872 10572271616 1128014048 12.103 99 18 35 0 1222178288 90473616 3690624
06:27:04 40920 8192000000 10129711376 10559115264 1130648944 12.127 99 33 63 1 1218626768 80613616 7364208
06:28:51 27999 8192000000 10152509360 10596839424 1125790816 12.082 99 43 85 1 1233458704 97937008 9730880
06:30:32 52276 8192000000 10071204240 10525736960 1138528048 12.202 100 11 22 1 1158298672 17302224 2468400
06:32:51 45268 8192000000 10129988192 10580578304 1156348688 12.369 100 23 46 0 1219340624 58109360 4882576
06:34:50 55690 8192000000 10096222624 10550616064 1140355392 12.219 99 23 45 1 1200982608 56029408 4597808
06:36:51 69955 8192000000 10105142944 10569068544 1112636272 11.957 96 16 32 1 1164533600 48906272 2991056
06:39:40 77862 8192000000 10063408640 10535067648 1098268432 11.821 96 12 24 0 1145110448 44650800 2191216
06:42:01 32248 8192000000 10149636704 10592059392 1136449296 12.182 99 47 94 2 1228465520 82103040 9913184
06:43:38 48333 8192000000 10081706224 10547879936 1121761808 12.044 98 19 38 0 1169841632 44340912 3738912
06:45:29 20780 8192000000 10090111344 10529992704 1133126528 12.151 100 41 82 0 1186201808 45277344 7797936
06:46:56 37000 8192000000 10071382256 10524921856 1127124928 12.094 99 23 46 0 1157965792 26164624 4676240
06:48:50 48729 8192000000 10120708944 10589925376 1130075168 12.122 99 30 60 0 1202275360 66059792 6140400
06:50:42 79713 8192000000 10055235072 10491682816 1085132224 11.696 95 19 39 0 1182327952 93514288 3681440
06:53:02 75340 8192000000 10128549280 10594226176 1115863744 11.988 98 32 64 2 1187197760 64306944 7027072
06:55:18 58126 8192000000 10081181104 10610384896 1096877328 11.808 97 10 20 0 1143929008 44827392 2224288
06:57:16 65074 8192000000 10096239296 10567041024 1096323728 11.803 96 19 37 1 1171695424 70772816 4598880
06:59:23 33805 8192000000 10132745568 10562568192 1089501568 11.738 96 45 91 2 1182225328 83279184 9444576
07:01:05 53733 8192000000 10106383968 10574307328 1106945088 11.903 99 21 41 0 1234256864 122512112 4799664
07:03:00 80927 8192000000 10052853264 10516353024 1084621312 11.691 97 15 30 0 1127162752 39199472 3341968
07:05:22 16763 8192000000 10122968272 10554085376 1110440752 11.937 99 51 102 0 1198627264 77935776 10250736
07:06:39 26871 8192000000 10050634128 10477174784 1064912832 11.503 96 29 58 0 1112066080 41980320 5172928
07:08:07 96770 8192000000 10031688704 10498228224 1095166176 11.792 98 29 58 0 1177720832 76585952 5968704
07:11:36 21297 8192000000 10061863056 10506416128 1118412128 12.012 100 30 60 0 1174888144 50880880 5595136
07:12:58 57797 8192000000 10104211152 10551734272 1130368304 12.125 100 8 16 0 1189508352 57360976 1779072
07:14:56 60207 8192000000 10075352592 10543398912 1122668880 12.052 99 15 29 1 1188922400 62986992 3266528
07:17:00 69394 8192000000 10088306752 10548879360 1132482704 12.145 100 13 26 0 1190139600 55076304 2580592
07:19:11 46134 8192000000 10102157744 10583552000 1139082784 12.207 100 29 59 0 1243770688 98775168 5912736
07:20:58 66033 8192000000 10108069360 10576113664 1108719264 11.920 96 23 46 0 1151662320 37978016 4965040
07:23:10 23010 8192000000 10059640944 10502111232 1134910128 12.168 99 35 67 3 1161973680 19423760 7639792
07:24:39 68921 8192000000 10048627904 10507485184 1082248448 11.669 95 15 30 0 1145620544 60530624 2841472
07:26:49 25981 8192000000 10155849168 10589372416 1126651248 12.090 99 40 80 1 1190653312 56047936 7954128
07:28:15 115701 8192000000 10074791904 10505269248 1068612688 11.539 94 19 37 1 1107811376 34658000 4540688
07:31:11 96775 8192000000 10128277888 10603839488 1120531968 12.032 98 41 82 0 1243032704 114160448 8340288
07:34:16 51699 8192000000 10042739072 10535944192 1123925296 12.064 100 3 6 0 1157003984 32471728 606960
07:36:08 60143 8192000000 10083895424 10562568192 1120428736 12.031 99 10 20 1 1156168704 33843760 1896208
07:38:08 59083 8192000000 10069305504 10519957504 1102667520 11.863 98 18 35 2 1147057216 40585440 3804256
07:40:27 52609 8192000000 10082518160 10527834112 1083312096 11.679 97 27 54 1 1155055232 66110416 5632720
07:42:20 47402 8192000000 10125281152 10586898432 1119771520 12.025 99 24 48 2 1206952016 82156144 5024352
07:44:15 108030 8192000000 10089835472 10592071680 1097251776 11.812 97 20 40 0 1212938208 111247072 4439360
07:47:13 20175 8192000000 10072608352 10501775360 1068348528 11.536 95 32 64 0 1110107632 35354544 6404560
07:48:36 41499 8192000000 9160313408 9454391296 291956384 3.441 27 6 12 0 318124752 25146048 1022320

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 90 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-09-2021 07:50:24 to 08-09-2021 07:50:26.

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          242976
MON$OLDEST_ACTIVE               242977
MON$OLDEST_SNAPSHOT             242977
MON$NEXT_TRANSACTION            242977
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                       2050464
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             818
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2585 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2585 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2585 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2585 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    Wed Sep  8 07:49:20 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'

08.09.21 07:50:27. 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-09-08 07:50:29 No severe PSQL-related problems occured
08.09.21 07:50:30. End of report.