Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.03.2021 07:42
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 16571616 kB
MemAvailable 31515688 kB
Buffers 0 kB
Cached 15114772 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-31 07:39:42
DTS_END                         2021-03-31 07:39:42.9830
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_MAKE_INVNT_SALDO(40:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/47550
TRN_ID                          234150
ATT_ID                          683
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: 8796.23 25325 1055548
customer order: creation 164.48 367 19738
customer order: refuse 14.65 349 1758
order to supplier: creation 43.58 1416 5230
order to supplier: removal 3.16 128851 379
invoice (draft): creation 44.03 3528 5283
invoice (draft): removal 49.93 1066 5992
invoice accept: apply 39.67 18674 4761
invoice accept: cancel 45.25 939 5430
customer reserve: creation 3692.91 181 443149
customer reserve: removal 4420.40 73 530448
realization accept: apply 80.78 173 9693
realization accept: cancel 76.94 122 9233
payment from customer: creation 55.75 107 6690
payment from customer: removal 5.23 90 628
payment to supplier: creation 45.83 124 5500
payment to supplier: removal 3.13 85 375
service: total inventory turnovers 3.68 3735 441
service: total monetary turnovers 5.93 631 711
service: aggregate perf. data 0.88 19229 105
service: refresh index statistics 0.03 326761 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-03-2021 07:42:21 to 31-03-2021 07:42:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3913
TEST_TIME 2 3593
TEST_TIME 3 3596
TEST_TIME 4 3806
TEST_TIME 5 4280
TEST_TIME 6 4520
TEST_TIME 7 4483
TEST_TIME 8 4658
TEST_TIME 9 4764
TEST_TIME 10 4994
TEST_TIME 11 5009
TEST_TIME 12 5685
TEST_TIME 13 5820
TEST_TIME 14 5912
TEST_TIME 15 6127
TEST_TIME 16 6326
TEST_TIME 17 6689
TEST_TIME 18 6990
TEST_TIME 19 7355
TEST_TIME 20 7502
TEST_TIME 21 7648
TEST_TIME 22 7554
TEST_TIME 23 7791
TEST_TIME 24 7903
TEST_TIME 25 8376
TEST_TIME 26 8251
TEST_TIME 27 8608
TEST_TIME 28 8605
TEST_TIME 29 9322
TEST_TIME 30 10708
TEST_TIME 31 10543
TEST_TIME 32 10435
TEST_TIME 33 10440
TEST_TIME 34 10431
TEST_TIME 35 10551
TEST_TIME 36 10441
TEST_TIME 37 10267
TEST_TIME 38 10179
TEST_TIME 39 10137
TEST_TIME 40 9986
TEST_TIME 41 9858
TEST_TIME 42 9925
TEST_TIME 43 10006
TEST_TIME 44 10012
TEST_TIME 45 10054
TEST_TIME 46 10009
TEST_TIME 47 10004
TEST_TIME 48 10092
TEST_TIME 49 9915
TEST_TIME 50 9739
TEST_TIME 51 9610
TEST_TIME 52 9539
TEST_TIME 53 9455
TEST_TIME 54 9440
TEST_TIME 55 9497
TEST_TIME 56 9434
TEST_TIME 57 9335
TEST_TIME 58 9315
TEST_TIME 59 9299
TEST_TIME 60 9190
TEST_TIME 61 9185
TEST_TIME 62 9149
TEST_TIME 63 9181
TEST_TIME 64 9299
TEST_TIME 65 9349
TEST_TIME 66 9397
TEST_TIME 67 9394
TEST_TIME 68 9390
TEST_TIME 69 9349
TEST_TIME 70 9408
TEST_TIME 71 9367
TEST_TIME 72 9381
TEST_TIME 73 9451
TEST_TIME 74 9422
TEST_TIME 75 9464
TEST_TIME 76 9438
TEST_TIME 77 9400
TEST_TIME 78 9331
TEST_TIME 79 9250
TEST_TIME 80 9200
TEST_TIME 81 9157
TEST_TIME 82 9105
TEST_TIME 83 9044
TEST_TIME 84 9005
TEST_TIME 85 9060
TEST_TIME 86 9041
TEST_TIME 87 8997
TEST_TIME 88 9057
TEST_TIME 89 9065
TEST_TIME 90 9084
TEST_TIME 91 9162
TEST_TIME 92 9173
TEST_TIME 93 9195
TEST_TIME 94 9219
TEST_TIME 95 9164
TEST_TIME 96 9196
TEST_TIME 97 9221
TEST_TIME 98 9182
TEST_TIME 99 9137
TEST_TIME 100 9083
TEST_TIME 101 9059
TEST_TIME 102 9013
TEST_TIME 103 8965
TEST_TIME 104 8954
TEST_TIME 105 8912
TEST_TIME 106 8889
TEST_TIME 107 8863
TEST_TIME 108 8849
TEST_TIME 109 8841
TEST_TIME 110 8815
TEST_TIME 111 8855
TEST_TIME 112 8860
TEST_TIME 113 8878
TEST_TIME 114 8872
TEST_TIME 115 8923
TEST_TIME 116 8931
TEST_TIME 117 8914
TEST_TIME 118 8908
TEST_TIME 119 8861
TEST_TIME 120 8801

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 31-03-2021 07:42:24 to 31-03-2021 07:42:28.

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 1466204 1466204 0 0.00 0 16857 6 0 0
doc_list_biud 1466201 1466201 0 0.00 0 27974 33 0 0
sp_add_invoice_to_stock 4944 4761 183 3.70 32 175428 18675 0 183
sp_cancel_adding_invoice 5430 5430 0 0.00 4 87115 939 0 0
sp_cancel_client_order 1758 1758 0 0.00 5 9076 350 0 0
sp_cancel_customer_reserve 530448 530448 0 0.00 1 21658 73 0 0
sp_cancel_pay_from_customer 628 628 0 0.00 3 3763 90 0 0
sp_cancel_pay_to_supplier 375 375 0 0.00 3 4608 85 0 0
sp_cancel_supplier_invoice 6003 5992 11 0.18 2 26596 1066 0 11
sp_cancel_supplier_order 379 379 0 0.00 8 1243572 128851 0 0
sp_cancel_write_off 9233 9233 0 0.00 1 10113 122 0 0
sp_client_order 19738 19738 0 0.00 4 44523 368 0 0
sp_customer_reserve 443149 443149 0 0.00 0 104070 182 0 0
sp_fill_shopping_cart 30246 30246 0 0.00 1 13167 40 0 0
sp_fill_shopping_cart_clo_res 443141 443141 0 0.00 0 16538 18 0 0
sp_get_clo_for_invoice 4751 4751 0 0.00 0 33396 355 0 0
sp_kill_cost_storno 15666 15666 0 0.00 0 9645 60 0 0
sp_kill_qstorno_ret_qs2qd 1076500 1076500 0 0.00 0 26590 40 0 0
sp_kill_qty_storno 567683 567683 0 0.00 0 27974 85 0 0
sp_lock_selected_doc 19943 19943 0 0.00 0 7916 7 0 0
sp_make_cost_storno 26634 26634 0 0.00 0 10136 52 0 0
sp_multiply_rows_for_pdistr 26632 26632 0 0.00 0 10312 32 0 0
sp_multiply_rows_for_qdistr 19738 19738 0 0.00 0 25281 189 0 0
sp_pay_from_customer 6690 6690 0 0.00 4 10830 108 0 0
sp_pay_to_supplier 5500 5500 0 0.00 5 12396 125 0 0
sp_payment_common 12188 12188 0 0.00 1 12343 20 0 0
sp_qd_handle_on_cancel_clo 1758 1758 0 0.00 0 9069 318 0 0
sp_qd_handle_on_invoice_upd_sts 10181 10181 0 0.00 0 27974 388 0 0
sp_qd_handle_on_reserve_upd_sts 9692 9692 0 0.00 0 17640 49 0 0
sp_reserve_write_off 9693 9693 0 0.00 7 17784 173 0 0
sp_supplier_invoice 5283 5283 0 0.00 7 82776 3529 0 0
sp_supplier_order 5230 5230 0 0.00 6 21104 1416 0 0
srv_aggregate_perf_data 105 105 0 0.00 3089 492908 19229 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1371 132922 43709 0 0
srv_find_qd_qs_mism 1010353 1010353 0 0.00 0 16857 5 0 0
srv_make_invnt_saldo 441 441 0 0.00 31 129024 3735 0 0
srv_make_money_saldo 711 711 0 0.00 1 15041 632 0 0
srv_recalc_idx_stat 4 4 0 0.00 171873 441125 326761 0 0
t$perf-abend:sp_add_invoice_to_stock 183 183 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4761 4761 0 0.00 0 10850 151 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 0 6811 121 0 0
t$perf-norm:sp_cancel_client_order 1758 1758 0 0.00 0 3215 15 0 0
t$perf-norm:sp_cancel_customer_reserve 652 652 0 0.00 0 4685 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 628 628 0 0.00 0 1805 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 375 375 0 0.00 0 2060 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 372 372 0 0.00 0 6768 39 0 0
t$perf-norm:sp_cancel_supplier_order 379 379 0 0.00 0 16305 1513 0 0
t$perf-norm:sp_cancel_write_off 941 941 0 0.00 0 3203 9 0 0
t$perf-norm:sp_client_order 19738 19738 0 0.00 0 7159 18 0 0
t$perf-norm:sp_customer_reserve 933 933 0 0.00 0 1494 8 0 0
t$perf-norm:sp_pay_from_customer 6690 6690 0 0.00 0 9349 9 0 0
t$perf-norm:sp_pay_to_supplier 5500 5500 0 0.00 0 4454 9 0 0
t$perf-norm:sp_reserve_write_off 9693 9693 0 0.00 0 4524 8 0 0
t$perf-norm:sp_supplier_invoice 5283 5283 0 0.00 0 8843 21 0 0
t$perf-norm:sp_supplier_order 5230 5230 0 0.00 0 10020 16 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 427 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 1033 17 0 0
t$perf-norm:srv_make_invnt_saldo 441 441 0 0.00 0 565 5 0 0
t$perf-norm:srv_make_money_saldo 711 711 0 0.00 0 2028 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 104 28 0 0
v_add_invoice_to_stock 9864 9498 366 3.71 0 12293 19 0 366
v_all_customers 35490 35490 0 0.00 0 142 1 0 0
v_all_suppliers 28958 28958 0 0.00 0 32 1 0 0
v_all_wares 39476 39476 0 0.00 0 32 1 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 446 6 0 0
v_cancel_client_order 3516 3516 0 0.00 0 2912 7 0 0
v_cancel_customer_prepayment 1256 1256 0 0.00 0 2343 9 0 0
v_cancel_customer_reserve 1304 1304 0 0.00 0 2494 8 0 0
v_cancel_payment_to_supplier 750 750 0 0.00 0 3094 12 0 0
v_cancel_supplier_invoice 766 744 22 2.87 0 1005 15 0 22
v_cancel_supplier_order 758 758 0 0.00 0 202 3 0 0
v_cancel_write_off 1882 1882 0 0.00 0 7016 24 0 0
v_max_id_clo_ord 5230 5230 0 0.00 0 4779 3 0 0
v_max_id_clo_res 933 933 0 0.00 0 3947 15 0 0
v_max_id_ord_sup 5278 5278 0 0.00 0 4575 4 0 0
v_max_non_paid_invoice 1528 1528 0 0.00 0 352 2 0 0
v_max_non_paid_realizn 3690 3690 0 0.00 0 6105 4 0 0
v_min_id_clo_ord 5230 5230 0 0.00 0 2306 5 0 0
v_min_id_clo_res 933 933 0 0.00 0 3328 13 0 0
v_min_id_ord_sup 5278 5278 0 0.00 0 3558 4 0 0
v_min_non_paid_invoice 5499 5499 0 0.00 0 12339 13 0 0
v_min_non_paid_realizn 6689 6689 0 0.00 0 4468 11 0 0
v_our_firm 9984 9984 0 0.00 0 142 1 0 0
v_reserve_write_off 19384 19384 0 0.00 0 4297 5 0 0
x_lock_depdocs_on_canc_invoice 11050 11050 0 0.00 0 23240 120 0 0
x_lock_depdocs_on_canc_sup_ord 379 379 0 0.00 0 5003 359 0 0
x_make_qstorno_1000_1200 5230 5230 0 0.00 2 21094 1343 0 0
x_make_qstorno_1200_2000 5283 5283 0 0.00 3 82761 3377 0 0
x_make_qstorno_2100_3300 443149 443149 0 0.00 0 104068 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-03-2021 07:42:29 to 31-03-2021 07:42:33.

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:09:46 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
05:10:47 37900 8192000000 9733015584 10029035520 809011712 8.988 100 93 186 0 839049696 7719792 22318192
05:12:37 21649 8192000000 9766482576 10071269376 837553696 9.275 100 89 177 1 872641360 13974752 21112912
05:14:08 7977 8192000000 9814046784 10129731584 868228720 9.582 100 83 166 0 924334480 36690976 19414784
05:15:27 9016 8192000000 9879668208 10213564416 927103568 10.166 100 77 153 0 983080384 39937920 16038896
05:16:37 8881 8192000000 9924576304 10278641664 978951872 10.674 100 75 150 3 1046478384 51767616 15758896
05:17:48 3806 8192000000 10004997296 10365657088 1017283216 11.046 100 80 159 0 1121063392 87860656 15919520
05:18:53 4050 8192000000 10017237584 10380832768 1041336944 11.278 100 83 166 0 1113737744 56110240 16290560
05:19:59 1542 8192000000 10036758304 10407190528 1063650832 11.491 100 94 187 1 1158073968 75014992 19408144
05:21:01 22780 8192000000 9984904112 10379386880 1070088704 11.553 100 36 72 2 1118585136 40223760 8272672
05:22:28 9602 8192000000 10002167568 10412052480 1084049904 11.686 100 30 61 0 1106904240 16827584 6026752
05:23:38 4931 8192000000 10002250160 10410729472 1094152736 11.782 100 31 61 2 1124514512 24032816 6328960
05:24:44 8063 8192000000 10001782608 10403356672 1098348768 11.822 100 34 69 1 1129156672 22909456 7898448
05:25:56 21094 8192000000 10041521248 10427047936 1107723152 11.911 100 74 148 0 1136703664 13072064 15908448
05:27:17 6223 8192000000 10022504880 10425692160 1101067648 11.848 100 39 77 4 1117446816 8195152 8184016
05:28:27 10266 8192000000 10001534656 10412326912 1055273328 11.411 96 29 58 1 1079454592 17933024 6248240
05:29:38 55280 8192000000 9947294144 10344673280 979050512 10.675 91 24 48 1 1017727904 33540752 5136640
05:31:35 6882 8192000000 9976196656 10368266240 1039163680 11.257 98 42 83 0 1064678896 15924096 9591120
05:32:44 7874 8192000000 9969314576 10355081216 1038821072 11.253 99 37 73 2 1062119888 14436176 8862640
05:33:58 9109 8192000000 9969329232 10362601472 997077616 10.850 96 39 78 0 1031717536 26186384 8453536
05:35:08 60871 8192000000 9948360448 10354237440 1036360416 11.230 98 25 50 1 1056414544 14142112 5912016
05:37:09 7527 8192000000 9973575760 10383253504 1061196064 11.468 100 26 52 0 1102029936 35076592 5757280
05:38:17 10133 8192000000 9987776304 10395164672 1075971920 11.609 100 26 52 1 1102167088 19227536 6967632
05:39:28 5414 8192000000 10010585392 10411855872 1093978144 11.780 100 35 68 1 1121166368 19402640 7785584
05:40:39 22856 8192000000 10046467792 10448281600 1111350032 11.945 100 39 78 1 1145917584 26729120 7838432
05:42:06 39188 8192000000 10082287600 10482143232 1123810080 12.063 100 53 103 3 1173897904 38382800 11705024
05:43:54 6105 8192000000 10160092800 10567360512 1141368464 12.228 100 65 130 1 1268894192 113074928 14450800
05:45:06 5391 8192000000 10145093952 10556624896 1150988416 12.319 100 50 97 0 1260877664 99737696 10151552
05:46:15 8562 8192000000 10176670592 10589659136 1158145440 12.386 100 45 90 0 1276465136 108351328 9968368
05:47:34 14441 8192000000 10198335680 10610778112 1170375264 12.500 100 46 93 0 1314077984 133364544 10338176
05:48:53 25115 8192000000 10265196208 10685730816 1168591232 12.484 99 59 118 0 1373660592 191348336 13721024
05:50:28 12423 8192000000 10283478496 10696261632 1176713200 12.560 100 58 116 0 1379926832 189763680 13449952
05:51:47 3360 8192000000 10285485216 10695561216 1166394768 12.463 99 67 134 1 1381841248 200553472 14893008
05:52:54 1981 8192000000 10334396160 10741035008 1175702816 12.550 99 80 157 1 1428136944 235398064 17036064
05:54:00 1959 8192000000 10359632320 10762117120 1166781536 12.467 100 85 169 0 1457879232 271578400 19519296
05:55:07 1370 8192000000 10377074864 10795479040 1167588352 12.474 100 82 161 1 1478290160 292218336 18483472
05:56:16 4409 8192000000 10383619312 10797096960 1159271104 12.396 100 72 142 0 1490800592 314498688 17030800
05:57:27 10882 8192000000 10379746336 10833731584 1156057008 12.366 100 41 82 1 1493599584 327323296 10219280
05:58:43 13272 8192000000 10373985568 10807730176 1152244976 12.331 100 45 90 1 1470850880 307541072 11064832
06:00:00 15597 8192000000 10391990096 10815418368 1159680352 12.400 100 40 80 2 1499180704 329890464 9609888
06:01:17 3799 8192000000 10398364176 10810007552 1139231776 12.208 99 48 95 1 1481415040 330734160 11449104
06:02:24 10406 8192000000 10434794752 10853580800 1137316912 12.190 100 41 82 1 1525376640 377067104 10992624
06:03:38 9602 8192000000 10469780224 10896584704 1150153328 12.311 100 43 85 0 1582135344 420162928 11819088
06:04:50 16582 8192000000 10410081456 10864865280 1144418624 12.257 100 27 53 1 1506091760 355023744 6649392
06:06:17 11786 8192000000 10429183504 10882441216 1132121968 12.141 100 35 70 1 1517763584 376742176 8899440
06:07:36 12540 8192000000 10457820432 10913464320 1126963248 12.093 100 24 48 1 1546189744 411919056 7307440
06:09:00 30050 8192000000 10220817040 10757390336 1101125568 11.848 98 19 37 1 1389195408 283160688 4909152
06:10:30 48916 8192000000 10108306480 10536325120 1126207728 12.086 99 36 68 3 1191398832 57659648 7531456
06:12:26 39916 8192000000 10116614512 10616020992 1133846688 12.158 100 16 32 1 1232981440 95363808 3770944
06:14:53 79849 8192000000 10073937904 10545410048 1105499072 11.890 97 13 26 0 1155380624 47635600 2245952
06:17:19 35082 8192000000 10109329984 10557804544 1137625920 12.193 100 40 81 0 1194675904 48358560 8691424
06:19:22 32930 8192000000 10099511904 10529337344 1154604272 12.353 100 31 62 3 1191679968 29509072 7566624
06:21:27 132921 8192000000 10139127536 10621792256 1141743136 12.232 99 13 25 1 1219458304 74718192 2996976
06:24:46 97179 8192000000 10110735280 10590625792 1134539344 12.164 98 8 16 1 1186205392 50200240 1465808
06:27:44 100042 8192000000 10070005712 10545762304 1140328240 12.219 99 9 18 1 1170736528 28403696 2004592
06:30:39 54107 8192000000 10091678352 10522955776 1148466864 12.295 100 46 92 2 1182080000 24360336 9252800
06:32:35 55299 8192000000 10158561952 10631008256 1164398736 12.444 100 54 107 1 1245977456 69636496 11942224
06:34:41 32389 8192000000 10086222608 10545168384 1141433056 12.229 98 11 18 0 1165559440 22437088 1689296
06:36:16 49665 8192000000 10100430064 10553991168 1135572544 12.174 98 11 22 0 1180312224 42189952 2549728
06:38:36 75086 8192000000 10086645920 10544705536 1109014480 11.923 96 16 33 1 1144587680 31896944 3676256
06:40:51 48450 8192000000 10094868512 10585612288 1124681984 12.071 99 4 8 1 1166341008 41142272 516752
06:42:42 74010 8192000000 10099038560 10586521600 1097759072 11.816 96 16 32 0 1203630928 102393984 3477872
06:44:57 72515 8192000000 10074889776 10540605440 1092713520 11.768 96 13 24 0 1159920336 64803552 2403264
06:47:16 30833 8192000000 10082994400 10530840576 1117382208 12.002 98 44 88 0 1169039008 42283616 9373184
06:48:47 61033 8192000000 10099970912 10528280576 1093440128 11.775 96 49 98 0 1175237712 71052896 10744688
06:50:51 31521 8192000000 10115804512 10559348736 1110247776 11.935 99 39 78 0 1205515648 87484096 7783776
06:52:23 56387 8192000000 10048469264 10477658112 1083291904 11.679 97 8 16 0 1109807152 25071280 1443968
06:55:03 58083 8192000000 10088907264 10536288256 1112556112 11.957 100 29 58 0 1176549520 58183088 5810320
06:57:02 79975 8192000000 10060491696 10505818112 1118210304 12.010 100 29 58 0 1160405008 35982016 6212688
07:00:53 46425 8192000000 10067337024 10528890880 1120838048 12.035 100 11 22 0 1166965664 43667968 2459648
07:02:44 56955 8192000000 10103358000 10563411968 1127186880 12.095 100 17 34 0 1212232064 81347792 3697392
07:04:43 57840 8192000000 10082483392 10552442880 1098368112 11.822 97 20 40 1 1177883792 75513488 4002192
07:06:41 84978 8192000000 10135997264 10621468672 1106477568 11.899 98 25 50 1 1246130320 133917024 5735728
07:09:08 59667 8192000000 10110525296 10561695744 1113199008 11.963 98 22 42 3 1182649440 64596752 4853680
07:11:08 79662 8192000000 10104234480 10561482752 1117722336 12.005 98 16 32 0 1179938032 59154736 3060960
07:13:28 53147 8192000000 10066339376 10505740288 1140322224 12.219 100 21 42 2 1175336384 30565328 4448832
07:15:21 35515 8192000000 10083837856 10513694720 1128358752 12.106 99 35 70 0 1166077216 30627648 7090816
07:16:57 46460 8192000000 10087546976 10524467200 1135436336 12.173 99 35 71 0 1196265296 53707456 7121504
07:18:50 58842 8192000000 10101752384 10572480512 1142055152 12.235 100 30 60 0 1206994976 58568224 6371600
07:20:58 45929 8192000000 10064729888 10541744128 1111935248 11.951 97 10 19 1 1144306816 30191712 2179856
07:22:56 60434 8192000000 10073042528 10575446016 1144707728 12.260 100 6 12 1 1187239312 41363312 1168272
07:24:57 71329 8192000000 10061660976 10519883776 1088473248 11.728 95 9 18 0 1119891152 29896208 1521696
07:27:09 73764 8192000000 10111958864 10563502080 1131637216 12.137 99 24 48 1 1196328224 58464384 6226624
07:29:23 35879 8192000000 10138331744 10582831104 1123212864 12.057 98 28 55 1 1207848240 78276736 6358640
07:30:59 65922 8192000000 10112923744 10565386240 1134928576 12.168 99 23 46 2 1181980176 42122576 4929024
07:33:23 53119 8192000000 10123631712 10567450624 1096083648 11.800 96 37 74 2 1181006416 76951216 7971552
07:35:22 59014 8192000000 10065568976 10498203648 1091708432 11.759 96 20 40 0 1120510976 24763472 4039072
07:37:23 124599 8192000000 10057164960 10532532224 1094438480 11.785 97 16 32 0 1141668544 44116976 3113088

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 31-03-2021 07:42:34 to 31-03-2021 07:42:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 183 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-03-2021 07:42:50 to 31-03-2021 07:42:52.

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          233801
MON$OLDEST_ACTIVE               234261
MON$OLDEST_SNAPSHOT             234261
MON$NEXT_TRANSACTION            234261
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                       2131200
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             795
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2395 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2395 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2395 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.2395 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'

31.03.21 07:42:53. 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-31 07:42:56 No severe PSQL-related problems occured
31.03.21 07:42:57. End of report.