Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.11.2020 07:37
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 17065272 kB
MemAvailable 31522892 kB
Buffers 12 kB
Cached 14509992 kB
SwapCached 2664 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 2020-11-29 07:37:16
DTS_END                         2020-11-29 07:37:16.8810
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/36910
TRN_ID                          234536
ATT_ID                          687
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: 8877.63 24019 1065316
customer order: creation 164.89 429 19787
customer order: refuse 14.13 374 1695
order to supplier: creation 44.17 1325 5300
order to supplier: removal 3.21 126114 385
invoice (draft): creation 44.84 3896 5381
invoice (draft): removal 48.63 1014 5835
invoice accept: apply 40.76 17292 4891
invoice accept: cancel 44.45 820 5334
customer reserve: creation 3827.25 166 459270
customer reserve: removal 4373.45 73 524814
realization accept: apply 80.31 168 9637
realization accept: cancel 67.53 132 8104
payment from customer: creation 57.18 103 6861
payment from customer: removal 5.63 96 675
payment to supplier: creation 47.25 121 5670
payment to supplier: removal 3.42 87 410
service: total inventory turnovers 3.61 3388 433
service: total monetary turnovers 5.98 500 718
service: aggregate perf. data 0.93 14432 112
service: refresh index statistics 0.03 309849 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-11-2020 07:37:56 to 29-11-2020 07:37:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3740
TEST_TIME 2 3999
TEST_TIME 3 6137
TEST_TIME 4 5890
TEST_TIME 5 5509
TEST_TIME 6 5363
TEST_TIME 7 5533
TEST_TIME 8 5616
TEST_TIME 9 5741
TEST_TIME 10 5875
TEST_TIME 11 6110
TEST_TIME 12 6238
TEST_TIME 13 6398
TEST_TIME 14 6349
TEST_TIME 15 6448
TEST_TIME 16 6711
TEST_TIME 17 7098
TEST_TIME 18 7273
TEST_TIME 19 8100
TEST_TIME 20 8108
TEST_TIME 21 8275
TEST_TIME 22 9365
TEST_TIME 23 9597
TEST_TIME 24 9885
TEST_TIME 25 10242
TEST_TIME 26 11804
TEST_TIME 27 11427
TEST_TIME 28 11087
TEST_TIME 29 10868
TEST_TIME 30 10698
TEST_TIME 31 10630
TEST_TIME 32 10459
TEST_TIME 33 10324
TEST_TIME 34 10208
TEST_TIME 35 10024
TEST_TIME 36 9952
TEST_TIME 37 9963
TEST_TIME 38 9744
TEST_TIME 39 9707
TEST_TIME 40 9833
TEST_TIME 41 9849
TEST_TIME 42 9843
TEST_TIME 43 9814
TEST_TIME 44 9837
TEST_TIME 45 9850
TEST_TIME 46 9853
TEST_TIME 47 9767
TEST_TIME 48 9748
TEST_TIME 49 9711
TEST_TIME 50 9840
TEST_TIME 51 9777
TEST_TIME 52 9597
TEST_TIME 53 9521
TEST_TIME 54 9398
TEST_TIME 55 9324
TEST_TIME 56 9290
TEST_TIME 57 9318
TEST_TIME 58 9298
TEST_TIME 59 9256
TEST_TIME 60 9221
TEST_TIME 61 9281
TEST_TIME 62 9273
TEST_TIME 63 9361
TEST_TIME 64 9351
TEST_TIME 65 9289
TEST_TIME 66 9415
TEST_TIME 67 9424
TEST_TIME 68 9418
TEST_TIME 69 9423
TEST_TIME 70 9477
TEST_TIME 71 9369
TEST_TIME 72 9276
TEST_TIME 73 9203
TEST_TIME 74 9135
TEST_TIME 75 9120
TEST_TIME 76 9058
TEST_TIME 77 9020
TEST_TIME 78 9039
TEST_TIME 79 9011
TEST_TIME 80 9017
TEST_TIME 81 9142
TEST_TIME 82 9108
TEST_TIME 83 9133
TEST_TIME 84 9087
TEST_TIME 85 9119
TEST_TIME 86 9203
TEST_TIME 87 9129
TEST_TIME 88 9060
TEST_TIME 89 9002
TEST_TIME 90 8992
TEST_TIME 91 8947
TEST_TIME 92 8943
TEST_TIME 93 8908
TEST_TIME 94 8954
TEST_TIME 95 8918
TEST_TIME 96 8982
TEST_TIME 97 9001
TEST_TIME 98 9001
TEST_TIME 99 8959
TEST_TIME 100 9035
TEST_TIME 101 9026
TEST_TIME 102 9028
TEST_TIME 103 9048
TEST_TIME 104 9139
TEST_TIME 105 9109
TEST_TIME 106 9033
TEST_TIME 107 8983
TEST_TIME 108 8939
TEST_TIME 109 8901
TEST_TIME 110 8910
TEST_TIME 111 8886
TEST_TIME 112 8873
TEST_TIME 113 8854
TEST_TIME 114 8865
TEST_TIME 115 8907
TEST_TIME 116 8891
TEST_TIME 117 8883
TEST_TIME 118 8902
TEST_TIME 119 8908
TEST_TIME 120 8880

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-11-2020 07:38:00 to 29-11-2020 07:38:03.

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 1488325 1488325 0 0.00 0 20337 6 0 0
doc_list_biud 1488325 1488325 0 0.00 0 57992 32 0 0
sp_add_invoice_to_stock 4974 4891 83 1.66 41 185999 17293 0 83
sp_cancel_adding_invoice 5334 5334 0 0.00 5 59969 821 0 0
sp_cancel_client_order 1695 1695 0 0.00 4 16491 374 0 0
sp_cancel_customer_reserve 524814 524814 0 0.00 1 33882 74 0 0
sp_cancel_pay_from_customer 675 675 0 0.00 3 7002 96 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 3 6385 88 0 0
sp_cancel_supplier_invoice 5841 5835 6 0.10 2 24935 1014 0 6
sp_cancel_supplier_order 385 385 0 0.00 25 1123321 126114 0 0
sp_cancel_write_off 8104 8104 0 0.00 1 13387 132 0 0
sp_client_order 19787 19787 0 0.00 4 31724 429 0 0
sp_customer_reserve 459270 459270 0 0.00 0 116397 166 0 0
sp_fill_shopping_cart 30462 30462 0 0.00 1 16542 50 0 0
sp_fill_shopping_cart_clo_res 459267 459267 0 0.00 0 28264 19 0 0
sp_get_clo_for_invoice 4887 4887 0 0.00 0 52892 468 0 0
sp_kill_cost_storno 14523 14523 0 0.00 0 9164 60 0 0
sp_kill_qstorno_ret_qs2qd 1063952 1063952 0 0.00 0 33868 40 0 0
sp_kill_qty_storno 560691 560691 0 0.00 0 57991 85 0 0
sp_lock_selected_doc 20139 20139 0 0.00 0 7419 6 0 0
sp_make_cost_storno 27055 27055 0 0.00 0 16699 52 0 0
sp_multiply_rows_for_pdistr 27055 27055 0 0.00 0 8065 29 0 0
sp_multiply_rows_for_qdistr 19787 19787 0 0.00 0 31714 245 0 0
sp_pay_from_customer 6861 6861 0 0.00 4 8214 103 0 0
sp_pay_to_supplier 5670 5670 0 0.00 5 11544 122 0 0
sp_payment_common 12531 12531 0 0.00 1 8263 15 0 0
sp_qd_handle_on_cancel_clo 1695 1695 0 0.00 0 16482 344 0 0
sp_qd_handle_on_invoice_upd_sts 10221 10221 0 0.00 0 57990 363 0 0
sp_qd_handle_on_reserve_upd_sts 9637 9637 0 0.00 0 14222 43 0 0
sp_reserve_write_off 9637 9637 0 0.00 7 16721 169 0 0
sp_supplier_invoice 5381 5381 0 0.00 7 91383 3897 0 0
sp_supplier_order 5300 5300 0 0.00 6 33636 1326 0 0
srv_aggregate_perf_data 112 112 0 0.00 3133 212570 14432 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1082 148044 49324 0 0
srv_find_qd_qs_mism 1017704 1017704 0 0.00 0 20337 6 0 0
srv_make_invnt_saldo 433 433 0 0.00 21 110143 3389 0 0
srv_make_money_saldo 718 718 0 0.00 1 13391 500 0 0
srv_recalc_idx_stat 4 4 0 0.00 211670 402632 309849 0 0
t$perf-abend:sp_add_invoice_to_stock 83 83 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4891 4891 0 0.00 0 12518 162 0 0
t$perf-norm:sp_cancel_adding_invoice 428 428 0 0.00 0 6915 103 0 0
t$perf-norm:sp_cancel_client_order 1695 1695 0 0.00 0 1506 8 0 0
t$perf-norm:sp_cancel_customer_reserve 677 677 0 0.00 0 646 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 675 675 0 0.00 0 215 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 222 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 374 374 0 0.00 0 601 11 0 0
t$perf-norm:sp_cancel_supplier_order 385 385 0 0.00 0 18179 1441 0 0
t$perf-norm:sp_cancel_write_off 972 972 0 0.00 0 4451 13 0 0
t$perf-norm:sp_client_order 19787 19787 0 0.00 0 10343 16 0 0
t$perf-norm:sp_customer_reserve 1002 1002 0 0.00 0 6138 19 0 0
t$perf-norm:sp_pay_from_customer 6861 6861 0 0.00 0 8492 8 0 0
t$perf-norm:sp_pay_to_supplier 5670 5670 0 0.00 0 6179 7 0 0
t$perf-norm:sp_reserve_write_off 9637 9637 0 0.00 0 13395 13 0 0
t$perf-norm:sp_supplier_invoice 5381 5381 0 0.00 0 13145 20 0 0
t$perf-norm:sp_supplier_order 5300 5300 0 0.00 0 9317 17 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 528 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 1162 21 0 0
t$perf-norm:srv_make_invnt_saldo 433 433 0 0.00 0 841 11 0 0
t$perf-norm:srv_make_money_saldo 718 718 0 0.00 0 504 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 3 0 0
v_add_invoice_to_stock 9940 9774 166 1.67 0 9126 17 0 166
v_all_customers 36166 36166 0 0.00 0 62 1 0 0
v_all_suppliers 29390 29390 0 0.00 0 17 1 0 0
v_all_wares 39574 39574 0 0.00 0 52 1 0 0
v_cancel_adding_invoice 856 856 0 0.00 0 74 5 0 0
v_cancel_client_order 3390 3390 0 0.00 0 4521 6 0 0
v_cancel_customer_prepayment 1350 1350 0 0.00 0 6780 15 0 0
v_cancel_customer_reserve 1354 1354 0 0.00 0 1747 4 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 6372 15 0 0
v_cancel_supplier_invoice 760 748 12 1.57 0 1124 15 0 12
v_cancel_supplier_order 770 770 0 0.00 0 1657 6 0 0
v_cancel_write_off 1944 1944 0 0.00 0 2332 13 0 0
v_max_id_clo_ord 5300 5300 0 0.00 0 658 1 0 0
v_max_id_clo_res 1002 1002 0 0.00 0 6654 14 0 0
v_max_id_ord_sup 5375 5375 0 0.00 0 7208 11 0 0
v_max_non_paid_invoice 1650 1650 0 0.00 0 85 2 0 0
v_max_non_paid_realizn 3746 3746 0 0.00 0 823 3 0 0
v_min_id_clo_ord 5300 5300 0 0.00 0 2237 4 0 0
v_min_id_clo_res 1002 1002 0 0.00 0 6705 17 0 0
v_min_id_ord_sup 5375 5375 0 0.00 0 4153 5 0 0
v_min_non_paid_invoice 5670 5670 0 0.00 0 6329 8 0 0
v_min_non_paid_realizn 6861 6861 0 0.00 0 5566 11 0 0
v_our_firm 9638 9638 0 0.00 0 14 1 0 0
v_reserve_write_off 19272 19272 0 0.00 0 6842 6 0 0
x_lock_depdocs_on_canc_invoice 10795 10795 0 0.00 0 26654 111 0 0
x_lock_depdocs_on_canc_sup_ord 385 385 0 0.00 0 6588 327 0 0
x_make_qstorno_1000_1200 5300 5300 0 0.00 2 33626 1258 0 0
x_make_qstorno_1200_2000 5381 5381 0 0.00 4 91342 3683 0 0
x_make_qstorno_2100_3300 459270 459270 0 0.00 0 116395 145 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-11-2020 07:38:04 to 29-11-2020 07:38:08.

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:07:20 2 8192000000 8796641312 8807108608 3168704 0.038 1 1 2 0 3266400 61840 35856
05:08:21 25912 8192000000 9728457904 10026278912 802062384 8.917 100 84 168 0 830722000 7684400 20975216
05:09:50 20875 8192000000 9771055120 10081546240 843198816 9.332 100 92 184 2 877991712 12949440 21843456
05:11:18 26692 8192000000 9824443968 10145046528 878259440 9.682 100 85 170 2 923198352 25892496 19046416
05:12:48 8382 8192000000 9885244368 10222080000 930750640 10.202 100 80 160 2 996644240 48724192 17169408
05:14:04 7166 8192000000 9936189200 10282487808 958271792 10.472 100 74 147 0 1054540944 80683248 15585904
05:15:17 4980 8192000000 9958640704 10317197312 1001944016 10.897 100 67 134 0 1075173904 59650800 13579088
05:16:24 2679 8192000000 9997887312 10365865984 1038834992 11.253 100 78 154 3 1105007568 50172048 16000528
05:17:30 1640 8192000000 10027798816 10396827648 1058503296 11.442 100 93 185 0 1146785456 69009392 19272768
05:18:33 40130 8192000000 10006127888 10394529792 1068710048 11.540 100 55 108 2 1137766560 58531184 10525328
05:20:14 8167 8192000000 9999962960 10399932416 1083407104 11.680 100 38 76 2 1115150176 23706432 8036640
05:21:28 8950 8192000000 10004955200 10407936000 1091182816 11.754 100 31 63 2 1128283920 30178512 6922592
05:22:40 7868 8192000000 10004534880 10398486528 1097927616 11.818 100 55 107 4 1125891568 14758592 13205360
05:23:48 23935 8192000000 10027548096 10442711040 1098413408 11.823 100 35 70 0 1133040992 27079200 7548384
05:25:13 7647 8192000000 10014633632 10428506112 1101902032 11.856 100 51 101 3 1146203872 33630160 10671680
05:26:23 3003 8192000000 10020198240 10417549312 1096872864 11.808 100 37 74 1 1131646784 26003680 8770240
05:27:26 4884 8192000000 10010928880 10395799552 1084686720 11.692 100 44 88 1 1117087456 22630080 9770656
05:28:34 21654 8192000000 9975982000 10376179712 1006636304 10.943 93 21 42 1 1025177840 14403328 4138208
05:29:57 4008 8192000000 9949359744 10326511616 1019547792 11.068 98 53 107 0 1051739824 20708192 11483840
05:31:01 4881 8192000000 9945358624 10322546688 997035008 10.850 98 54 107 3 1032367760 23332416 12000336
05:32:08 80972 8192000000 9936959936 10336350208 996844912 10.848 96 24 46 2 1020656336 18225328 5586096
05:34:30 8244 8192000000 9978907296 10375766016 1049634048 11.357 100 42 83 2 1068580160 9305536 9640576
05:35:39 9639 8192000000 9979914432 10388983808 1057257040 11.430 100 42 84 1 1093104624 26388080 9459504
05:36:50 8562 8192000000 9993174528 10395189248 1065962208 11.514 100 53 106 1 1100263088 22854960 11445920
05:37:59 79098 8192000000 10024589344 10428497920 1091208272 11.754 100 35 70 0 1115837568 17412592 7216704
05:40:25 5383 8192000000 10102158432 10499010560 1115707888 11.986 100 73 145 1 1202420240 72158368 14553984
05:41:34 2179 8192000000 10117697136 10521788416 1128436832 12.107 100 64 122 3 1228082080 86314800 13330448
05:42:41 16166 8192000000 10130376912 10536837120 1131565968 12.136 99 52 103 2 1230219216 87623216 11030032
05:44:08 18565 8192000000 10221034880 10636087296 1160871600 12.411 100 64 128 1 1320242256 144558240 14812416
05:45:31 9055 8192000000 10249815488 10664255488 1169169344 12.489 100 55 111 1 1351013536 169176464 12667728
05:46:45 8009 8192000000 10287938096 10702503936 1167789808 12.476 100 56 110 2 1393585216 213479136 12316272
05:47:57 12264 8192000000 10323250160 10736508928 1160198272 12.405 99 46 92 0 1401325824 230729600 10397952
05:49:13 1698 8192000000 10345105136 10748207104 1169448112 12.492 100 85 169 2 1445622848 257066128 19108608
05:50:22 3600 8192000000 10373329680 10789691392 1170277952 12.499 100 65 128 0 1479290096 293813216 15198928
05:51:31 1995 8192000000 10408833776 10806362112 1151993248 12.328 99 84 167 2 1502599488 330640624 19965616
05:52:41 2417 8192000000 10459267248 10859216896 1162738544 12.429 100 85 169 1 1558149104 375417152 19993408
05:53:49 1080 8192000000 10547322016 10944286720 1166878304 12.468 100 88 176 0 1633841312 446065120 20897888
05:54:55 1673 8192000000 10564063968 10966757376 1173413168 12.529 100 86 172 0 1653065136 458169744 21482224
05:56:05 10712 8192000000 10591474352 11016531968 1153424144 12.342 99 56 112 0 1678025568 510086080 14515344
05:57:18 11538 8192000000 10503876464 10960785408 1141707824 12.232 100 35 70 1 1606369696 454895936 9765936
05:58:51 14258 8192000000 10483744288 10986438656 1144811024 12.261 100 35 69 0 1644140288 489435712 9893552
06:00:08 30076 8192000000 10378895344 10840428544 1148972816 12.300 100 26 52 1 1504623040 348767376 6882848
06:01:40 19220 8192000000 10379175056 10868502528 1115760000 11.987 100 17 34 1 1474930944 353687376 5483568
06:03:03 14701 8192000000 10190850544 10706239488 1106401920 11.898 99 16 32 1 1343275456 232818832 4054704
06:04:22 77755 8192000000 10051236576 10490691584 1122351616 12.049 100 37 72 0 1151836800 21766304 7718880
06:06:46 104868 8192000000 10060412432 10496622592 1089099312 11.734 96 18 36 0 1108360096 15985760 3275024
06:09:32 98101 8192000000 10049157664 10510123008 1113038992 11.961 98 16 32 1 1146990864 30797424 3154448
06:12:11 51715 8192000000 10090150544 10531880960 1080566672 11.653 95 23 46 1 1149515280 64294528 4654080
06:14:06 43760 8192000000 10111427760 10586365952 1116505328 11.994 99 29 58 2 1214309152 91117888 6685936
06:15:54 61335 8192000000 10142862192 10610061312 1131635568 12.137 100 21 42 1 1247128944 110097216 5396160
06:18:26 148043 8192000000 10090529824 10555957248 1112857424 11.959 98 20 40 2 1196445952 79505728 4082800
06:22:02 77821 8192000000 10088803136 10548748288 1110579312 11.938 98 25 50 2 1154599072 38695792 5323968
06:24:25 37325 8192000000 10057839152 10490101760 1123578848 12.061 99 30 59 1 1164415904 34456992 6380064
06:26:09 107896 8192000000 10078876144 10589384704 1102802128 11.864 97 14 28 0 1163882800 58321968 2758704
06:28:58 110683 8192000000 10085606080 10552434688 1142318640 12.237 100 25 50 0 1198961568 51384720 5258208
06:32:07 69957 8192000000 10076049856 10538262528 1093048624 11.772 97 13 26 1 1149960896 53927168 2985104
06:34:19 37419 8192000000 10056591264 10552352768 1124168368 12.066 100 11 22 0 1161258160 34421040 2668752
06:35:57 41364 8192000000 10075023632 10532540416 1109568416 11.928 98 19 38 0 1153159936 39969120 3622400
06:38:14 48587 8192000000 10104156944 10567340032 1110697728 11.939 98 15 30 0 1174262288 60328720 3235840
06:40:15 69237 8192000000 10063226448 10534395904 1094044144 11.781 97 12 24 0 1159207072 62792784 2370144
06:42:31 60696 8192000000 10111088976 10564014080 1059799104 11.455 94 39 78 1 1134368320 66930592 7638624
06:44:37 81382 8192000000 10083898992 10513137664 1089656048 11.739 97 21 42 0 1143517776 49756080 4105648
06:47:02 58452 8192000000 10077452928 10560724992 1091875776 11.760 98 28 56 2 1133541040 35492480 6172784
06:49:08 85588 8192000000 10045659504 10503245824 1107334784 11.907 99 16 32 0 1128866320 18202496 3329040
06:51:48 30994 8192000000 10051001008 10519629824 1112933328 11.960 100 4 8 2 1167279600 53514400 831872
06:53:29 53102 8192000000 10081120336 10522767360 1083281792 11.679 96 16 31 2 1141756240 55000192 3474256
06:55:25 58991 8192000000 10137922464 10629062656 1127696640 12.100 100 23 46 2 1228063504 95304848 5062016
06:57:25 64557 8192000000 10065627552 10588995584 1108688048 11.920 98 29 59 1 1206838608 91604512 6546048
06:59:38 52372 8192000000 10108465328 10547150848 1129140528 12.113 99 30 60 1 1206770000 70719456 6910016
07:01:31 33406 8192000000 10120176064 10544762880 1101185088 11.849 97 59 117 1 1184020688 71134608 11700992
07:03:11 111103 8192000000 10059174896 10500276224 1111867216 11.950 98 23 45 4 1138279472 21846816 4565440
07:06:15 47179 8192000000 10083188624 10535784448 1120413632 12.031 99 20 40 0 1176581120 51723072 4444416
07:08:02 33134 8192000000 10061024656 10565521408 1130779904 12.129 100 4 8 0 1182341520 51109392 452224
07:09:37 105539 8192000000 10121708064 10628730880 1127889024 12.101 99 17 33 1 1178379904 47091072 3399808
07:12:23 75076 8192000000 10119932832 10581307392 1139862304 12.214 99 29 55 3 1197575216 51349312 6363600
07:14:52 86277 8192000000 10099582336 10529083392 1129768864 12.119 98 27 55 1 1160620704 24605168 6246672
07:17:21 39501 8192000000 10110987104 10601033728 1101466688 11.852 96 22 44 1 1173340304 67043424 4830192
07:19:08 30054 8192000000 10077350384 10506362880 1112244208 11.954 98 14 28 2 1146132592 30985984 2902400
07:20:39 58673 8192000000 10116105632 10650689536 1117196432 12.000 98 25 50 1 1238470160 116157936 5115792
07:22:41 98334 8192000000 10065265200 10524213248 1125114304 12.075 99 14 28 0 1158937744 30916912 2906528
07:25:22 60035 8192000000 10082509744 10551676928 1135140400 12.170 100 13 27 0 1179513408 41672256 2700752
07:27:58 55405 8192000000 10067793856 10502676480 1073674880 11.587 95 24 47 0 1111231232 33128224 4428128
07:29:57 46688 8192000000 10090639344 10539270144 1097956656 11.818 98 33 66 0 1172900928 68068928 6875344
07:31:44 63765 8192000000 10059619632 10501550080 1095457536 11.795 98 22 45 1 1174393072 74123152 4812384
07:33:49 37479 8192000000 10088081120 10525581312 1097411728 11.813 98 24 48 1 1161720256 59117040 5191488
07:35:44 80685 8192000000 10066007808 10543341568 1043449008 11.298 94 21 42 0 1120481200 72730928 4301264

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-11-2020 07:38:10 to 29-11-2020 07:38:25.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-11-2020 07:38:26 to 29-11-2020 07:38:28.

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          234577
MON$OLDEST_ACTIVE               235202
MON$OLDEST_SNAPSHOT             235202
MON$NEXT_TRANSACTION            235202
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                       2081248
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             806
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2280 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2280 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2280 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.2280 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sun Nov 29 07:37:24 2020
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Sun Nov 29 07:37:25 2020
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
--- end of diff output ---

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

29.11.20 07:38:29. 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
2020-11-29 07:38:32 No severe PSQL-related problems occured
29.11.20 07:38:33. End of report.