Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.03.2021 04:27
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID df9f488d08424df294c459d6c283e9c3
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-03-04 16:07
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.26
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16757200 kB
MemAvailable 31487424 kB
Buffers 20 kB
Cached 14812092 kB
SwapCached 0 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.0
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-03-17 04:26:57
DTS_END                         2021-03-17 04:26:57.1520
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/54968
TRN_ID                          234322
ATT_ID                          682
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: 9055.03 21063 1086604
customer order: creation 167.89 414 20147
customer order: refuse 14.38 359 1725
order to supplier: creation 44.86 1380 5383
order to supplier: removal 3.31 113247 397
invoice (draft): creation 45.69 3552 5483
invoice (draft): removal 50.63 858 6076
invoice accept: apply 41.74 17526 5009
invoice accept: cancel 46.33 868 5559
customer reserve: creation 3805.39 172 456647
customer reserve: removal 4545.86 66 545503
realization accept: apply 82.15 164 9858
realization accept: cancel 79.53 126 9543
payment from customer: creation 59.43 100 7131
payment from customer: removal 6.12 98 734
payment to supplier: creation 47.56 118 5707
payment to supplier: removal 3.53 88 424
service: total inventory turnovers 3.86 3261 463
service: total monetary turnovers 5.90 655 708
service: aggregate perf. data 0.86 12744 103
service: refresh index statistics 0.03 265463 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 17-03-2021 04:27:51 to 17-03-2021 04:27:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2437
TEST_TIME 2 4694
TEST_TIME 3 5479
TEST_TIME 4 5440
TEST_TIME 5 5521
TEST_TIME 6 5550
TEST_TIME 7 5558
TEST_TIME 8 5393
TEST_TIME 9 5768
TEST_TIME 10 5595
TEST_TIME 11 6140
TEST_TIME 12 6128
TEST_TIME 13 6345
TEST_TIME 14 6637
TEST_TIME 15 6687
TEST_TIME 16 7302
TEST_TIME 17 7391
TEST_TIME 18 7475
TEST_TIME 19 7822
TEST_TIME 20 7974
TEST_TIME 21 8037
TEST_TIME 22 8361
TEST_TIME 23 8862
TEST_TIME 24 9497
TEST_TIME 25 10223
TEST_TIME 26 10960
TEST_TIME 27 10905
TEST_TIME 28 10733
TEST_TIME 29 10728
TEST_TIME 30 10446
TEST_TIME 31 10401
TEST_TIME 32 10180
TEST_TIME 33 10081
TEST_TIME 34 10011
TEST_TIME 35 10083
TEST_TIME 36 9996
TEST_TIME 37 10037
TEST_TIME 38 9884
TEST_TIME 39 9927
TEST_TIME 40 9868
TEST_TIME 41 9834
TEST_TIME 42 9712
TEST_TIME 43 9658
TEST_TIME 44 9566
TEST_TIME 45 9535
TEST_TIME 46 9664
TEST_TIME 47 9608
TEST_TIME 48 9691
TEST_TIME 49 9685
TEST_TIME 50 9897
TEST_TIME 51 9823
TEST_TIME 52 9660
TEST_TIME 53 9545
TEST_TIME 54 9497
TEST_TIME 55 9402
TEST_TIME 56 9297
TEST_TIME 57 9214
TEST_TIME 58 9152
TEST_TIME 59 9231
TEST_TIME 60 9206
TEST_TIME 61 9293
TEST_TIME 62 9280
TEST_TIME 63 9281
TEST_TIME 64 9301
TEST_TIME 65 9283
TEST_TIME 66 9394
TEST_TIME 67 9533
TEST_TIME 68 9554
TEST_TIME 69 9563
TEST_TIME 70 9550
TEST_TIME 71 9505
TEST_TIME 72 9444
TEST_TIME 73 9337
TEST_TIME 74 9313
TEST_TIME 75 9253
TEST_TIME 76 9166
TEST_TIME 77 9104
TEST_TIME 78 9053
TEST_TIME 79 9066
TEST_TIME 80 9099
TEST_TIME 81 9097
TEST_TIME 82 9243
TEST_TIME 83 9316
TEST_TIME 84 9334
TEST_TIME 85 9314
TEST_TIME 86 9316
TEST_TIME 87 9277
TEST_TIME 88 9347
TEST_TIME 89 9419
TEST_TIME 90 9373
TEST_TIME 91 9290
TEST_TIME 92 9245
TEST_TIME 93 9179
TEST_TIME 94 9139
TEST_TIME 95 9187
TEST_TIME 96 9184
TEST_TIME 97 9143
TEST_TIME 98 9088
TEST_TIME 99 9096
TEST_TIME 100 9150
TEST_TIME 101 9126
TEST_TIME 102 9143
TEST_TIME 103 9124
TEST_TIME 104 9231
TEST_TIME 105 9239
TEST_TIME 106 9298
TEST_TIME 107 9276
TEST_TIME 108 9325
TEST_TIME 109 9340
TEST_TIME 110 9257
TEST_TIME 111 9222
TEST_TIME 112 9189
TEST_TIME 113 9152
TEST_TIME 114 9093
TEST_TIME 115 9057
TEST_TIME 116 9026
TEST_TIME 117 8991
TEST_TIME 118 9029
TEST_TIME 119 9051
TEST_TIME 120 9059

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-03-2021 04:27:55 to 17-03-2021 04:27:59.

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 1509019 1509019 0 0.00 0 10404 6 0 0
doc_list_biud 1509018 1509018 0 0.00 0 72259 30 0 0
sp_add_invoice_to_stock 5109 5009 100 1.95 45 202767 17526 0 100
sp_cancel_adding_invoice 5559 5559 0 0.00 5 77631 868 0 0
sp_cancel_client_order 1725 1725 0 0.00 4 20921 359 0 0
sp_cancel_customer_reserve 545503 545503 0 0.00 1 72262 66 0 0
sp_cancel_pay_from_customer 734 734 0 0.00 3 7056 98 0 0
sp_cancel_pay_to_supplier 424 424 0 0.00 2 7574 89 0 0
sp_cancel_supplier_invoice 6084 6076 8 0.13 2 17031 859 0 8
sp_cancel_supplier_order 397 397 0 0.00 7 1276774 113248 0 0
sp_cancel_write_off 9543 9543 0 0.00 1 9057 127 0 0
sp_client_order 20147 20147 0 0.00 4 23684 415 0 0
sp_customer_reserve 456647 456647 0 0.00 0 46090 173 0 0
sp_fill_shopping_cart 31008 31008 0 0.00 1 16022 48 0 0
sp_fill_shopping_cart_clo_res 456642 456642 0 0.00 0 35702 18 0 0
sp_get_clo_for_invoice 5003 5003 0 0.00 0 35438 407 0 0
sp_kill_cost_storno 16260 16260 0 0.00 0 9055 54 0 0
sp_kill_qstorno_ret_qs2qd 1107022 1107022 0 0.00 0 71777 36 0 0
sp_kill_qty_storno 583662 583662 0 0.00 0 72259 77 0 0
sp_lock_selected_doc 20649 20649 0 0.00 0 10054 6 0 0
sp_make_cost_storno 27697 27697 0 0.00 0 9573 46 0 0
sp_multiply_rows_for_pdistr 27697 27697 0 0.00 0 9255 30 0 0
sp_multiply_rows_for_qdistr 20147 20147 0 0.00 0 18999 239 0 0
sp_pay_from_customer 7131 7131 0 0.00 4 7108 101 0 0
sp_pay_to_supplier 5707 5707 0 0.00 5 9940 119 0 0
sp_payment_common 12838 12838 0 0.00 1 7692 16 0 0
sp_qd_handle_on_cancel_clo 1725 1725 0 0.00 0 20915 323 0 0
sp_qd_handle_on_invoice_upd_sts 10560 10560 0 0.00 0 25021 371 0 0
sp_qd_handle_on_reserve_upd_sts 9858 9858 0 0.00 0 13148 54 0 0
sp_reserve_write_off 9858 9858 0 0.00 7 13163 164 0 0
sp_supplier_invoice 5483 5483 0 0.00 7 96342 3553 0 0
sp_supplier_order 5383 5383 0 0.00 4 67129 1381 0 0
srv_aggregate_perf_data 103 103 0 0.00 2525 115002 12744 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1575 140393 48597 0 0
srv_find_qd_qs_mism 1039345 1039345 0 0.00 0 10403 5 0 0
srv_make_invnt_saldo 463 463 0 0.00 51 116797 3261 0 0
srv_make_money_saldo 708 708 0 0.00 0 11583 656 0 0
srv_recalc_idx_stat 4 4 0 0.00 103839 457671 265463 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5009 5009 0 0.00 0 16395 185 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 0 2807 95 0 0
t$perf-norm:sp_cancel_client_order 1725 1725 0 0.00 0 1801 8 0 0
t$perf-norm:sp_cancel_customer_reserve 708 708 0 0.00 0 2312 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 734 734 0 0.00 0 5196 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 424 424 0 0.00 0 1140 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 974 17 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 11883 1300 0 0
t$perf-norm:sp_cancel_write_off 1014 1014 0 0.00 0 8364 19 0 0
t$perf-norm:sp_client_order 20147 20147 0 0.00 0 10753 19 0 0
t$perf-norm:sp_customer_reserve 991 991 0 0.00 0 2594 6 0 0
t$perf-norm:sp_pay_from_customer 7131 7131 0 0.00 0 10124 13 0 0
t$perf-norm:sp_pay_to_supplier 5707 5707 0 0.00 0 13057 16 0 0
t$perf-norm:sp_reserve_write_off 9858 9858 0 0.00 0 14145 11 0 0
t$perf-norm:sp_supplier_invoice 5483 5483 0 0.00 0 5028 16 0 0
t$perf-norm:sp_supplier_order 5383 5383 0 0.00 0 8243 18 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 125 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 6930 113 0 0
t$perf-norm:srv_make_invnt_saldo 463 463 0 0.00 0 1629 12 0 0
t$perf-norm:srv_make_money_saldo 708 708 0 0.00 0 286 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 15 5 0 0
v_add_invoice_to_stock 10202 10002 200 1.96 0 4313 16 0 200
v_all_customers 36702 36702 0 0.00 0 16 1 0 0
v_all_suppliers 29752 29752 0 0.00 0 38 1 0 0
v_all_wares 40294 40294 0 0.00 0 84 1 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 620 6 0 0
v_cancel_client_order 3450 3450 0 0.00 0 2939 5 0 0
v_cancel_customer_prepayment 1468 1468 0 0.00 0 2941 14 0 0
v_cancel_customer_reserve 1416 1416 0 0.00 0 2888 7 0 0
v_cancel_payment_to_supplier 848 848 0 0.00 0 1688 8 0 0
v_cancel_supplier_invoice 802 786 16 1.99 0 3027 22 0 16
v_cancel_supplier_order 794 794 0 0.00 0 1700 6 0 0
v_cancel_write_off 2028 2028 0 0.00 0 4643 16 0 0
v_max_id_clo_ord 5383 5383 0 0.00 0 1495 2 0 0
v_max_id_clo_res 991 991 0 0.00 0 2400 15 0 0
v_max_id_ord_sup 5478 5478 0 0.00 0 5673 5 0 0
v_max_non_paid_invoice 1692 1692 0 0.00 0 4433 4 0 0
v_max_non_paid_realizn 3944 3944 0 0.00 0 2035 4 0 0
v_min_id_clo_ord 5383 5383 0 0.00 0 3970 6 0 0
v_min_id_clo_res 991 991 0 0.00 0 9075 26 0 0
v_min_id_ord_sup 5478 5478 0 0.00 0 14232 22 0 0
v_min_non_paid_invoice 5707 5707 0 0.00 0 5097 9 0 0
v_min_non_paid_realizn 7131 7131 0 0.00 0 3357 9 0 0
v_our_firm 9966 9966 0 0.00 0 19 1 0 0
v_reserve_write_off 19716 19716 0 0.00 0 4063 5 0 0
x_lock_depdocs_on_canc_invoice 11242 11242 0 0.00 0 17008 104 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 3282 295 0 0
x_make_qstorno_1000_1200 5383 5383 0 0.00 2 67119 1304 0 0
x_make_qstorno_1200_2000 5483 5483 0 0.00 4 96330 3362 0 0
x_make_qstorno_2100_3300 456647 456647 0 0.00 0 46089 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-03-2021 04:28:00 to 17-03-2021 04:28:04.

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
01:57:00 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
01:58:02 14229 8192000000 9712137792 10008510464 793867152 8.834 100 88 175 2 826898112 11377984 21652976
01:59:22 21499 8192000000 9766097216 10071363584 839466336 9.294 100 96 192 0 873935552 12031568 22437648
02:00:50 22763 8192000000 9798300976 10120073216 863050080 9.531 100 78 156 1 905501312 24685824 17765408
02:02:15 11727 8192000000 9860923904 10194411520 915929024 10.056 100 75 150 0 968323072 35892320 16501728
02:03:32 8382 8192000000 9909850528 10253275136 956035792 10.450 100 90 180 0 1030980432 55743536 19201104
02:04:43 6533 8192000000 9974732592 10324520960 983114736 10.715 100 87 175 0 1087640512 86769536 17756240
02:05:51 6095 8192000000 9986321264 10345553920 1013264816 11.007 100 74 147 2 1100538960 72608512 14665632
02:06:58 2039 8192000000 10013575504 10379886592 1041002832 11.274 100 87 172 1 1123338144 64917536 17417776
02:08:02 1402 8192000000 10035279184 10403729408 1064632000 11.501 100 91 180 1 1142562576 59364528 18566048
02:09:04 4799 8192000000 10004655984 10403528704 1066874640 11.522 100 43 87 0 1124444288 48537824 9031824
02:10:13 4491 8192000000 9987030320 10376900608 1081516352 11.662 100 38 76 3 1114540784 25002128 8022304
02:11:18 52876 8192000000 10012760240 10416603136 1085750272 11.702 100 37 74 1 1118549712 23721472 9077968
02:13:16 4424 8192000000 10016302416 10416197632 1091613280 11.758 100 55 108 0 1132974608 28534944 12826384
02:14:26 9976 8192000000 10013056320 10422149120 1098183136 11.820 100 34 69 2 1130770576 24694240 7893200
02:15:39 7735 8192000000 10013019056 10415063040 1090448000 11.747 99 31 64 1 1110872256 13975264 6448992
02:16:48 25729 8192000000 10001452976 10409005056 1077108352 11.620 98 19 38 0 1095845648 14940976 3796320
02:18:14 6876 8192000000 9985063728 10378526720 1041618048 11.280 97 31 62 1 1064713632 15412608 7682976
02:19:23 4241 8192000000 9971576992 10342428672 1031405808 11.182 99 41 83 0 1059548400 19070224 9072368
02:20:29 62747 8192000000 9944675248 10347069440 981365104 10.697 95 25 50 0 1013677136 26945872 5366160
02:22:36 7277 8192000000 9968393504 10361503744 1042460832 11.288 100 43 86 2 1073426672 21292256 9673584
02:23:48 10939 8192000000 9975705024 10364493824 1043005216 11.294 99 45 89 1 1073016592 18668144 11343232
02:25:01 10168 8192000000 9975472192 10383155200 1068063248 11.534 100 25 51 1 1094423600 20556240 5804112
02:26:11 19932 8192000000 9990775664 10401034240 1067736128 11.530 100 19 38 1 1095690144 24074064 3879952
02:27:40 31670 8192000000 10008007024 10408693760 1092486944 11.766 100 34 66 0 1126412256 25814544 8110768
02:29:17 12534 8192000000 10078502048 10475749376 1117735088 12.006 100 64 128 2 1187203808 55846816 13621904
02:30:40 18521 8192000000 10106627232 10508464128 1125219552 12.076 100 56 111 0 1208673120 71610912 11842656
02:32:06 33121 8192000000 10116364432 10535993344 1145837680 12.270 100 39 78 2 1232795504 78361760 8596064
02:33:44 5169 8192000000 10166424896 10573053952 1156114704 12.367 100 59 118 1 1269172864 100057744 13000416
02:34:54 13107 8192000000 10225620768 10647707648 1164392384 12.444 100 55 110 2 1335139824 158554480 12192960
02:36:15 10635 8192000000 10223065632 10644402176 1174210208 12.536 100 49 99 0 1327419024 141726496 11482320
02:37:34 3069 8192000000 10246665120 10651447296 1155619104 12.362 98 73 146 0 1340830320 169353648 15857568
02:38:48 5410 8192000000 10287735120 10699378688 1167605792 12.474 100 68 134 0 1392740064 209530752 15603520
02:39:57 3523 8192000000 10294413584 10712887296 1165857536 12.458 99 78 153 1 1393988576 210655536 17475504
02:41:06 1632 8192000000 10355247152 10758144000 1166974720 12.469 100 85 168 1 1446881872 259641040 20266112
02:42:11 2657 8192000000 10351031472 10753245184 1162623952 12.428 100 87 171 2 1444042608 262145344 19273312
02:43:19 1571 8192000000 10374045616 10779086848 1158310560 12.387 100 76 151 2 1469738944 294464432 16963952
02:44:25 3110 8192000000 10393804976 10801569792 1157330624 12.378 99 84 160 1 1490321552 314104384 18886544
02:45:33 4081 8192000000 10428497392 10866110464 1168197344 12.480 100 55 109 1 1530132704 349502816 12432544
02:46:41 3430 8192000000 10423521424 10846724096 1169204720 12.489 100 53 105 1 1523770016 341715152 12850144
02:47:56 2264 8192000000 10440659984 10850402304 1135640592 12.175 98 77 156 1 1520824720 366289344 18894784
02:49:01 14761 8192000000 10379486192 10850095104 1131108384 12.132 99 40 78 1 1505040816 363966544 9965888
02:50:21 14447 8192000000 10364864560 10836451328 1120497168 12.032 99 25 50 0 1479360400 351624832 7238400
02:51:46 18174 8192000000 10206670352 10663419904 1125575568 12.080 100 23 47 0 1307295232 176368416 5351248
02:53:20 38097 8192000000 10047113904 10482794496 1092488128 11.766 98 22 44 1 1119195888 22110880 4596880
02:54:58 133928 8192000000 10023494688 10448220160 1074631120 11.596 97 36 68 4 1115381392 33694048 7056224
02:58:21 49871 8192000000 10076744400 10502684672 1127828784 12.101 100 36 70 0 1180939808 46028496 7082528
03:00:14 28207 8192000000 10083209264 10528206848 1114553392 11.976 99 28 55 0 1171593648 50447520 6592736
03:01:46 50680 8192000000 10084773776 10537295872 1116720048 11.996 98 27 52 0 1180841200 58808208 5312944
03:03:37 35545 8192000000 10078054208 10542673920 1134369792 12.163 100 17 34 1 1168912944 31098416 3444736
03:05:13 33753 8192000000 10101379376 10563624960 1124387504 12.068 99 14 27 2 1181303936 53640864 3275568
03:06:49 47900 8192000000 10057134112 10486001664 1126693472 12.090 99 26 50 2 1150085520 18396992 4995056
03:08:40 15111 8192000000 10105347024 10529849344 1139316080 12.209 100 46 90 2 1193354032 44998960 9038992
03:09:56 85781 8192000000 10160234544 10610216960 1152574736 12.334 100 24 48 0 1239727488 82232880 4919872
03:12:34 42513 8192000000 10115555984 10569965568 1138278752 12.199 99 25 49 0 1208776128 65623376 4874000
03:14:17 65023 8192000000 10163099280 10622070784 1136195168 12.180 98 15 30 0 1224239440 84638992 3405280
03:17:31 86795 8192000000 10066295392 10507038720 1121080960 12.037 98 37 74 0 1162335248 33314752 7939536
03:20:37 52798 8192000000 10094367472 10569519104 1135474464 12.173 99 13 26 1 1185002944 46938272 2590208
03:22:34 53821 8192000000 10116064624 10576846848 1112085264 11.952 97 11 22 1 1184986752 70355040 2546448
03:24:28 89236 8192000000 10098816848 10534203392 1084937616 11.694 96 17 33 1 1180886064 92488432 3460016
03:27:15 46289 8192000000 10072953680 10502074368 1115120880 11.981 98 22 43 3 1159114416 39053360 4940176
03:29:02 73100 8192000000 10101198352 10558812160 1099711440 11.835 96 17 34 1 1166371568 63624208 3035920
03:31:16 72210 8192000000 10156141328 10660376576 1131746800 12.138 99 13 26 2 1253516832 118407568 3362464
03:33:28 105853 8192000000 10080524848 10540257280 1115362128 11.983 97 12 24 1 1174121472 56028672 2730672
03:36:15 71530 8192000000 10087411456 10519969792 1079341280 11.641 94 18 36 1 1116287936 33223536 3723120
03:38:37 95692 8192000000 10073885632 10502688768 1119201680 12.019 99 43 86 0 1174817216 46825424 8790112
03:41:21 65789 8192000000 10042846224 10487992320 1090981072 11.752 96 9 18 0 1118892928 26199280 1712576
03:43:31 71892 8192000000 10133920128 10597875712 1118500960 12.013 98 16 30 0 1204804752 82357648 3946144
03:45:44 65477 8192000000 10130174640 10615205888 1089136352 11.734 96 23 46 0 1231731616 137524208 5071056
03:47:56 140393 8192000000 10094771168 10605830144 1096684352 11.806 98 15 30 0 1184196560 83732128 3780080
03:51:16 31621 8192000000 10066981696 10495856640 1113905568 11.969 99 24 47 1 1134228336 15054208 5268560
03:52:55 63324 8192000000 10133474160 10576130048 1124761216 12.072 99 28 56 0 1197936240 67058496 6116528
03:55:04 62910 8192000000 10081636816 10592706560 1123539392 12.060 99 13 25 1 1191709904 65690928 2479584
03:57:17 57301 8192000000 10070144800 10521042944 1136589056 12.183 100 11 22 0 1183200912 44462576 2149280
03:59:43 57600 8192000000 10123691136 10574405632 1130224432 12.123 98 17 34 2 1179516944 46062544 3229968
04:01:41 55135 8192000000 10095308240 10616832000 1141042896 12.225 100 13 26 0 1201823248 58007472 2772880
04:03:39 44939 8192000000 10116521520 10557886464 1149825072 12.308 100 41 82 2 1191854928 32750656 9279200
04:05:36 76035 8192000000 10073550176 10565996544 1111159216 11.943 98 23 47 0 1171357520 55470080 4728224
04:07:55 29947 8192000000 10094636928 10521665536 1110940896 11.941 98 27 54 1 1160100944 43873840 5286208
04:09:32 77934 8192000000 10133534112 10644172800 1127617120 12.099 99 16 32 0 1268139328 136762192 3760016
04:11:51 130946 8192000000 10098917936 10607927296 1137314416 12.190 99 12 24 0 1205913744 66103856 2495472
04:15:02 122053 8192000000 10084362688 10564694016 1097718560 11.816 96 19 38 1 1165077504 63536448 3822496
04:18:04 39856 8192000000 10039268576 10492817408 1118919968 12.017 99 9 19 0 1138177024 16824208 2432848
04:19:55 53064 8192000000 10045808288 10507853824 1103194064 11.868 99 5 10 0 1132242528 28249360 799104
04:21:52 80967 8192000000 10093302480 10556903424 1104903488 11.884 98 11 22 0 1163586960 56433056 2250416
04:24:13 109111 8192000000 10046776336 10543861760 1074049872 11.591 95 15 30 0 1194642624 116905840 3686912

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 17-03-2021 04:28:06 to 17-03-2021 04:28:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 100 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-03-2021 04:28:21 to 17-03-2021 04:28:23.

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          233956
MON$OLDEST_ACTIVE               234434
MON$OLDEST_SNAPSHOT             234434
MON$NEXT_TRANSACTION            234434
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                       2104512
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.0.2387 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:205: 
:206: mscs-test-161.budzdorov.ru    Wed Mar 17 04:26:59 2021
:207:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:208: 
:209: 
:210: mscs-test-161.budzdorov.ru    Wed Mar 17 04:26:59 2021
:211:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:212: 
:213: 
:214: mscs-test-161.budzdorov.ru    Wed Mar 17 04:27:01 2021
:215:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:216: 
--- end of diff output ---

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

17.03.21 04:28:24. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-03-17 04:28:27 No severe PSQL-related problems occured
17.03.21 04:28:28. End of report.