Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.01.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 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 16768396 kB
MemAvailable 31250968 kB
Buffers 0 kB
Cached 14878028 kB
SwapCached 4868 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-01-25 07:26:55
DTS_END                         2021-01-25 07:26:55.4460
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/50890
TRN_ID                          221807
ATT_ID                          666
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: 8482.56 24829 1017907
customer order: creation 156.19 416 18743
customer order: refuse 13.09 382 1571
order to supplier: creation 41.34 1397 4961
order to supplier: removal 3.23 119951 387
invoice (draft): creation 42.43 4303 5091
invoice (draft): removal 48.81 996 5857
invoice accept: apply 37.26 19226 4471
invoice accept: cancel 44.03 862 5284
customer reserve: creation 3497.23 185 419667
customer reserve: removal 4324.52 70 518943
realization accept: apply 76.15 196 9138
realization accept: cancel 81.90 125 9828
payment from customer: creation 53.38 114 6406
payment from customer: removal 5.11 88 613
payment to supplier: creation 44.93 149 5391
payment to supplier: removal 3.08 66 369
service: total inventory turnovers 3.40 3902 408
service: total monetary turnovers 5.63 561 676
service: aggregate perf. data 0.82 19117 98
service: refresh index statistics 0.04 324473 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 25-01-2021 07:28:10 to 25-01-2021 07:28:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2176
TEST_TIME 2 2681
TEST_TIME 3 3300
TEST_TIME 4 4051
TEST_TIME 5 4267
TEST_TIME 6 4327
TEST_TIME 7 4456
TEST_TIME 8 4809
TEST_TIME 9 4839
TEST_TIME 10 5200
TEST_TIME 11 5384
TEST_TIME 12 5519
TEST_TIME 13 5719
TEST_TIME 14 5877
TEST_TIME 15 6015
TEST_TIME 16 6349
TEST_TIME 17 6465
TEST_TIME 18 6914
TEST_TIME 19 7183
TEST_TIME 20 7503
TEST_TIME 21 7838
TEST_TIME 22 8179
TEST_TIME 23 8496
TEST_TIME 24 8832
TEST_TIME 25 8994
TEST_TIME 26 9412
TEST_TIME 27 10009
TEST_TIME 28 10104
TEST_TIME 29 10382
TEST_TIME 30 10101
TEST_TIME 31 9863
TEST_TIME 32 9850
TEST_TIME 33 9677
TEST_TIME 34 9605
TEST_TIME 35 9468
TEST_TIME 36 9439
TEST_TIME 37 9255
TEST_TIME 38 9182
TEST_TIME 39 9237
TEST_TIME 40 9078
TEST_TIME 41 9170
TEST_TIME 42 9030
TEST_TIME 43 8972
TEST_TIME 44 8887
TEST_TIME 45 8847
TEST_TIME 46 8828
TEST_TIME 47 9079
TEST_TIME 48 9064
TEST_TIME 49 8988
TEST_TIME 50 9037
TEST_TIME 51 8955
TEST_TIME 52 9005
TEST_TIME 53 8971
TEST_TIME 54 9033
TEST_TIME 55 9000
TEST_TIME 56 9044
TEST_TIME 57 9029
TEST_TIME 58 8932
TEST_TIME 59 8824
TEST_TIME 60 8761
TEST_TIME 61 8651
TEST_TIME 62 8592
TEST_TIME 63 8570
TEST_TIME 64 8582
TEST_TIME 65 8759
TEST_TIME 66 8702
TEST_TIME 67 8744
TEST_TIME 68 8733
TEST_TIME 69 8804
TEST_TIME 70 8770
TEST_TIME 71 8793
TEST_TIME 72 8851
TEST_TIME 73 8900
TEST_TIME 74 8861
TEST_TIME 75 8857
TEST_TIME 76 8881
TEST_TIME 77 8817
TEST_TIME 78 8712
TEST_TIME 79 8743
TEST_TIME 80 8671
TEST_TIME 81 8610
TEST_TIME 82 8545
TEST_TIME 83 8509
TEST_TIME 84 8555
TEST_TIME 85 8523
TEST_TIME 86 8494
TEST_TIME 87 8503
TEST_TIME 88 8511
TEST_TIME 89 8535
TEST_TIME 90 8562
TEST_TIME 91 8604
TEST_TIME 92 8645
TEST_TIME 93 8668
TEST_TIME 94 8664
TEST_TIME 95 8742
TEST_TIME 96 8692
TEST_TIME 97 8659
TEST_TIME 98 8600
TEST_TIME 99 8574
TEST_TIME 100 8556
TEST_TIME 101 8587
TEST_TIME 102 8595
TEST_TIME 103 8606
TEST_TIME 104 8584
TEST_TIME 105 8569
TEST_TIME 106 8571
TEST_TIME 107 8589
TEST_TIME 108 8602
TEST_TIME 109 8657
TEST_TIME 110 8623
TEST_TIME 111 8673
TEST_TIME 112 8735
TEST_TIME 113 8697
TEST_TIME 114 8635
TEST_TIME 115 8602
TEST_TIME 116 8569
TEST_TIME 117 8544
TEST_TIME 118 8520
TEST_TIME 119 8518
TEST_TIME 120 8486
TEST_TIME 121 8416

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-01-2021 07:28:14 to 25-01-2021 07:28:17.

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 1407258 1407258 0 0.00 0 25469 7 0 0
doc_list_biud 1407258 1407258 0 0.00 0 39420 33 0 0
sp_add_invoice_to_stock 4533 4471 62 1.36 35 237338 19226 0 62
sp_cancel_adding_invoice 5284 5284 0 0.00 5 55101 862 0 0
sp_cancel_client_order 1571 1571 0 0.00 4 10163 383 0 0
sp_cancel_customer_reserve 518943 518943 0 0.00 1 33374 71 0 0
sp_cancel_pay_from_customer 613 613 0 0.00 3 6478 89 0 0
sp_cancel_pay_to_supplier 369 369 0 0.00 2 1092 66 0 0
sp_cancel_supplier_invoice 5865 5857 8 0.13 3 22483 997 0 8
sp_cancel_supplier_order 387 387 0 0.00 8 1216978 119951 0 0
sp_cancel_write_off 9828 9828 0 0.00 1 28243 125 0 0
sp_client_order 18743 18743 0 0.00 4 22273 417 0 0
sp_customer_reserve 419667 419667 0 0.00 0 99292 185 0 0
sp_fill_shopping_cart 28773 28773 0 0.00 1 18437 51 0 0
sp_fill_shopping_cart_clo_res 419659 419659 0 0.00 0 30592 18 0 0
sp_get_clo_for_invoice 4463 4463 0 0.00 0 54118 496 0 0
sp_kill_cost_storno 16094 16094 0 0.00 0 17447 65 0 0
sp_kill_qstorno_ret_qs2qd 1053958 1053958 0 0.00 0 33306 38 0 0
sp_kill_qty_storno 555471 555471 0 0.00 0 39420 82 0 0
sp_lock_selected_doc 18883 18883 0 0.00 0 8447 4 0 0
sp_make_cost_storno 25398 25398 0 0.00 0 20185 64 0 0
sp_multiply_rows_for_pdistr 25398 25398 0 0.00 0 19436 35 0 0
sp_multiply_rows_for_qdistr 18743 18743 0 0.00 0 17617 218 0 0
sp_pay_from_customer 6406 6406 0 0.00 4 18033 114 0 0
sp_pay_to_supplier 5391 5391 0 0.00 4 21340 149 0 0
sp_payment_common 11797 11797 0 0.00 1 17691 25 0 0
sp_qd_handle_on_cancel_clo 1571 1571 0 0.00 0 10155 360 0 0
sp_qd_handle_on_invoice_upd_sts 9747 9747 0 0.00 0 39420 386 0 0
sp_qd_handle_on_reserve_upd_sts 9138 9138 0 0.00 0 22048 60 0 0
sp_reserve_write_off 9138 9138 0 0.00 7 22083 196 0 0
sp_supplier_invoice 5091 5091 0 0.00 7 131105 4303 0 0
sp_supplier_order 4961 4961 0 0.00 5 88058 1398 0 0
srv_aggregate_perf_data 98 98 0 0.00 2580 428607 19118 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1206 158966 50851 0 0
srv_find_qd_qs_mism 974987 974987 0 0.00 0 25468 6 0 0
srv_make_invnt_saldo 408 408 0 0.00 38 175321 3902 0 0
srv_make_money_saldo 676 676 0 0.00 1 18305 562 0 0
srv_recalc_idx_stat 5 5 0 0.00 216731 468346 324473 0 0
t$perf-abend:sp_add_invoice_to_stock 62 62 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4471 4471 0 0.00 0 18998 174 0 0
t$perf-norm:sp_cancel_adding_invoice 392 392 0 0.00 0 2662 73 0 0
t$perf-norm:sp_cancel_client_order 1571 1571 0 0.00 0 1771 13 0 0
t$perf-norm:sp_cancel_customer_reserve 632 632 0 0.00 0 436 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 613 613 0 0.00 0 2721 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 369 369 0 0.00 0 1390 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 367 367 0 0.00 0 5281 32 0 0
t$perf-norm:sp_cancel_supplier_order 387 387 0 0.00 0 20744 1509 0 0
t$perf-norm:sp_cancel_write_off 954 954 0 0.00 0 729 5 0 0
t$perf-norm:sp_client_order 18743 18743 0 0.00 0 5728 17 0 0
t$perf-norm:sp_customer_reserve 963 963 0 0.00 0 374 3 0 0
t$perf-norm:sp_pay_from_customer 6406 6406 0 0.00 0 7940 9 0 0
t$perf-norm:sp_pay_to_supplier 5391 5391 0 0.00 0 4298 8 0 0
t$perf-norm:sp_reserve_write_off 9138 9138 0 0.00 0 4635 9 0 0
t$perf-norm:sp_supplier_invoice 5091 5091 0 0.00 0 3361 15 0 0
t$perf-norm:sp_supplier_order 4961 4961 0 0.00 0 3407 16 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 344 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 4351 78 0 0
t$perf-norm:srv_make_invnt_saldo 408 408 0 0.00 0 7761 29 0 0
t$perf-norm:srv_make_money_saldo 676 676 0 0.00 0 1111 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 11 5 0 0
v_add_invoice_to_stock 9050 8926 124 1.37 0 5099 16 0 124
v_all_customers 34170 34170 0 0.00 0 18 1 0 0
v_all_suppliers 27980 27980 0 0.00 0 229 1 0 0
v_all_wares 37476 37476 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 784 784 0 0.00 0 761 7 0 0
v_cancel_client_order 3142 3142 0 0.00 0 3012 3 0 0
v_cancel_customer_prepayment 1226 1226 0 0.00 0 2751 9 0 0
v_cancel_customer_reserve 1264 1264 0 0.00 0 1245 5 0 0
v_cancel_payment_to_supplier 738 738 0 0.00 0 1085 5 0 0
v_cancel_supplier_invoice 750 734 16 2.13 0 5248 21 0 16
v_cancel_supplier_order 774 774 0 0.00 0 1511 6 0 0
v_cancel_write_off 1908 1908 0 0.00 0 3005 19 0 0
v_max_id_clo_ord 4960 4960 0 0.00 0 339 1 0 0
v_max_id_clo_res 963 963 0 0.00 0 2267 9 0 0
v_max_id_ord_sup 5075 5075 0 0.00 0 7042 6 0 0
v_max_non_paid_invoice 1436 1436 0 0.00 0 406 2 0 0
v_max_non_paid_realizn 3313 3313 0 0.00 0 553 2 0 0
v_min_id_clo_ord 4960 4960 0 0.00 0 3908 6 0 0
v_min_id_clo_res 963 963 0 0.00 0 6611 20 0 0
v_min_id_ord_sup 5075 5075 0 0.00 0 6286 7 0 0
v_min_non_paid_invoice 5391 5391 0 0.00 0 17682 17 0 0
v_min_non_paid_realizn 6406 6406 0 0.00 0 6978 16 0 0
v_our_firm 9492 9492 0 0.00 0 231 1 0 0
v_reserve_write_off 18270 18270 0 0.00 0 4938 6 0 0
x_lock_depdocs_on_canc_invoice 10774 10774 0 0.00 0 22888 118 0 0
x_lock_depdocs_on_canc_sup_ord 387 387 0 0.00 0 25908 434 0 0
x_make_qstorno_1000_1200 4961 4961 0 0.00 2 88009 1318 0 0
x_make_qstorno_1200_2000 5091 5091 0 0.00 3 131052 4095 0 0
x_make_qstorno_2100_3300 419667 419667 0 0.00 0 99290 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-01-2021 07:28:18 to 25-01-2021 07:28:22.

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 3 8192000000 8796640128 8807108608 3168304 0.038 1 1 2 0 3265920 61840 35776
04:58:01 21106 8192000000 9720742432 10023329792 808879488 8.986 100 84 168 0 836795376 7496160 20419728
04:59:30 15424 8192000000 9769706112 10079391744 845372672 9.354 100 86 172 0 877240896 11632096 20236128
05:00:52 12864 8192000000 9805956912 10121367552 872225600 9.622 100 88 175 1 914224432 21927040 20071792
05:02:14 15597 8192000000 9851737792 10182307840 910990480 10.007 100 78 156 0 962101808 34316576 16794752
05:03:36 10030 8192000000 9918689728 10258595840 954883808 10.439 100 76 151 0 1026068800 55640656 15544336
05:04:51 4707 8192000000 9958210992 10307383296 986435472 10.747 100 89 174 2 1066900688 62311984 18153232
05:05:59 3285 8192000000 9973871776 10332459008 1013412688 11.008 100 86 172 2 1084106736 53224640 17469408
05:07:04 1949 8192000000 10010718320 10377953280 1032592928 11.193 100 84 167 0 1132796560 83840784 16362848
05:08:07 1151 8192000000 10049038848 10417500160 1055417984 11.413 100 97 193 0 1158398336 83299648 19680704
05:09:09 19057 8192000000 9976541920 10373914624 1066532368 11.519 100 33 66 3 1098422384 24875280 7014736
05:10:29 3590 8192000000 10006211040 10394411008 1079052560 11.638 100 46 92 3 1118902592 30323984 9526048
05:11:33 7313 8192000000 9986590848 10394382336 1082765552 11.674 100 16 32 0 1115198672 29416736 3016384
05:12:41 26794 8192000000 9988554464 10400813056 1085553968 11.700 100 39 78 2 1107686032 13197360 8934704
05:14:08 9739 8192000000 10011240400 10405642240 1092744704 11.769 100 30 60 1 1124286272 25244128 6297440
05:15:18 6063 8192000000 10031847744 10425970688 1096867744 11.808 100 50 100 1 1130098400 22615168 10615488
05:16:25 8634 8192000000 10005492880 10418360320 1096739856 11.807 100 37 74 1 1126907312 22101408 8066048
05:17:34 13349 8192000000 9989653488 10380578816 1056615280 11.424 97 68 133 1 1080721440 8900304 15205856
05:18:51 3357 8192000000 9973216080 10351484928 1012977040 11.004 96 46 94 1 1053535648 31307760 9250848
05:19:55 4670 8192000000 9940530096 10317225984 982343152 10.707 95 34 65 1 1006250240 16310832 7596256
05:21:02 6134 8192000000 9938367136 10318143488 1012908576 11.004 98 42 84 2 1043338208 20930016 9499616
05:22:13 30499 8192000000 9934462528 10322784256 1012634880 11.001 98 39 78 1 1038602608 16899600 9068128
05:23:48 7796 8192000000 9990631088 10383945728 1043719360 11.300 100 54 106 1 1082970192 27659104 11591728
05:24:58 6191 8192000000 9999577024 10389463040 1057952560 11.437 100 45 89 1 1080808160 12515872 10339728
05:26:09 18160 8192000000 9987109072 10380881920 1079702352 11.645 100 36 71 1 1108126736 19795840 8628544
05:28:03 24872 8192000000 10031034976 10427842560 1096326848 11.803 100 42 82 3 1141087264 36259632 8500784
05:29:36 11898 8192000000 10054666656 10450165760 1111299248 11.945 100 49 98 1 1170999872 49551904 10148720
05:30:54 15505 8192000000 10108891232 10513141760 1124381920 12.068 100 51 102 0 1211390960 76564192 10444848
05:32:12 9524 8192000000 10134274976 10538639360 1136596464 12.184 100 55 110 1 1248504384 100289920 11618000
05:33:28 9272 8192000000 10174169920 10575523840 1152186816 12.330 100 60 120 1 1272947136 107896656 12863664
05:34:41 27536 8192000000 10210526112 10627231744 1165406240 12.454 100 54 102 2 1326579008 149203040 11969728
05:36:16 30431 8192000000 10241152560 10672431104 1167538016 12.474 100 52 104 2 1360210320 180146752 12525552
05:37:52 4417 8192000000 10279748784 10686836736 1182376496 12.612 100 60 120 0 1377308432 181345984 13585952
05:39:02 5486 8192000000 10311142656 10724843520 1192702512 12.709 100 57 112 1 1423083104 217637744 12742848
05:40:11 6588 8192000000 10327874304 10749399040 1189957760 12.683 100 58 114 0 1435005616 232122880 12924976
05:41:20 2259 8192000000 10385121840 10787069952 1184436080 12.632 100 81 160 0 1481106704 277874416 18796208
05:42:26 6056 8192000000 10377365136 10790907904 1168248496 12.480 98 59 119 1 1474272768 291938096 14086176
05:43:39 1202 8192000000 10441385552 10843283456 1182713984 12.616 100 90 180 2 1536520992 332383504 21423504
05:44:43 4000 8192000000 10403175408 10820747264 1153092592 12.339 100 84 166 0 1501903136 328856304 19954240
05:45:52 4171 8192000000 10421706336 10834149376 1141420688 12.229 98 63 125 0 1511985056 355126384 15437984
05:47:02 10074 8192000000 10370880832 10813882368 1110336816 11.936 98 49 98 0 1469389584 347055040 11997728
05:48:22 16238 8192000000 10362547632 10784305152 1124472560 12.069 99 38 76 1 1455857264 321877968 9506736
05:49:47 10179 8192000000 10318672800 10760798208 1123106176 12.056 100 34 68 4 1417156832 285471488 8579168
05:51:07 31594 8192000000 10243198160 10718777344 1096765440 11.807 99 19 38 0 1380980112 279003168 5211504
05:52:43 16197 8192000000 10177865792 10658955264 1096517232 11.805 99 22 44 2 1304328912 201915712 5895968
05:54:06 13205 8192000000 10124255696 10544238592 1104014160 11.876 99 41 81 0 1218566576 105357824 9194592
05:55:28 84060 8192000000 10062169904 10491203584 1116516976 11.994 100 20 39 1 1179344144 58128544 4698624
05:58:01 141746 8192000000 10107902800 10560651264 1121134960 12.038 99 18 36 2 1158451024 33967904 3348160
06:01:25 52258 8192000000 10131842416 10586578944 1130547760 12.127 99 11 22 0 1212630544 79724800 2357984
06:03:19 44751 8192000000 10126358176 10558406656 1127557968 12.098 99 28 55 0 1214195408 80852752 5784688
06:05:09 145206 8192000000 10065731120 10579935232 1106682032 11.901 97 24 48 0 1243535872 131422592 5431248
06:09:00 50681 8192000000 10088581824 10537623552 1133384928 12.153 99 23 46 0 1185557792 47508704 4664160
06:11:01 72997 8192000000 10152030720 10594840576 1144364800 12.257 100 32 64 1 1269969216 118203824 7400592
06:13:16 83665 8192000000 10090156560 10566467584 1137332480 12.190 99 5 9 0 1182701120 44599104 769536
06:15:58 77462 8192000000 10101910816 10529132544 1141010576 12.225 99 25 50 0 1176639696 30828784 4800336
06:18:17 53140 8192000000 10096513424 10520866816 1121955488 12.045 98 18 36 0 1162161696 36190624 4015584
06:20:11 42154 8192000000 10107552160 10548977664 1131777456 12.138 98 24 48 0 1187679392 51173296 4728640
06:22:00 64964 8192000000 10103289264 10595594240 1141759360 12.232 99 31 62 1 1204734400 56470384 6504656
06:24:08 158965 8192000000 10106843744 10569191424 1133366528 12.153 98 26 52 1 1183979920 44812640 5800752
06:27:55 76547 8192000000 10194677776 10667175936 1166708512 12.466 100 20 40 1 1299842448 128487520 4646416
06:30:16 70428 8192000000 10178143200 10689617920 1133402512 12.153 98 17 34 1 1280406672 142808400 4195760
06:32:27 42524 8192000000 10106291648 10551341056 1131636080 12.137 99 26 52 0 1199483472 62164944 5682448
06:34:10 46841 8192000000 10088323280 10534572032 1110266352 11.935 97 22 45 4 1146370368 31322112 4781904
06:35:57 58024 8192000000 10093882592 10513207296 1110093920 11.933 97 26 51 3 1157888288 42414528 5379840
06:37:55 110926 8192000000 10053461312 10522918912 1058315408 11.440 93 13 26 1 1115071872 54279184 2477280
06:41:08 18051 8192000000 10063365504 10474598400 1088619760 11.730 99 55 110 3 1132112080 30455360 13036960
06:42:26 25341 8192000000 10063522176 10485784576 1105488944 11.890 100 32 64 0 1156450288 44860128 6101216
06:43:52 130791 8192000000 10000269536 10463711232 1073536032 11.586 98 17 32 2 1114455648 37590208 3329408
06:48:52 95189 8192000000 10046775856 10531848192 1103747360 11.873 99 15 31 0 1186066016 78780352 3538304
06:51:33 38066 8192000000 10044350144 10477035520 1090894672 11.751 98 24 48 0 1116393120 20398432 5100016
06:53:11 52799 8192000000 10095075200 10546053120 1120749168 12.034 100 21 42 2 1194270528 68788336 4733024
06:55:13 47775 8192000000 10106829568 10578198528 1107759264 11.911 99 24 48 1 1186148368 73272704 5116400
06:57:06 79724 8192000000 10099945792 10525061120 1111362704 11.945 99 11 21 0 1210180704 96436112 2381888
06:59:28 45200 8192000000 10085592960 10540892160 1096227600 11.802 98 22 44 1 1155456912 54735504 4493808
07:01:14 103032 8192000000 10101900992 10597867520 1121031632 12.037 100 18 36 0 1217848048 93218944 3597472
07:04:15 75696 8192000000 10079471040 10540195840 1134905840 12.168 100 9 18 0 1174818512 38115680 1796992
07:06:31 81568 8192000000 10083859216 10557440000 1117794160 12.006 98 14 28 1 1192906704 72284464 2828080
07:09:04 58317 8192000000 10063376560 10510323712 1118858720 12.016 98 9 18 1 1171436096 49920736 2656640
07:11:03 67016 8192000000 10085487808 10522603520 1124742976 12.072 98 31 59 2 1168050752 37276784 6030992
07:13:11 83936 8192000000 10138694352 10604204032 1132718976 12.147 98 25 50 0 1190973984 53085312 5169696
07:15:51 49202 8192000000 10098467696 10523815936 1111000656 11.942 97 25 50 0 1150419936 34485424 4933856
07:17:44 64768 8192000000 10104854096 10600681472 1127547264 12.098 98 10 20 0 1199344960 69654704 2142992
07:19:53 121468 8192000000 10119716016 10578403328 1152784176 12.336 100 47 93 1 1198650464 35317552 10548736
07:24:41 60734 8192000000 10075522688 10519851008 1116512032 11.994 98 7 14 0 1158860544 40947232 1401280
07:26:50 14252 8192000000 9064606160 9320148992 211352576 2.515 19 4 8 0 232765936 20563952 849408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-01-2021 07:28:24 to 25-01-2021 07:28:39.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-01-2021 07:28:40 to 25-01-2021 07:28:42.

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          223257
MON$OLDEST_ACTIVE               223258
MON$OLDEST_SNAPSHOT             223258
MON$NEXT_TRANSACTION            223258
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                       2082208
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             775
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

25.01.21 07:28:43. 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-01-25 07:28:45 No severe PSQL-related problems occured
25.01.21 07:28:47. End of report.