Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.03.2021 07: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 16909928 kB
MemAvailable 31528336 kB
Buffers 0 kB
Cached 14724860 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-18 07:27:18
DTS_END                         2021-03-18 07:26:55.6110
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/42770
TRN_ID                          244646
ATT_ID                          259
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: 8687.32 19054 1042478
customer order: creation 179.47 362 21536
customer order: refuse 15.10 333 1812
order to supplier: creation 46.00 1277 5520
order to supplier: removal 3.30 102539 396
invoice (draft): creation 46.74 3363 5609
invoice (draft): removal 48.18 831 5781
invoice accept: apply 42.08 17711 5050
invoice accept: cancel 42.82 934 5138
customer reserve: creation 3788.51 179 454621
customer reserve: removal 4176.98 66 501237
realization accept: apply 85.67 165 10280
realization accept: cancel 79.63 125 9556
payment from customer: creation 62.10 107 7452
payment from customer: removal 5.57 63 668
payment to supplier: creation 50.34 132 6041
payment to supplier: removal 3.62 104 434
service: total inventory turnovers 3.93 3120 472
service: total monetary turnovers 6.28 559 754
service: aggregate perf. data 0.98 11777 117
service: refresh index statistics 0.03 237341 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-03-2021 07:27:51 to 18-03-2021 07:27:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2382
TEST_TIME 2 2282
TEST_TIME 3 3015
TEST_TIME 4 4026
TEST_TIME 5 4681
TEST_TIME 6 5106
TEST_TIME 7 5298
TEST_TIME 8 5522
TEST_TIME 9 5395
TEST_TIME 10 5463
TEST_TIME 11 5873
TEST_TIME 12 6019
TEST_TIME 13 5992
TEST_TIME 14 6035
TEST_TIME 15 6129
TEST_TIME 16 6244
TEST_TIME 17 6895
TEST_TIME 18 6987
TEST_TIME 19 7105
TEST_TIME 20 7763
TEST_TIME 21 7914
TEST_TIME 22 7910
TEST_TIME 23 8564
TEST_TIME 24 9120
TEST_TIME 25 9356
TEST_TIME 26 9575
TEST_TIME 27 10404
TEST_TIME 28 10059
TEST_TIME 29 9852
TEST_TIME 30 9876
TEST_TIME 31 9931
TEST_TIME 32 9900
TEST_TIME 33 9811
TEST_TIME 34 9690
TEST_TIME 35 9650
TEST_TIME 36 9573
TEST_TIME 37 9542
TEST_TIME 38 9521
TEST_TIME 39 9434
TEST_TIME 40 9406
TEST_TIME 41 9532
TEST_TIME 42 9425
TEST_TIME 43 9495
TEST_TIME 44 9463
TEST_TIME 45 9405
TEST_TIME 46 9431
TEST_TIME 47 9409
TEST_TIME 48 9481
TEST_TIME 49 9300
TEST_TIME 50 9120
TEST_TIME 51 8994
TEST_TIME 52 9008
TEST_TIME 53 9146
TEST_TIME 54 9146
TEST_TIME 55 9102
TEST_TIME 56 9031
TEST_TIME 57 8934
TEST_TIME 58 8842
TEST_TIME 59 8818
TEST_TIME 60 8887
TEST_TIME 61 8953
TEST_TIME 62 8949
TEST_TIME 63 8935
TEST_TIME 64 9015
TEST_TIME 65 8945
TEST_TIME 66 8958
TEST_TIME 67 9082
TEST_TIME 68 9108
TEST_TIME 69 9160
TEST_TIME 70 9157
TEST_TIME 71 9165
TEST_TIME 72 9167
TEST_TIME 73 9175
TEST_TIME 74 9233
TEST_TIME 75 9119
TEST_TIME 76 9060
TEST_TIME 77 8994
TEST_TIME 78 8911
TEST_TIME 79 8886
TEST_TIME 80 8925
TEST_TIME 81 8830
TEST_TIME 82 8864
TEST_TIME 83 8888
TEST_TIME 84 8847
TEST_TIME 85 8815
TEST_TIME 86 8839
TEST_TIME 87 8886
TEST_TIME 88 8931
TEST_TIME 89 8899
TEST_TIME 90 8915
TEST_TIME 91 8949
TEST_TIME 92 8914
TEST_TIME 93 8903
TEST_TIME 94 8826
TEST_TIME 95 8829
TEST_TIME 96 8772
TEST_TIME 97 8734
TEST_TIME 98 8770
TEST_TIME 99 8736
TEST_TIME 100 8783
TEST_TIME 101 8761
TEST_TIME 102 8741
TEST_TIME 103 8737
TEST_TIME 104 8739
TEST_TIME 105 8745
TEST_TIME 106 8798
TEST_TIME 107 8804
TEST_TIME 108 8840
TEST_TIME 109 8797
TEST_TIME 110 8730
TEST_TIME 111 8701
TEST_TIME 112 8643
TEST_TIME 113 8613
TEST_TIME 114 8674
TEST_TIME 115 8671
TEST_TIME 116 8641
TEST_TIME 117 8636
TEST_TIME 118 8650
TEST_TIME 119 8633
TEST_TIME 120 8688

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-03-2021 07:27:54 to 18-03-2021 07:27:58.

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 1463601 1463601 0 0.00 0 19029 7 0 0
doc_list_biud 1463592 1463592 0 0.00 0 31062 29 0 0
sp_add_invoice_to_stock 5198 5050 148 2.84 22 185127 17711 0 148
sp_cancel_adding_invoice 5138 5138 0 0.00 5 87329 934 0 0
sp_cancel_client_order 1812 1812 0 0.00 5 10724 334 0 0
sp_cancel_customer_reserve 501237 501237 0 0.00 1 31066 67 0 0
sp_cancel_pay_from_customer 668 668 0 0.00 3 3395 64 0 0
sp_cancel_pay_to_supplier 434 434 0 0.00 3 8459 104 0 0
sp_cancel_supplier_invoice 5793 5781 12 0.20 1 15559 831 0 12
sp_cancel_supplier_order 396 396 0 0.00 7 1273370 102540 0 0
sp_cancel_write_off 9556 9556 0 0.00 1 21601 126 0 0
sp_client_order 21536 21536 0 0.00 4 21969 362 0 0
sp_customer_reserve 454621 454621 0 0.00 0 99393 179 0 0
sp_fill_shopping_cart 32657 32657 0 0.00 1 18236 45 0 0
sp_fill_shopping_cart_clo_res 454620 454620 0 0.00 0 22511 18 0 0
sp_get_clo_for_invoice 5048 5048 0 0.00 0 51283 430 0 0
sp_kill_cost_storno 15796 15796 0 0.00 0 10356 53 0 0
sp_kill_qstorno_ret_qs2qd 1018207 1018207 0 0.00 0 31050 36 0 0
sp_kill_qty_storno 539239 539239 0 0.00 0 31061 77 0 0
sp_lock_selected_doc 21237 21237 0 0.00 0 9364 5 0 0
sp_make_cost_storno 28821 28821 0 0.00 0 19007 52 0 0
sp_multiply_rows_for_pdistr 28818 28818 0 0.00 0 13234 30 0 0
sp_multiply_rows_for_qdistr 21536 21536 0 0.00 0 21792 182 0 0
sp_pay_from_customer 7452 7452 0 0.00 4 19052 108 0 0
sp_pay_to_supplier 6041 6041 0 0.00 5 20987 133 0 0
sp_payment_common 13493 13493 0 0.00 1 9109 17 0 0
sp_qd_handle_on_cancel_clo 1812 1812 0 0.00 0 10715 305 0 0
sp_qd_handle_on_invoice_upd_sts 10181 10181 0 0.00 0 24369 343 0 0
sp_qd_handle_on_reserve_upd_sts 10276 10276 0 0.00 0 12461 56 0 0
sp_reserve_write_off 10280 10280 0 0.00 6 13285 166 0 0
sp_supplier_invoice 5609 5609 0 0.00 8 81535 3363 0 0
sp_supplier_order 5520 5520 0 0.00 6 21191 1277 0 0
srv_aggregate_perf_data 117 117 0 0.00 2612 87315 11778 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1498 152821 47480 0 0
srv_find_qd_qs_mism 994122 994122 0 0.00 0 13438 6 0 0
srv_make_invnt_saldo 472 472 0 0.00 47 82147 3121 0 0
srv_make_money_saldo 754 754 0 0.00 1 16669 560 0 0
srv_recalc_idx_stat 4 4 0 0.00 153510 425641 237342 0 0
t$perf-abend:sp_add_invoice_to_stock 148 148 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 5050 5050 0 0.00 0 15329 171 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 0 3542 104 0 0
t$perf-norm:sp_cancel_client_order 1812 1812 0 0.00 0 2580 12 0 0
t$perf-norm:sp_cancel_customer_reserve 720 720 0 0.00 0 3362 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 668 668 0 0.00 0 10918 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 434 434 0 0.00 0 1585 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 423 423 0 0.00 0 888 14 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 14404 1329 0 0
t$perf-norm:sp_cancel_write_off 1055 1055 0 0.00 0 3155 10 0 0
t$perf-norm:sp_client_order 21536 21536 0 0.00 0 6722 18 0 0
t$perf-norm:sp_customer_reserve 994 994 0 0.00 0 3526 13 0 0
t$perf-norm:sp_pay_from_customer 7452 7452 0 0.00 0 8327 8 0 0
t$perf-norm:sp_pay_to_supplier 6041 6041 0 0.00 0 5513 11 0 0
t$perf-norm:sp_reserve_write_off 10280 10280 0 0.00 0 4082 10 0 0
t$perf-norm:sp_supplier_invoice 5609 5609 0 0.00 0 5943 16 0 0
t$perf-norm:sp_supplier_order 5520 5520 0 0.00 0 7871 15 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 251 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 2087 43 0 0
t$perf-norm:srv_make_invnt_saldo 472 472 0 0.00 0 648 6 0 0
t$perf-norm:srv_make_money_saldo 754 754 0 0.00 0 2311 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10382 10086 296 2.85 0 7051 16 0 296
v_all_customers 39254 39254 0 0.00 0 22 1 0 0
v_all_suppliers 30698 30698 0 0.00 0 15 1 0 0
v_all_wares 43072 43072 0 0.00 0 122 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 2815 10 0 0
v_cancel_client_order 3624 3624 0 0.00 0 3382 7 0 0
v_cancel_customer_prepayment 1336 1336 0 0.00 0 366 3 0 0
v_cancel_customer_reserve 1440 1440 0 0.00 0 338 4 0 0
v_cancel_payment_to_supplier 868 868 0 0.00 0 2599 11 0 0
v_cancel_supplier_invoice 870 846 24 2.75 0 6790 20 0 24
v_cancel_supplier_order 792 792 0 0.00 0 613 3 0 0
v_cancel_write_off 2110 2110 0 0.00 0 2751 14 0 0
v_max_id_clo_ord 5517 5517 0 0.00 0 1976 2 0 0
v_max_id_clo_res 994 994 0 0.00 0 2079 16 0 0
v_max_id_ord_sup 5604 5604 0 0.00 0 4920 4 0 0
v_max_non_paid_invoice 1813 1813 0 0.00 0 296 2 0 0
v_max_non_paid_realizn 4010 4010 0 0.00 0 2627 4 0 0
v_min_id_clo_ord 5517 5517 0 0.00 0 8558 12 0 0
v_min_id_clo_res 994 994 0 0.00 0 2273 4 0 0
v_min_id_ord_sup 5604 5604 0 0.00 0 6677 8 0 0
v_min_non_paid_invoice 6041 6041 0 0.00 0 3015 8 0 0
v_min_non_paid_realizn 7452 7452 0 0.00 0 4324 11 0 0
v_our_firm 10702 10702 0 0.00 0 14 1 0 0
v_reserve_write_off 20552 20552 0 0.00 0 3462 7 0 0
x_lock_depdocs_on_canc_invoice 10496 10496 0 0.00 0 12307 108 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 17552 332 0 0
x_make_qstorno_1000_1200 5520 5520 0 0.00 2 21181 1198 0 0
x_make_qstorno_1200_2000 5609 5609 0 0.00 5 81226 3187 0 0
x_make_qstorno_2100_3300 454621 454621 0 0.00 0 99390 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-03-2021 07:27:59 to 18-03-2021 07:28:03.

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
04:56:59 1 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
04:58:00 12669 8192000000 9708117088 10002522112 792249184 8.818 100 86 171 1 819965424 7021520 20694720
04:59:17 17832 8192000000 9766754624 10072174592 835627712 9.256 100 82 164 0 865613440 10568464 19417264
05:00:50 21180 8192000000 9798226928 10114453504 863165968 9.532 100 85 170 0 905216272 22119232 19931072
05:02:14 9915 8192000000 9853853328 10186174464 913608448 10.033 100 76 151 0 962667872 32505840 16553584
05:03:29 8782 8192000000 9929638144 10268893184 955992480 10.450 100 80 160 0 1037317088 64682176 16642432
05:04:40 6198 8192000000 9956660464 10311000064 990765952 10.789 100 71 142 0 1070911440 65678784 14466704
05:05:49 2446 8192000000 9999333904 10363314176 1031262208 11.181 100 82 162 3 1112530736 64991968 16276560
05:06:53 1933 8192000000 10026255120 10390814720 1052164832 11.381 100 85 168 0 1137764656 68440528 17159296
05:07:56 2580 8192000000 10043780960 10416943104 1067950480 11.533 100 73 143 0 1161779168 79728736 14099952
05:08:59 30360 8192000000 10018527792 10418180096 1076187424 11.611 100 53 104 1 1124401072 37044272 11169376
05:10:38 7458 8192000000 10017849200 10407956480 1085310288 11.698 100 45 89 0 1131902928 36035520 10557120
05:11:48 6386 8192000000 9988991728 10389708800 1088139104 11.725 100 33 67 0 1106663584 11260192 7264288
05:12:59 11522 8192000000 10013668768 10419359744 1092713584 11.768 100 25 50 1 1112400352 14425808 5260960
05:14:12 10425 8192000000 10020320592 10402721792 1095125872 11.791 100 79 162 2 1125259696 11970512 18163312
05:15:22 3876 8192000000 10028042416 10425466880 1093612800 11.777 100 55 106 2 1135691104 30358304 11720000
05:16:31 4154 8192000000 9991760544 10391953408 1075994288 11.609 99 45 92 1 1102705152 16664576 10046288
05:17:40 71510 8192000000 9949771344 10347462656 995357216 10.833 93 29 58 1 1027556112 25285536 6913360
05:19:54 6940 8192000000 9958255600 10331385856 1033231920 11.200 100 29 58 2 1064263216 24204400 6826896
05:21:01 8443 8192000000 9960215024 10347634688 1030198624 11.170 99 42 87 5 1055821472 16059648 9563200
05:22:10 20068 8192000000 9959222144 10356674560 1037294112 11.239 99 27 53 2 1054915184 9816144 7804928
05:23:35 16159 8192000000 9959836912 10361135104 1043873280 11.302 99 19 35 2 1072164928 24612976 3678672
05:24:52 5576 8192000000 9978710720 10373066752 1063948688 11.494 100 56 109 4 1100933456 23911680 13073088
05:26:01 8268 8192000000 9987978256 10379517952 1074963888 11.599 100 30 61 0 1092885296 12238560 5682848
05:27:10 48204 8192000000 10030384736 10434260992 1099847232 11.836 100 40 81 2 1132790080 24034224 8908624
05:29:05 35102 8192000000 10065526064 10471890944 1120029376 12.027 100 38 75 0 1179966336 52420336 7516624
05:30:43 1701 8192000000 10098359296 10496622592 1130913072 12.130 100 67 130 0 1206156592 60826000 14417520
05:31:49 2442 8192000000 10138543408 10542141440 1142022352 12.235 100 58 117 2 1245234160 91011040 12200768
05:32:55 10880 8192000000 10133054176 10551304192 1144914864 12.262 100 52 103 1 1253221456 97185152 11121440
05:34:11 9920 8192000000 10175244672 10580127744 1135662848 12.175 99 52 104 2 1266105312 119252640 11189824
05:35:27 9626 8192000000 10185000640 10596667392 1151153760 12.320 99 68 130 2 1288890720 124361840 13375120
05:36:42 5825 8192000000 10237810576 10649341952 1153833616 12.345 99 61 119 4 1329726848 161825648 14067584
05:37:50 6980 8192000000 10205960368 10612764672 1145400928 12.266 98 66 130 0 1301806736 140725232 15680576
05:39:05 20601 8192000000 10262619184 10687442944 1171193120 12.508 100 42 84 0 1357535472 176762320 9580032
05:40:31 3075 8192000000 10276322912 10692706304 1161547376 12.418 100 53 103 1 1377405984 204458304 11400304
05:41:41 1495 8192000000 10320427488 10729431040 1152562928 12.334 100 70 138 0 1417799856 249281680 15955248
05:42:48 4647 8192000000 10360725744 10761027584 1147915472 12.290 100 76 151 1 1458831744 293952608 16963664
05:43:57 7154 8192000000 10320304064 10730360832 1143010560 12.244 100 42 84 1 1417820032 264695952 10113520
05:45:09 1858 8192000000 10372308480 10768080896 1132887584 12.149 100 85 169 2 1466075600 314131664 19056352
05:46:14 7602 8192000000 10356237264 10787033088 1111738480 11.949 100 49 97 1 1446580960 322851744 11990736
05:47:25 6392 8192000000 10306198848 10733760512 1114893440 11.979 100 47 92 1 1411506304 284873952 11738912
05:48:36 9184 8192000000 10408260944 10828496896 1142142960 12.236 100 51 100 0 1506217920 351642048 12432912
05:49:47 19702 8192000000 10339714336 10842161152 1143254544 12.246 100 32 64 2 1483427984 331385264 8788176
05:51:22 22891 8192000000 10253296208 10697048064 1142426048 12.238 100 20 40 1 1359636240 212433824 4776368
05:52:46 18975 8192000000 10264831072 10745663488 1114523232 11.975 98 24 48 2 1356282864 235381824 6377808
05:54:20 94711 8192000000 10161649344 10610429952 1135753184 12.176 100 50 100 1 1245047760 97921760 11372816
05:57:35 91520 8192000000 10074336992 10594152448 1139202288 12.208 99 13 26 0 1239318112 97244240 2871584
06:00:10 86250 8192000000 10089417072 10554408960 1143922320 12.252 100 20 41 0 1184425696 36417872 4085504
06:02:40 59326 8192000000 10087778256 10529468416 1126007328 12.084 99 29 58 1 1175426064 43352816 6065920
06:04:49 46732 8192000000 10104421136 10537668608 1137445584 12.191 99 37 73 0 1174456016 29126304 7884128
06:06:36 70769 8192000000 10073839184 10534998016 1133928752 12.158 99 16 30 1 1198776816 61247744 3600320
06:08:47 111509 8192000000 10091497136 10572972032 1133011552 12.150 99 10 20 0 1173852256 39155424 1685280
06:11:47 68855 8192000000 10074197776 10519719936 1123796608 12.063 98 11 22 0 1159831680 33826208 2208864
06:14:00 109793 8192000000 10111177312 10604023808 1143195888 12.246 100 13 26 0 1208603248 62397136 3010224
06:17:06 67870 8192000000 10160018672 10618744832 1142813408 12.242 99 40 80 1 1225295456 73974224 8507824
06:19:21 25071 8192000000 10085402576 10602561536 1124860544 12.073 98 20 40 1 1194235872 65410016 3965312
06:20:47 152821 8192000000 10050059248 10488307712 993411712 10.815 87 18 36 1 1019271072 22120944 3738416
06:24:20 82187 8192000000 10098398832 10588819456 1117525024 12.004 100 14 28 1 1215793104 94865024 3403056
06:26:47 49355 8192000000 10081263728 10543882240 1107431376 11.908 99 15 29 1 1163232368 52639552 3161440
06:28:41 33164 8192000000 10082608624 10527649792 1112518528 11.956 98 21 42 1 1159107632 42498976 4090128
06:30:20 44579 8192000000 10051067952 10514518016 1079564016 11.643 96 10 20 1 1140173040 58396240 2212784
06:32:05 49667 8192000000 10132735888 10571681792 1110050960 11.933 98 29 58 1 1211066288 95252960 5762368
06:34:22 34614 8192000000 10076965360 10499567616 1049896224 11.360 95 37 74 0 1124778880 67211664 7670992
06:35:59 28469 8192000000 10050081680 10470064128 1076685744 11.616 98 32 64 0 1133260432 50584224 5990464
06:37:30 56071 8192000000 10070964336 10506797056 1097433840 11.813 99 31 62 1 1166606784 62799120 6373824
06:39:26 142765 8192000000 10040751392 10484158464 1117185040 12.000 100 13 26 0 1165812288 46156928 2470320
06:42:52 95289 8192000000 10077783392 10524975104 1116570176 11.995 99 11 22 0 1152508992 33594608 2344208
06:45:59 69277 8192000000 10088431440 10627502080 1117972128 12.008 99 10 21 0 1179523552 59346304 2205120
06:48:10 74085 8192000000 10101380416 10569625600 1114292048 11.973 98 13 26 1 1182630112 65746032 2592032
06:50:49 21598 8192000000 10099900592 10544828416 1138731072 12.204 100 28 56 1 1184513184 40117440 5664672
06:52:11 58057 8192000000 10103083952 10594471936 1129375488 12.115 99 17 34 2 1219034224 85554432 4104304
06:54:57 62265 8192000000 10083043408 10527113216 1104249712 11.878 97 27 54 1 1127206336 17210032 5746592
06:57:11 64259 8192000000 10072425648 10512609280 1127404800 12.097 99 16 32 0 1172667968 42514336 2748832
06:59:16 25076 8192000000 10112285616 10546720768 1114908288 11.979 98 45 90 1 1185391840 61563728 8919824
07:00:41 74153 8192000000 10064451968 10526994432 1134137088 12.160 100 35 70 0 1203428832 62209568 7082176
07:02:55 47691 8192000000 10097137200 10569531392 1138062144 12.197 100 7 14 0 1199550624 60066768 1421712
07:04:49 55565 8192000000 10108806336 10579636224 1141913328 12.234 100 15 30 1 1209416352 64352000 3151024
07:06:53 48957 8192000000 10082437024 10507681792 1139504016 12.211 100 29 57 1 1178652672 33634784 5513872
07:08:46 58696 8192000000 10105473824 10560040960 1102618048 11.862 96 17 34 1 1147072656 40801392 3653216
07:10:52 71383 8192000000 10135240656 10597384192 1124815984 12.072 99 25 50 1 1206840560 76594960 5429616
07:13:13 41487 8192000000 10089173520 10536579072 1089992208 11.743 96 18 36 1 1137665232 44021888 3651136
07:15:07 17354 8192000000 10079713664 10497019904 1118018704 12.008 98 40 78 0 1147205856 19485440 9701712
07:16:25 100542 8192000000 10071270400 10531241984 1076829584 11.617 94 16 32 1 1132034640 52158736 3046320
07:20:02 109355 8192000000 10066919648 10604294144 1083436496 11.680 96 8 16 0 1184827824 99167200 2224128
07:22:52 39500 8192000000 10053916320 10480881664 1045272368 11.315 93 31 62 1 1091334096 39432992 6628736
07:24:45 48697 8192000000 10098862800 10517811200 1103992736 11.876 99 34 69 0 1165652224 54662432 6997056

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-03-2021 07:28:05 to 18-03-2021 07:28:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 148 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 18-03-2021 07:28:20 to 18-03-2021 07:28:22.

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          251790
MON$OLDEST_ACTIVE               251791
MON$OLDEST_SNAPSHOT             251791
MON$NEXT_TRANSACTION            251791
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                       2099328
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             825
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2389 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2389 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2389 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.2389 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 ---
--- end of diff output ---

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

18.03.21 07: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-18 07:28:26 No severe PSQL-related problems occured
18.03.21 07:28:27. End of report.