Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.11.2021 13:38
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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15656924 kB
MemAvailable 30868132 kB
Buffers 0 kB
Cached 16075588 kB
SwapCached 4108 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.1
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-11-02 13:37:08
DTS_END                         2021-11-02 13:37:08.7320
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/58862
TRN_ID                          239366
ATT_ID                          652
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: 9405.72 27672 1128686
customer order: creation 168.97 400 20276
customer order: refuse 13.93 349 1672
order to supplier: creation 47.13 1502 5656
order to supplier: removal 3.52 112975 422
invoice (draft): creation 45.83 3655 5500
invoice (draft): removal 52.80 1005 6336
invoice accept: apply 42.83 19186 5140
invoice accept: cancel 47.82 949 5738
customer reserve: creation 3923.14 191 470777
customer reserve: removal 4762.92 65 571550
realization accept: apply 82.85 200 9942
realization accept: cancel 83.73 119 10048
payment from customer: creation 59.72 137 7166
payment from customer: removal 5.92 97 710
payment to supplier: creation 50.05 163 6006
payment to supplier: removal 3.48 93 417
service: total inventory turnovers 3.32 4334 398
service: total monetary turnovers 6.72 588 806
service: aggregate perf. data 1.02 14515 122
service: refresh index statistics 0.03 392911 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 02-11-2021 13:39:07 to 02-11-2021 13:39:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6163
TEST_TIME 2 5376
TEST_TIME 3 5805
TEST_TIME 4 6050
TEST_TIME 5 5870
TEST_TIME 6 5703
TEST_TIME 7 6000
TEST_TIME 8 5894
TEST_TIME 9 6445
TEST_TIME 10 6589
TEST_TIME 11 6581
TEST_TIME 12 7140
TEST_TIME 13 7346
TEST_TIME 14 7728
TEST_TIME 15 7789
TEST_TIME 16 7877
TEST_TIME 17 8153
TEST_TIME 18 8288
TEST_TIME 19 8620
TEST_TIME 20 8645
TEST_TIME 21 8729
TEST_TIME 22 8949
TEST_TIME 23 9299
TEST_TIME 24 9738
TEST_TIME 25 9729
TEST_TIME 26 9782
TEST_TIME 27 9976
TEST_TIME 28 10387
TEST_TIME 29 11437
TEST_TIME 30 11427
TEST_TIME 31 11729
TEST_TIME 32 11533
TEST_TIME 33 11316
TEST_TIME 34 11198
TEST_TIME 35 11053
TEST_TIME 36 10802
TEST_TIME 37 10974
TEST_TIME 38 10916
TEST_TIME 39 10804
TEST_TIME 40 10776
TEST_TIME 41 10794
TEST_TIME 42 10778
TEST_TIME 43 10650
TEST_TIME 44 10518
TEST_TIME 45 10427
TEST_TIME 46 10384
TEST_TIME 47 10437
TEST_TIME 48 10362
TEST_TIME 49 10192
TEST_TIME 50 10115
TEST_TIME 51 10000
TEST_TIME 52 9933
TEST_TIME 53 9959
TEST_TIME 54 9953
TEST_TIME 55 9990
TEST_TIME 56 9940
TEST_TIME 57 9999
TEST_TIME 58 10042
TEST_TIME 59 10048
TEST_TIME 60 10077
TEST_TIME 61 10193
TEST_TIME 62 10238
TEST_TIME 63 10172
TEST_TIME 64 10171
TEST_TIME 65 10036
TEST_TIME 66 10053
TEST_TIME 67 10032
TEST_TIME 68 9944
TEST_TIME 69 9864
TEST_TIME 70 9874
TEST_TIME 71 9821
TEST_TIME 72 9799
TEST_TIME 73 9758
TEST_TIME 74 9808
TEST_TIME 75 9882
TEST_TIME 76 9912
TEST_TIME 77 9942
TEST_TIME 78 9973
TEST_TIME 79 10038
TEST_TIME 80 9995
TEST_TIME 81 9982
TEST_TIME 82 10007
TEST_TIME 83 10052
TEST_TIME 84 10029
TEST_TIME 85 9974
TEST_TIME 86 9976
TEST_TIME 87 9898
TEST_TIME 88 9924
TEST_TIME 89 9875
TEST_TIME 90 9807
TEST_TIME 91 9764
TEST_TIME 92 9702
TEST_TIME 93 9727
TEST_TIME 94 9763
TEST_TIME 95 9767
TEST_TIME 96 9785
TEST_TIME 97 9747
TEST_TIME 98 9791
TEST_TIME 99 9905
TEST_TIME 100 9855
TEST_TIME 101 9852
TEST_TIME 102 9886
TEST_TIME 103 9912
TEST_TIME 104 9948
TEST_TIME 105 9976
TEST_TIME 106 9979
TEST_TIME 107 9897
TEST_TIME 108 9827
TEST_TIME 109 9794
TEST_TIME 110 9811
TEST_TIME 111 9747
TEST_TIME 112 9719
TEST_TIME 113 9697
TEST_TIME 114 9681
TEST_TIME 115 9636
TEST_TIME 116 9580
TEST_TIME 117 9548
TEST_TIME 118 9530
TEST_TIME 119 9487
TEST_TIME 120 9409

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-11-2021 13:39:11 to 02-11-2021 13:39:14.

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 1563590 1563590 0 0.00 0 20278 7 0 0
doc_list_biud 1563588 1563588 0 0.00 0 39931 30 0 0
sp_add_invoice_to_stock 5235 5140 95 1.81 27 172522 19186 0 95
sp_cancel_adding_invoice 5738 5738 0 0.00 5 95105 949 0 0
sp_cancel_client_order 1672 1672 0 0.00 5 18328 349 0 0
sp_cancel_customer_reserve 571550 571550 0 0.00 1 39935 66 0 0
sp_cancel_pay_from_customer 710 710 0 0.00 3 5242 97 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 3 3948 94 0 0
sp_cancel_supplier_invoice 6348 6336 12 0.18 2 37983 1005 0 12
sp_cancel_supplier_order 422 422 0 0.00 10 1147212 112976 0 0
sp_cancel_write_off 10048 10048 0 0.00 1 10878 120 0 0
sp_client_order 20276 20276 0 0.00 5 32460 400 0 0
sp_customer_reserve 470777 470777 0 0.00 0 87163 192 0 0
sp_fill_shopping_cart 31431 31431 0 0.00 1 15153 48 0 0
sp_fill_shopping_cart_clo_res 470772 470772 0 0.00 0 14975 14 0 0
sp_get_clo_for_invoice 5135 5135 0 0.00 0 23867 259 0 0
sp_kill_cost_storno 16913 16913 0 0.00 0 9879 59 0 0
sp_kill_qstorno_ret_qs2qd 1159906 1159906 0 0.00 0 39839 36 0 0
sp_kill_qty_storno 610843 610843 0 0.00 0 39928 77 0 0
sp_lock_selected_doc 20946 20946 0 0.00 0 7640 5 0 0
sp_make_cost_storno 28249 28249 0 0.00 0 20276 60 0 0
sp_multiply_rows_for_pdistr 28249 28249 0 0.00 0 7949 32 0 0
sp_multiply_rows_for_qdistr 20276 20276 0 0.00 0 14237 186 0 0
sp_pay_from_customer 7166 7166 0 0.00 4 20320 138 0 0
sp_pay_to_supplier 6006 6006 0 0.00 5 20086 163 0 0
sp_payment_common 13172 13172 0 0.00 1 10402 32 0 0
sp_qd_handle_on_cancel_clo 1672 1672 0 0.00 0 8216 300 0 0
sp_qd_handle_on_invoice_upd_sts 10873 10873 0 0.00 0 30811 380 0 0
sp_qd_handle_on_reserve_upd_sts 9942 9942 0 0.00 0 15728 53 0 0
sp_reserve_write_off 9942 9942 0 0.00 7 20465 201 0 0
sp_supplier_invoice 5500 5500 0 0.00 7 111509 3656 0 0
sp_supplier_order 5656 5656 0 0.00 6 34523 1502 0 0
srv_aggregate_perf_data 122 122 0 0.00 3129 158768 14515 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 965 158574 43061 0 0
srv_find_qd_qs_mism 1080069 1080069 0 0.00 0 10299 5 0 0
srv_make_invnt_saldo 398 398 0 0.00 55 103126 4335 0 0
srv_make_money_saldo 806 806 0 0.00 1 25889 589 0 0
srv_recalc_idx_stat 4 4 0 0.00 231370 633506 392912 0 0
t$perf-abend:sp_add_invoice_to_stock 95 95 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5140 5140 0 0.00 0 12917 204 0 0
t$perf-norm:sp_cancel_adding_invoice 442 442 0 0.00 1 13549 175 0 0
t$perf-norm:sp_cancel_client_order 1672 1672 0 0.00 0 8369 29 0 0
t$perf-norm:sp_cancel_customer_reserve 746 746 0 0.00 0 6844 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 710 710 0 0.00 0 8210 27 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 2560 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 1920 25 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 16416 1578 0 0
t$perf-norm:sp_cancel_write_off 1035 1035 0 0.00 0 3403 9 0 0
t$perf-norm:sp_client_order 20276 20276 0 0.00 0 11912 20 0 0
t$perf-norm:sp_customer_reserve 1025 1025 0 0.00 0 323 4 0 0
t$perf-norm:sp_pay_from_customer 7166 7166 0 0.00 0 7010 11 0 0
t$perf-norm:sp_pay_to_supplier 6006 6006 0 0.00 0 7783 13 0 0
t$perf-norm:sp_reserve_write_off 9942 9942 0 0.00 0 15644 13 0 0
t$perf-norm:sp_supplier_invoice 5500 5500 0 0.00 0 5092 17 0 0
t$perf-norm:sp_supplier_order 5656 5656 0 0.00 0 9400 18 0 0
t$perf-norm:srv_aggregate_perf_data 122 122 0 0.00 0 544 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 1116 34 0 0
t$perf-norm:srv_make_invnt_saldo 398 398 0 0.00 0 6962 33 0 0
t$perf-norm:srv_make_money_saldo 806 806 0 0.00 0 9800 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 4 0 0
v_add_invoice_to_stock 10460 10270 190 1.81 0 19215 24 0 190
v_all_customers 37146 37146 0 0.00 0 23 1 0 0
v_all_suppliers 30992 30992 0 0.00 0 98 1 0 0
v_all_wares 40552 40552 0 0.00 0 54 1 0 0
v_cancel_adding_invoice 884 884 0 0.00 0 12926 46 0 0
v_cancel_client_order 3344 3344 0 0.00 0 17640 16 0 0
v_cancel_customer_prepayment 1420 1420 0 0.00 0 5195 13 0 0
v_cancel_customer_reserve 1492 1492 0 0.00 0 1653 7 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 3931 18 0 0
v_cancel_supplier_invoice 874 850 24 2.74 0 15482 51 0 24
v_cancel_supplier_order 844 844 0 0.00 0 739 5 0 0
v_cancel_write_off 2070 2070 0 0.00 0 4085 18 0 0
v_max_id_clo_ord 5656 5656 0 0.00 0 602 2 0 0
v_max_id_clo_res 1025 1025 0 0.00 0 7935 25 0 0
v_max_id_ord_sup 5499 5499 0 0.00 0 5633 4 0 0
v_max_non_paid_invoice 1665 1665 0 0.00 0 6084 6 0 0
v_max_non_paid_realizn 3770 3770 0 0.00 0 3186 4 0 0
v_min_id_clo_ord 5656 5656 0 0.00 0 10004 21 0 0
v_min_id_clo_res 1025 1025 0 0.00 0 6202 11 0 0
v_min_id_ord_sup 5499 5499 0 0.00 0 6638 11 0 0
v_min_non_paid_invoice 6006 6006 0 0.00 0 6493 26 0 0
v_min_non_paid_realizn 7165 7165 0 0.00 0 6334 22 0 0
v_our_firm 10196 10196 0 0.00 0 23 1 0 0
v_reserve_write_off 19884 19884 0 0.00 0 20115 11 0 0
x_lock_depdocs_on_canc_invoice 11649 11649 0 0.00 0 14780 120 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 8072 401 0 0
x_make_qstorno_1000_1200 5656 5656 0 0.00 2 34492 1390 0 0
x_make_qstorno_1200_2000 5500 5500 0 0.00 3 111467 3500 0 0
x_make_qstorno_2100_3300 470777 470777 0 0.00 0 87162 175 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-11-2021 13:39:15 to 02-11-2021 13:39:19.

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
11:07:12 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:13 14521 8192000000 9715163776 10013843456 796134928 8.857 100 85 170 1 824248400 7789584 20323888
11:09:32 18715 8192000000 9766426384 10073718784 835455936 9.254 100 82 163 0 866168896 11080272 19632688
11:10:54 24003 8192000000 9800844176 10116325376 869055856 9.591 100 88 176 0 906342608 16726560 20560192
11:12:23 10941 8192000000 9857118880 10186522624 910534240 10.003 100 78 157 0 959135744 31190000 17411504
11:13:40 7761 8192000000 9927848608 10270322688 955137440 10.441 100 85 170 1 1036411760 63065904 18208416
11:14:53 5021 8192000000 9976348720 10330103808 995183408 10.832 100 82 162 0 1083733664 72010080 16540176
11:16:00 4444 8192000000 9989262192 10353860608 1019336512 11.066 100 84 168 0 1110163200 73608784 17217904
11:17:07 2656 8192000000 10010363472 10373742592 1044962464 11.312 100 89 176 1 1124141104 61099632 18079008
11:18:11 2212 8192000000 10035211168 10406088704 1059383152 11.451 100 89 180 2 1141233136 64037088 17812896
11:19:15 1897 8192000000 10029091968 10403782656 1068217312 11.535 100 86 172 0 1137981568 52901568 16862688
11:20:18 7313 8192000000 9995307808 10391392256 1075184416 11.602 100 51 105 1 1110145072 24414624 10546032
11:21:26 6468 8192000000 9997781264 10392522752 1079389136 11.642 100 42 84 1 1118503552 29902560 9211856
11:22:34 5204 8192000000 10012696512 10409971712 1087115424 11.715 100 43 86 1 1133327200 36681840 9529936
11:23:40 36869 8192000000 10015939040 10406838272 1087512096 11.719 100 53 107 3 1130492576 31617648 11362832
11:25:17 6070 8192000000 10013232864 10415878144 1095823856 11.798 100 56 113 1 1135783632 28218848 11740928
11:26:24 3887 8192000000 10000419680 10407030784 1097652112 11.815 100 57 111 2 1122913936 12976416 12285408
11:27:32 8517 8192000000 9984593552 10389282816 1064506320 11.500 98 17 34 1 1103314768 35457504 3350944
11:28:41 53734 8192000000 9951358000 10344194048 998884576 10.868 94 32 65 1 1015914544 9699936 7330032
11:30:36 3592 8192000000 9954131408 10327220224 1021701824 11.088 99 46 89 1 1057436800 26370288 9364688
11:31:41 5524 8192000000 9928400688 10310164480 1017548992 11.048 99 47 90 3 1040662704 12850752 10262960
11:32:48 8695 8192000000 9941577280 10331873280 990669680 10.788 97 51 103 2 1015364528 13110880 11583968
11:33:59 28377 8192000000 9955730304 10329645056 1027601280 11.145 99 42 86 0 1057886640 20240128 10045232
11:35:28 5549 8192000000 9967085776 10362630144 1059233440 11.449 100 50 97 0 1084407232 13965376 11208416
11:36:33 7514 8192000000 9975237584 10379997184 1064150544 11.496 100 37 74 0 1089865456 17801648 7913264
11:37:51 23888 8192000000 9999620848 10396069888 1080499104 11.652 100 36 73 0 1110241344 21710736 8031504
11:39:21 64834 8192000000 10046905488 10459316224 1098166928 11.820 100 47 92 1 1165128608 57302624 9659056
11:41:32 1309 8192000000 10078621568 10474242048 1113831536 11.969 100 63 127 4 1187742256 60230720 13680000
11:42:37 14151 8192000000 10102999520 10503782400 1130509088 12.126 100 59 118 1 1218815008 75290800 13015120
11:43:59 21131 8192000000 10148098128 10556325888 1136621744 12.184 100 52 103 0 1265390368 116843296 11925328
11:45:24 4973 8192000000 10173185680 10579419136 1145435920 12.267 100 53 105 0 1280075312 122747088 11892304
11:46:32 11194 8192000000 10224512416 10649047040 1162572512 12.427 100 60 118 0 1323038976 147818384 12648080
11:47:47 11124 8192000000 10217742256 10636398592 1171227968 12.508 100 40 79 2 1330533040 149832704 9472368
11:49:05 13412 8192000000 10226947104 10644529152 1159900160 12.402 99 52 104 0 1325543888 153840272 11803456
11:50:25 5857 8192000000 10241125712 10644967424 1168897184 12.487 100 77 153 0 1346057072 160846928 16312960
11:51:35 1030 8192000000 10299284336 10692624384 1170007024 12.497 100 94 186 0 1396206672 205461056 20738592
11:52:38 3366 8192000000 10285077040 10688856064 1155436992 12.361 100 78 154 0 1388171664 215717024 17017648
11:53:45 2997 8192000000 10330398160 10721083392 1156477072 12.370 100 91 179 1 1424261120 248003360 19780688
11:54:52 1807 8192000000 10320900400 10719694848 1134951824 12.168 99 90 179 1 1415542784 260816448 19774512
11:56:00 1637 8192000000 10372596752 10766647296 1150952288 12.318 100 77 154 0 1466903056 298177904 17772864
11:57:06 6875 8192000000 10361965104 10783453184 1158072992 12.385 100 53 106 0 1458661376 287723408 12864976
11:58:17 2247 8192000000 10421936816 10856333312 1169545872 12.493 100 61 119 0 1537529808 353470704 14513232
11:59:35 964 8192000000 10472248624 10889920512 1188924656 12.673 100 95 188 2 1566627184 354755728 22946800
12:00:39 7949 8192000000 10446940384 10892873728 1175428624 12.548 99 47 94 1 1553391664 365654512 12308528
12:01:48 12507 8192000000 10422569664 10862071808 1183829360 12.626 100 37 74 1 1527964576 334854160 9281056
12:03:10 31367 8192000000 10442437280 10889363456 1167927728 12.477 100 30 60 1 1535912320 359825408 8159184
12:05:06 28453 8192000000 10394285040 10913030144 1144642016 12.259 100 17 34 0 1552654176 402592256 5419904
12:06:57 36446 8192000000 10194611488 10644369408 1132596368 12.146 98 12 24 0 1246343264 111023232 2723664
12:08:44 35769 8192000000 10069405376 10522628096 1143886640 12.252 100 13 24 2 1170934016 24522720 2524656
12:10:20 11731 8192000000 10097364976 10533548032 1127214320 12.095 99 39 76 0 1186710480 51748752 7747408
12:11:35 45707 8192000000 10141763904 10578919424 1139187696 12.208 99 35 70 0 1237227008 90705024 7334288
12:13:22 104545 8192000000 10076917328 10510266368 1121719104 12.043 98 25 50 1 1259762416 131727584 6315728
12:16:08 57979 8192000000 10083383872 10523971584 1114334400 11.973 97 26 52 0 1153501664 33631024 5536240
12:18:07 35109 8192000000 10105013936 10559741952 1129871744 12.120 98 49 98 1 1209837632 69248608 10717280
12:19:49 33568 8192000000 10080330576 10532024320 1141239968 12.227 99 18 37 0 1165513040 21031328 3241744
12:21:22 20977 8192000000 10114988784 10557865984 1161627168 12.419 100 45 91 0 1216440784 44862784 9950832
12:22:58 56965 8192000000 10103110512 10549874688 1120162640 12.029 97 20 38 1 1203393440 78853728 4377072
12:24:57 37388 8192000000 10094386736 10558095360 1144323216 12.256 100 31 62 0 1187585120 36488320 6773584
12:26:36 57557 8192000000 10079506048 10516852736 1137460384 12.192 99 41 85 1 1186157632 39204256 9492992
12:28:35 85739 8192000000 10092902064 10559856640 1106444592 11.899 97 8 16 0 1166192736 57843216 1904928
12:31:01 34988 8192000000 10107640960 10548629504 1141129312 12.226 99 25 50 1 1195734464 49587232 5017920
12:32:38 36740 8192000000 10081902768 10543652864 1142414480 12.238 100 16 32 0 1181856736 36137312 3304944
12:34:50 35529 8192000000 10050951632 10498129920 1103173024 11.868 98 15 30 0 1159666016 52545120 3947872
12:36:26 82756 8192000000 10095629600 10554122240 1103477280 11.871 98 17 34 0 1205730448 98503792 3749376
12:38:54 82964 8192000000 10063474528 10530902016 1078123744 11.630 96 15 31 0 1126820096 45798992 2897360
12:41:17 121534 8192000000 10087899408 10539479040 1095753552 11.797 98 38 76 3 1175157744 70932896 8471296
12:44:20 68592 8192000000 10064039216 10540929024 1099501504 11.833 98 21 42 0 1155508176 51933472 4073200
12:46:42 52865 8192000000 10035599504 10533556224 1106388880 11.898 99 10 20 0 1165465952 57207568 1869504
12:48:39 73923 8192000000 10087928544 10530955264 1103032352 11.866 98 20 40 0 1164560512 57051440 4476720
12:51:06 71892 8192000000 10138586784 10602516480 1105385632 11.889 96 21 42 0 1221477376 111203056 4888688
12:53:19 66857 8192000000 10117745792 10606530560 1142863232 12.242 100 22 44 0 1248199840 100691968 4644640
12:55:38 39057 8192000000 10095071232 10579574784 1091516912 11.757 96 32 64 0 1171450032 73482464 6450656
12:57:21 22814 8192000000 10087703136 10519355392 1125906352 12.083 99 35 70 0 1165665824 32596416 7163056
12:58:46 46862 8192000000 10139566048 10596835328 1126335696 12.087 99 40 81 0 1223791488 89214048 8241744
13:00:36 61299 8192000000 10094264496 10573164544 1102180576 11.858 97 21 42 0 1198763008 92259648 4322784
13:03:22 53677 8192000000 10081609568 10517196800 1128744928 12.110 99 20 40 0 1170439168 37239408 4454832
13:05:26 53972 8192000000 10077119488 10562097152 1143167024 12.245 100 23 45 2 1194117312 46378928 4571360
13:07:20 67652 8192000000 10137444464 10611294208 1147644080 12.287 100 17 33 3 1220053440 68986064 3423296
13:09:32 64699 8192000000 10171910368 10652438528 1130722192 12.128 99 11 22 0 1253352448 119843760 2786496
13:11:45 46518 8192000000 10074040656 10537279488 1127787184 12.100 99 22 44 0 1185803024 53481232 4534608
13:13:48 40774 8192000000 10133025392 10586140672 1148357008 12.294 100 18 36 1 1231194784 79259280 3578496
13:15:32 97582 8192000000 10088594176 10597974016 1133183360 12.151 99 18 36 0 1263026400 125695312 4147728
13:18:10 65003 8192000000 10128391680 10618777600 1132507648 12.145 98 33 66 0 1214623376 75283856 6831872
13:20:28 61208 8192000000 10107874336 10569519104 1072309648 11.574 93 17 34 0 1135612560 60121808 3181104
13:22:30 158574 8192000000 10050908288 10494177280 1059778784 11.454 93 20 40 0 1111180752 47115488 4286480
13:26:27 62948 8192000000 10095399056 10563514368 1116493008 11.994 98 16 32 0 1177425408 57245920 3686480
13:28:39 84541 8192000000 10039986992 10497654784 1049090976 11.352 93 9 18 0 1070051456 19015472 1945008
13:31:04 50293 8192000000 10036074064 10506989568 1100891888 11.846 98 18 36 2 1135154608 29697344 4565376
13:32:55 51372 8192000000 10048871248 10510512128 1127455072 12.097 100 6 10 1 1166838432 38315728 1067632
13:34:52 61139 8192000000 10046174064 10516541440 1101773472 11.854 97 15 30 0 1143895632 38850560 3271600
13:36:58 30735 8192000000 8958630000 9186750464 126710512 1.523 12 1 2 0 134907184 8161264 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-11-2021 13:39:21 to 02-11-2021 13:39:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 95 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-11-2021 13:39:37 to 02-11-2021 13:39:39.

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          240918
MON$OLDEST_ACTIVE               240919
MON$OLDEST_SNAPSHOT             240919
MON$NEXT_TRANSACTION            240919
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                       2085920
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             810
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2649 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2649 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2649 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2649 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17: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 ---
:192: 
:193: mscs-test-161.budzdorov.ru    Tue Nov  2 13:37:36 2021
:194:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:195: 
--- end of diff output ---

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

02.11.21 13:39:40. 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-11-02 13:39:43 No severe PSQL-related problems occured
02.11.21 13:39:44. End of report.