Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.04.2021 04:27
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID df9f488d08424df294c459d6c283e9c3
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-03-04 16:07
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.26
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16656736 kB
MemAvailable 31372596 kB
Buffers 20 kB
Cached 14911324 kB
SwapCached 96 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-04-07 04:26:59
DTS_END                         2021-04-07 04:26:59.2440
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/41870
TRN_ID                          232978
ATT_ID                          658
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.78 26106 1065334
customer order: creation 162.28 420 19473
customer order: refuse 13.53 303 1624
order to supplier: creation 44.16 1398 5299
order to supplier: removal 3.34 123294 401
invoice (draft): creation 43.98 3447 5278
invoice (draft): removal 48.93 1030 5872
invoice accept: apply 39.69 16508 4763
invoice accept: cancel 45.12 876 5414
customer reserve: creation 3773.36 156 452803
customer reserve: removal 4414.02 74 529682
realization accept: apply 80.28 176 9633
realization accept: cancel 85.28 109 10233
payment from customer: creation 57.18 103 6861
payment from customer: removal 5.33 81 640
payment to supplier: creation 47.72 116 5726
payment to supplier: removal 3.23 123 388
service: total inventory turnovers 3.73 3374 447
service: total monetary turnovers 5.67 498 680
service: aggregate perf. data 0.94 15650 113
service: refresh index statistics 0.03 354391 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-04-2021 04:28:16 to 07-04-2021 04:28:18.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1024
TEST_TIME 2 2635
TEST_TIME 3 4099
TEST_TIME 4 4628
TEST_TIME 5 4624
TEST_TIME 6 4813
TEST_TIME 7 4629
TEST_TIME 8 4438
TEST_TIME 9 4744
TEST_TIME 10 4909
TEST_TIME 11 4905
TEST_TIME 12 5057
TEST_TIME 13 5393
TEST_TIME 14 5402
TEST_TIME 15 5774
TEST_TIME 16 5794
TEST_TIME 17 6113
TEST_TIME 18 6471
TEST_TIME 19 7103
TEST_TIME 20 7220
TEST_TIME 21 7554
TEST_TIME 22 7503
TEST_TIME 23 8218
TEST_TIME 24 9023
TEST_TIME 25 9789
TEST_TIME 26 10647
TEST_TIME 27 10567
TEST_TIME 28 10294
TEST_TIME 29 10485
TEST_TIME 30 10181
TEST_TIME 31 10026
TEST_TIME 32 9875
TEST_TIME 33 9867
TEST_TIME 34 9693
TEST_TIME 35 9953
TEST_TIME 36 9851
TEST_TIME 37 9965
TEST_TIME 38 10036
TEST_TIME 39 9978
TEST_TIME 40 9972
TEST_TIME 41 9888
TEST_TIME 42 9842
TEST_TIME 43 9867
TEST_TIME 44 9919
TEST_TIME 45 9927
TEST_TIME 46 9976
TEST_TIME 47 9807
TEST_TIME 48 9606
TEST_TIME 49 9546
TEST_TIME 50 9473
TEST_TIME 51 9421
TEST_TIME 52 9451
TEST_TIME 53 9432
TEST_TIME 54 9351
TEST_TIME 55 9326
TEST_TIME 56 9317
TEST_TIME 57 9238
TEST_TIME 58 9256
TEST_TIME 59 9174
TEST_TIME 60 9256
TEST_TIME 61 9274
TEST_TIME 62 9286
TEST_TIME 63 9291
TEST_TIME 64 9261
TEST_TIME 65 9322
TEST_TIME 66 9331
TEST_TIME 67 9427
TEST_TIME 68 9449
TEST_TIME 69 9568
TEST_TIME 70 9539
TEST_TIME 71 9503
TEST_TIME 72 9412
TEST_TIME 73 9324
TEST_TIME 74 9283
TEST_TIME 75 9211
TEST_TIME 76 9216
TEST_TIME 77 9168
TEST_TIME 78 9116
TEST_TIME 79 9046
TEST_TIME 80 8994
TEST_TIME 81 9065
TEST_TIME 82 9102
TEST_TIME 83 9137
TEST_TIME 84 9123
TEST_TIME 85 9188
TEST_TIME 86 9178
TEST_TIME 87 9170
TEST_TIME 88 9173
TEST_TIME 89 9174
TEST_TIME 90 9209
TEST_TIME 91 9181
TEST_TIME 92 9085
TEST_TIME 93 9063
TEST_TIME 94 9008
TEST_TIME 95 8979
TEST_TIME 96 9029
TEST_TIME 97 9005
TEST_TIME 98 8989
TEST_TIME 99 8995
TEST_TIME 100 9030
TEST_TIME 101 9053
TEST_TIME 102 9052
TEST_TIME 103 9074
TEST_TIME 104 9030
TEST_TIME 105 9120
TEST_TIME 106 9112
TEST_TIME 107 9125
TEST_TIME 108 9150
TEST_TIME 109 9075
TEST_TIME 110 9006
TEST_TIME 111 9002
TEST_TIME 112 8947
TEST_TIME 113 8938
TEST_TIME 114 8928
TEST_TIME 115 8892
TEST_TIME 116 8909
TEST_TIME 117 8904
TEST_TIME 118 8891
TEST_TIME 119 8914
TEST_TIME 120 8878

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-04-2021 04:28:20 to 07-04-2021 04:28:23.

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 1484363 1484363 0 0.00 0 29024 6 0 0
doc_list_biud 1484363 1484363 0 0.00 0 67450 33 0 0
sp_add_invoice_to_stock 4858 4763 95 1.95 41 173783 16509 0 95
sp_cancel_adding_invoice 5414 5414 0 0.00 5 107751 877 0 0
sp_cancel_client_order 1624 1624 0 0.00 4 9760 303 0 0
sp_cancel_customer_reserve 529682 529682 0 0.00 1 40229 74 0 0
sp_cancel_pay_from_customer 640 640 0 0.00 3 6301 82 0 0
sp_cancel_pay_to_supplier 388 388 0 0.00 3 18313 124 0 0
sp_cancel_supplier_invoice 5882 5872 10 0.17 2 67495 1030 0 10
sp_cancel_supplier_order 401 401 0 0.00 8 1346195 123295 0 0
sp_cancel_write_off 10233 10233 0 0.00 1 12698 109 0 0
sp_client_order 19473 19473 0 0.00 4 36539 421 0 0
sp_customer_reserve 452803 452803 0 0.00 0 86531 156 0 0
sp_fill_shopping_cart 30037 30037 0 0.00 1 11325 47 0 0
sp_fill_shopping_cart_clo_res 452802 452802 0 0.00 0 23823 19 0 0
sp_get_clo_for_invoice 4762 4762 0 0.00 0 30739 447 0 0
sp_kill_cost_storno 16675 16675 0 0.00 0 11045 58 0 0
sp_kill_qstorno_ret_qs2qd 1075870 1075870 0 0.00 0 67447 40 0 0
sp_kill_qty_storno 567621 567621 0 0.00 0 67449 85 0 0
sp_lock_selected_doc 19888 19888 0 0.00 0 8363 6 0 0
sp_make_cost_storno 26982 26982 0 0.00 0 20952 63 0 0
sp_multiply_rows_for_pdistr 26982 26982 0 0.00 0 10967 29 0 0
sp_multiply_rows_for_qdistr 19473 19473 0 0.00 0 36525 248 0 0
sp_pay_from_customer 6861 6861 0 0.00 4 12911 104 0 0
sp_pay_to_supplier 5726 5726 0 0.00 5 18849 117 0 0
sp_payment_common 12587 12587 0 0.00 1 10308 25 0 0
sp_qd_handle_on_cancel_clo 1624 1624 0 0.00 0 9749 269 0 0
sp_qd_handle_on_invoice_upd_sts 10176 10176 0 0.00 0 34615 368 0 0
sp_qd_handle_on_reserve_upd_sts 9633 9633 0 0.00 0 10837 46 0 0
sp_reserve_write_off 9633 9633 0 0.00 7 30821 177 0 0
sp_supplier_invoice 5278 5278 0 0.00 7 120418 3448 0 0
sp_supplier_order 5299 5299 0 0.00 6 38652 1398 0 0
srv_aggregate_perf_data 113 113 0 0.00 2751 284730 15650 0 0
srv_fill_mon_cache_memory 59 59 0 0.00 1598 149497 54171 0 0
srv_find_qd_qs_mism 1019191 1019191 0 0.00 0 29023 5 0 0
srv_make_invnt_saldo 447 447 0 0.00 66 133684 3374 0 0
srv_make_money_saldo 680 680 0 0.00 1 15466 498 0 0
srv_recalc_idx_stat 4 4 0 0.00 160439 513656 354392 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 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4763 4763 0 0.00 0 20080 161 0 0
t$perf-norm:sp_cancel_adding_invoice 433 433 0 0.00 0 5318 87 0 0
t$perf-norm:sp_cancel_client_order 1624 1624 0 0.00 0 3294 11 0 0
t$perf-norm:sp_cancel_customer_reserve 698 698 0 0.00 0 706 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 640 640 0 0.00 0 2731 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 388 388 0 0.00 0 613 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 371 371 0 0.00 0 2961 21 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 22448 1449 0 0
t$perf-norm:sp_cancel_write_off 938 938 0 0.00 0 2461 9 0 0
t$perf-norm:sp_client_order 19473 19473 0 0.00 0 7756 19 0 0
t$perf-norm:sp_customer_reserve 986 986 0 0.00 0 2177 5 0 0
t$perf-norm:sp_pay_from_customer 6861 6861 0 0.00 0 7920 8 0 0
t$perf-norm:sp_pay_to_supplier 5726 5726 0 0.00 0 8530 10 0 0
t$perf-norm:sp_reserve_write_off 9633 9633 0 0.00 0 7000 10 0 0
t$perf-norm:sp_supplier_invoice 5278 5278 0 0.00 0 4747 15 0 0
t$perf-norm:sp_supplier_order 5299 5299 0 0.00 0 5902 15 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 652 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 118 3 0 0
t$perf-norm:srv_make_invnt_saldo 447 447 0 0.00 0 5803 19 0 0
t$perf-norm:srv_make_money_saldo 680 680 0 0.00 0 4536 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9712 9522 190 1.95 0 5678 15 0 190
v_all_customers 35832 35832 0 0.00 0 21 1 0 0
v_all_suppliers 29712 29712 0 0.00 0 20 1 0 0
v_all_wares 38942 38942 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 866 866 0 0.00 0 374 5 0 0
v_cancel_client_order 3248 3248 0 0.00 0 2474 6 0 0
v_cancel_customer_prepayment 1280 1280 0 0.00 0 1762 8 0 0
v_cancel_customer_reserve 1396 1396 0 0.00 0 660 3 0 0
v_cancel_payment_to_supplier 776 776 0 0.00 0 18228 31 0 0
v_cancel_supplier_invoice 762 742 20 2.62 0 1173 13 0 20
v_cancel_supplier_order 802 802 0 0.00 0 292 3 0 0
v_cancel_write_off 1876 1876 0 0.00 0 1768 14 0 0
v_max_id_clo_ord 5299 5299 0 0.00 0 515 1 0 0
v_max_id_clo_res 986 986 0 0.00 0 3535 12 0 0
v_max_id_ord_sup 5267 5267 0 0.00 0 2443 4 0 0
v_max_non_paid_invoice 1436 1436 0 0.00 0 3803 4 0 0
v_max_non_paid_realizn 3497 3497 0 0.00 0 1124 3 0 0
v_min_id_clo_ord 5299 5299 0 0.00 0 7076 12 0 0
v_min_id_clo_res 986 986 0 0.00 0 2714 7 0 0
v_min_id_ord_sup 5267 5267 0 0.00 0 6468 6 0 0
v_min_non_paid_invoice 5726 5726 0 0.00 0 10301 18 0 0
v_min_non_paid_realizn 6861 6861 0 0.00 0 7747 19 0 0
v_our_firm 9838 9838 0 0.00 0 16 1 0 0
v_reserve_write_off 19266 19266 0 0.00 0 18231 6 0 0
x_lock_depdocs_on_canc_invoice 10915 10915 0 0.00 0 25225 118 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 16311 375 0 0
x_make_qstorno_1000_1200 5299 5299 0 0.00 2 38637 1322 0 0
x_make_qstorno_1200_2000 5278 5278 0 0.00 3 120407 3264 0 0
x_make_qstorno_2100_3300 452803 452803 0 0.00 0 86528 135 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-04-2021 04:28:24 to 07-04-2021 04:28:28.

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
01:57:03 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
01:58:04 18651 8192000000 9713397600 10008338432 794276800 8.838 100 86 172 0 823343008 7767184 21299024
01:59:29 17316 8192000000 9762073776 10066055168 829787808 9.197 100 86 172 0 862979568 12505344 20686416
02:00:49 9684 8192000000 9803091440 10118909952 865262352 9.553 100 82 164 1 905797264 21421184 19113728
02:02:09 11028 8192000000 9865663232 10197569536 918772080 10.084 100 81 162 0 973425600 37147456 17506064
02:03:23 10222 8192000000 9931222928 10271625216 961287200 10.502 100 83 166 2 1036524736 58201296 17036240
02:04:35 2595 8192000000 9989482960 10345684992 998735248 10.866 100 76 152 1 1102169536 87358144 16076144
02:05:39 2734 8192000000 10001260208 10360745984 1025696192 11.127 100 87 173 0 1114562976 71564608 17302176
02:06:44 2636 8192000000 10004579536 10371551232 1044245520 11.305 100 80 160 0 1127835056 67470272 16119264
02:07:48 1817 8192000000 10039370976 10409631744 1068737520 11.540 100 92 185 0 1149468704 61050688 19680496
02:08:51 28425 8192000000 9994316880 10393821184 1073752448 11.588 100 44 89 3 1109479328 26369504 9357376
02:10:23 7997 8192000000 9992410496 10394087424 1081515696 11.662 100 55 104 2 1113740640 20503696 11721248
02:11:31 8684 8192000000 10018748192 10431926272 1091436736 11.756 100 29 56 2 1136686208 38694496 6554976
02:12:43 6369 8192000000 10007606032 10408878080 1101404336 11.851 100 35 70 1 1122313664 12494672 8414656
02:13:50 61943 8192000000 10023376352 10429284352 1103479872 11.871 100 37 74 1 1133602896 21591120 8531904
02:15:52 6219 8192000000 10022525552 10429681664 1100819728 11.845 100 40 80 0 1139828208 30510816 8497664
02:17:02 6297 8192000000 10008422096 10408615936 1094444928 11.785 100 35 69 1 1112516848 10862848 7209072
02:18:09 6337 8192000000 9976060400 10369753088 1049579696 11.357 99 34 68 0 1072926192 16200416 7146080
02:19:19 44669 8192000000 9943743568 10336104448 953782864 10.428 90 22 43 0 971948240 14145904 4019472
02:21:09 13082 8192000000 9921821232 10306777088 982330160 10.707 97 29 57 1 1006328816 17235696 6762960
02:22:23 6856 8192000000 9958123536 10345906176 1041961888 11.284 100 40 78 2 1069212960 18169328 9081744
02:23:33 6509 8192000000 9979965600 10374537216 1056618080 11.424 100 49 100 1 1078302608 9853872 11830656
02:24:40 26379 8192000000 9973218480 10389180416 1061536736 11.471 100 30 60 1 1082891232 15289344 6065152
02:26:08 10573 8192000000 10001255312 10414120960 1075910384 11.608 100 33 66 2 1100749952 17688288 7151280
02:27:25 45729 8192000000 10010805376 10409422848 1093712752 11.778 100 30 59 0 1114609376 15493936 5402688
02:29:14 6659 8192000000 10086884432 10484359168 1121400656 12.040 100 51 102 0 1195442976 63031504 11010816
02:30:24 53710 8192000000 10108185344 10541613056 1133452400 12.154 100 29 58 1 1244131584 104602816 6076368
02:32:29 31169 8192000000 10165732576 10591748096 1147967840 12.290 100 47 94 1 1267033824 109150768 9915216
02:34:16 10245 8192000000 10186206240 10591436800 1162172288 12.424 100 60 118 0 1287272736 112700784 12399664
02:35:30 14112 8192000000 10249757520 10662559744 1174232288 12.536 100 61 122 1 1352881840 165563904 13085648
02:36:48 11601 8192000000 10247786784 10671693824 1185034736 12.637 100 46 92 1 1359949072 164121120 10793216
02:38:03 7562 8192000000 10319050128 10735996928 1193494064 12.716 100 59 116 4 1423724752 216896928 13333760
02:39:18 7148 8192000000 10356332192 10782187520 1208040384 12.851 100 77 155 2 1455216592 228577232 18598976
02:40:32 1869 8192000000 10389280752 10795438080 1196261104 12.742 100 85 169 0 1491107392 274912192 19934096
02:41:37 2880 8192000000 10426779936 10844966912 1200649264 12.782 100 74 148 0 1526117856 307628816 17839776
02:42:45 2302 8192000000 10516769392 10919702528 1191942896 12.701 100 92 183 1 1610334864 396780928 21611040
02:43:51 6813 8192000000 10489434016 10908422144 1192923344 12.711 100 62 124 0 1587079808 378144288 16012176
02:45:05 1597 8192000000 10501430416 10910896128 1194202912 12.722 100 94 185 1 1601071680 384660096 22208672
02:46:11 10343 8192000000 10481662144 10911825920 1172363184 12.519 98 52 104 1 1573041312 387479888 13198240
02:47:31 9013 8192000000 10512787872 10959372288 1172287056 12.518 99 52 103 0 1610921536 425372624 13261856
02:48:47 5958 8192000000 10529336352 10953949184 1151704848 12.325 100 57 114 0 1622562016 455745200 15111968
02:49:56 21167 8192000000 10394631664 10902949888 1155184528 12.358 100 32 64 0 1551959760 387792624 8982608
02:51:22 29361 8192000000 10236833360 10712481792 1144405664 12.257 100 14 28 3 1368728768 220840224 3482880
02:53:09 76846 8192000000 10081901136 10527784960 1129030816 12.112 100 19 34 0 1184322544 51549600 3742128
02:55:33 34398 8192000000 10068607456 10533416960 1122581760 12.051 100 12 24 0 1172732992 47867056 2284176
02:57:26 67717 8192000000 10110503216 10553819136 1113741776 11.968 98 24 49 1 1177834720 58368592 5724352
02:59:58 138055 8192000000 10101512064 10652872704 1103504544 11.871 98 18 35 1 1205309248 97456464 4348240
03:03:17 85202 8192000000 10079162416 10539941888 1094851072 11.789 97 8 15 2 1158387760 61880304 1656384
03:05:54 75205 8192000000 10058217888 10474803200 1107545488 11.909 98 23 41 0 1145635648 34451328 3638832
03:08:12 76594 8192000000 10106349584 10559471616 1089718592 11.740 97 16 32 1 1124588528 31759680 3110256
03:10:29 61289 8192000000 10100219776 10555953152 1137469104 12.192 100 16 32 0 1185929232 45019376 3440752
03:12:31 82589 8192000000 10071218224 10538287104 1126492768 12.088 99 6 12 0 1161726192 34047552 1185872
03:15:30 54350 8192000000 10086246944 10539757568 1125376208 12.078 99 33 66 3 1176737616 44350496 7010912
03:17:25 51398 8192000000 10118766496 10573340672 1145753424 12.270 100 19 38 1 1207498976 57552784 4192768
03:19:30 141257 8192000000 10119276784 10585341952 1136859200 12.186 99 19 38 0 1202854864 61730416 4265248
03:23:42 62305 8192000000 10094645152 10586701824 1133919792 12.158 99 9 18 0 1209256480 73034736 2301952
03:25:47 84571 8192000000 10139752784 10632998912 1149275792 12.303 100 23 46 1 1243605680 88898128 5431760
03:28:18 121696 8192000000 10085686096 10559119360 1069847520 11.551 93 16 32 0 1126492608 53500272 3144816
03:31:52 34696 8192000000 10113455936 10555006976 1121123072 12.038 98 30 58 1 1197994336 70205376 6665888
03:33:27 33196 8192000000 10147514832 10618171392 1139050224 12.207 99 16 32 0 1213990352 71445792 3494336
03:35:08 93716 8192000000 10053718960 10497671168 1065336192 11.508 93 15 30 0 1130385024 62106256 2942576
03:37:42 84797 8192000000 10057328784 10504785920 1112182592 11.953 100 34 68 3 1162169568 42663616 7323360
03:40:08 41938 8192000000 10033800624 10474078208 1091835328 11.760 98 26 51 2 1129001312 31615376 5550608
03:42:13 39734 8192000000 10036674848 10518577152 1102446544 11.861 99 11 22 0 1156987840 52086032 2455264
03:43:52 57604 8192000000 10057668528 10510499840 1082638816 11.673 96 13 26 1 1130330800 44964128 2727856
03:45:50 72062 8192000000 10106994192 10544783360 1080348480 11.651 96 23 46 0 1187892000 102261712 5281808
03:48:03 46227 8192000000 10072025552 10534551552 1099539824 11.833 98 34 68 1 1161219936 54154400 7525712
03:49:57 44273 8192000000 10098281872 10542383104 1109940512 11.932 99 20 40 0 1172046112 57261328 4844272
03:51:42 69755 8192000000 10069199808 10506375168 1083061760 11.677 97 29 58 1 1149754608 60959776 5733072
03:53:55 29281 8192000000 10067794832 10512695296 1102047216 11.857 100 41 82 0 1167241568 57289392 7904960
03:55:30 30519 8192000000 10088256320 10536050688 1114242976 11.973 100 33 67 0 1171318496 50448496 6627024
03:57:04 115611 8192000000 10040407040 10474053632 1106907152 11.903 99 19 38 0 1147435616 36504192 4024272
04:00:08 73046 8192000000 10084070112 10565435392 1121194032 12.038 99 6 12 0 1177323904 54941952 1187920
04:02:22 59774 8192000000 10054038096 10521141248 1110288704 11.935 98 17 33 0 1145833952 32390912 3154336
04:04:22 69413 8192000000 10102319168 10567938048 1142853968 12.242 100 21 42 2 1217741360 70517696 4369696
04:06:41 64710 8192000000 10077830848 10546241536 1129121424 12.113 99 12 23 1 1164168848 32629984 2417440
04:08:58 87096 8192000000 10108866112 10568069120 1129753216 12.119 98 18 36 0 1200920496 67047008 4120272
04:11:28 149496 8192000000 10097706368 10556743680 1073704784 11.587 93 6 12 1 1117666288 42755424 1206080
04:15:04 139861 8192000000 10102234576 10563792896 1154451440 12.351 100 26 52 0 1214170896 53849456 5870000
04:18:29 86484 8192000000 10075138032 10567438336 1123914624 12.064 98 5 10 0 1149063952 24226176 923152
04:21:06 109984 8192000000 10093605120 10588864512 1119522848 12.022 97 15 30 0 1181812640 58763184 3526608
04:24:14 32710 8192000000 10109974336 10554302464 1117575760 12.004 98 32 64 0 1183705472 59431248 6698464
04:25:55 37354 8192000000 10121981936 10568232960 1127694080 12.100 99 45 88 2 1199563376 61963136 9906160

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-04-2021 04:28:30 to 07-04-2021 04:28:45.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-04-2021 04:28:46 to 07-04-2021 04:28:48.

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          228963
MON$OLDEST_ACTIVE               233098
MON$OLDEST_SNAPSHOT             233098
MON$NEXT_TRANSACTION            233098
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                       2102976
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             796
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2406 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2406 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2406 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.2406 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 ---
:49: 
:50: mscs-test-161.budzdorov.ru    Wed Apr  7 04:27:05 2021
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:52: 
--- end of diff output ---

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

07.04.21 04:28:49. 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-04-07 04:28:51 No severe PSQL-related problems occured
07.04.21 04:28:52. End of report.