Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.03.2021 13:28
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 17000028 kB
MemAvailable 31510208 kB
Buffers 0 kB
Cached 14692388 kB
SwapCached 0 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.0
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-03-31 13:27:18
DTS_END                         2021-03-31 13:26:58.1770
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/49644
TRN_ID                          225589
ATT_ID                          259
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: 8733.36 29006 1048003
customer order: creation 161.94 420 19433
customer order: refuse 14.19 376 1703
order to supplier: creation 43.88 1445 5265
order to supplier: removal 3.23 121119 387
invoice (draft): creation 42.53 3829 5103
invoice (draft): removal 48.78 1043 5853
invoice accept: apply 39.51 18032 4741
invoice accept: cancel 43.87 877 5264
customer reserve: creation 3722.57 171 446708
customer reserve: removal 4335.10 71 520212
realization accept: apply 78.03 213 9364
realization accept: cancel 79.24 126 9509
payment from customer: creation 55.43 132 6652
payment from customer: removal 5.60 121 672
payment to supplier: creation 45.83 153 5499
payment to supplier: removal 3.19 74 383
service: total inventory turnovers 3.68 3777 442
service: total monetary turnovers 5.84 560 701
service: aggregate perf. data 0.90 16653 108
service: refresh index statistics 0.03 410924 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 31-03-2021 13:29:08 to 31-03-2021 13:29:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1377
TEST_TIME 2 2308
TEST_TIME 3 2029
TEST_TIME 4 2350
TEST_TIME 5 3001
TEST_TIME 6 2911
TEST_TIME 7 3390
TEST_TIME 8 3903
TEST_TIME 9 4000
TEST_TIME 10 4133
TEST_TIME 11 4577
TEST_TIME 12 4652
TEST_TIME 13 4896
TEST_TIME 14 5127
TEST_TIME 15 5669
TEST_TIME 16 5747
TEST_TIME 17 5850
TEST_TIME 18 6148
TEST_TIME 19 6358
TEST_TIME 20 6877
TEST_TIME 21 7226
TEST_TIME 22 7148
TEST_TIME 23 7475
TEST_TIME 24 7726
TEST_TIME 25 8490
TEST_TIME 26 9076
TEST_TIME 27 9177
TEST_TIME 28 10185
TEST_TIME 29 10287
TEST_TIME 30 10468
TEST_TIME 31 10243
TEST_TIME 32 10101
TEST_TIME 33 9837
TEST_TIME 34 9833
TEST_TIME 35 9651
TEST_TIME 36 9660
TEST_TIME 37 9556
TEST_TIME 38 9559
TEST_TIME 39 9491
TEST_TIME 40 9521
TEST_TIME 41 9490
TEST_TIME 42 9362
TEST_TIME 43 9248
TEST_TIME 44 9234
TEST_TIME 45 9142
TEST_TIME 46 9222
TEST_TIME 47 9329
TEST_TIME 48 9209
TEST_TIME 49 9239
TEST_TIME 50 9265
TEST_TIME 51 9358
TEST_TIME 52 9262
TEST_TIME 53 9354
TEST_TIME 54 9345
TEST_TIME 55 9339
TEST_TIME 56 9398
TEST_TIME 57 9413
TEST_TIME 58 9447
TEST_TIME 59 9473
TEST_TIME 60 9466
TEST_TIME 61 9332
TEST_TIME 62 9246
TEST_TIME 63 9218
TEST_TIME 64 9116
TEST_TIME 65 9025
TEST_TIME 66 8972
TEST_TIME 67 8895
TEST_TIME 68 9012
TEST_TIME 69 9017
TEST_TIME 70 9130
TEST_TIME 71 9088
TEST_TIME 72 9121
TEST_TIME 73 9114
TEST_TIME 74 9067
TEST_TIME 75 9112
TEST_TIME 76 9199
TEST_TIME 77 9189
TEST_TIME 78 9317
TEST_TIME 79 9317
TEST_TIME 80 9264
TEST_TIME 81 9176
TEST_TIME 82 9146
TEST_TIME 83 9094
TEST_TIME 84 9050
TEST_TIME 85 9037
TEST_TIME 86 9008
TEST_TIME 87 8956
TEST_TIME 88 8990
TEST_TIME 89 9005
TEST_TIME 90 9020
TEST_TIME 91 9009
TEST_TIME 92 9062
TEST_TIME 93 9056
TEST_TIME 94 9152
TEST_TIME 95 9099
TEST_TIME 96 9224
TEST_TIME 97 9193
TEST_TIME 98 9120
TEST_TIME 99 9069
TEST_TIME 100 9008
TEST_TIME 101 8954
TEST_TIME 102 8940
TEST_TIME 103 8891
TEST_TIME 104 8906
TEST_TIME 105 8884
TEST_TIME 106 8899
TEST_TIME 107 8870
TEST_TIME 108 8845
TEST_TIME 109 8842
TEST_TIME 110 8853
TEST_TIME 111 8872
TEST_TIME 112 8873
TEST_TIME 113 8914
TEST_TIME 114 8952
TEST_TIME 115 8959
TEST_TIME 116 8883
TEST_TIME 117 8846
TEST_TIME 118 8820
TEST_TIME 119 8769
TEST_TIME 120 8733

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-03-2021 13:29:12 to 31-03-2021 13:29:15.

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 1460014 1460014 0 0.00 0 41570 7 0 0
doc_list_biud 1460014 1460014 0 0.00 0 55302 32 0 0
sp_add_invoice_to_stock 4836 4741 95 1.96 24 161204 18033 0 95
sp_cancel_adding_invoice 5264 5264 0 0.00 5 73954 878 0 0
sp_cancel_client_order 1703 1703 0 0.00 4 17172 377 0 0
sp_cancel_customer_reserve 520212 520212 0 0.00 1 55303 71 0 0
sp_cancel_pay_from_customer 672 672 0 0.00 3 9927 121 0 0
sp_cancel_pay_to_supplier 383 383 0 0.00 3 2239 75 0 0
sp_cancel_supplier_invoice 5865 5853 12 0.20 2 34923 1043 0 12
sp_cancel_supplier_order 387 387 0 0.00 10 1237999 121120 0 0
sp_cancel_write_off 9509 9509 0 0.00 1 10870 126 0 0
sp_client_order 19433 19433 0 0.00 4 46487 420 0 0
sp_customer_reserve 446708 446708 0 0.00 0 105198 171 0 0
sp_fill_shopping_cart 29790 29790 0 0.00 1 23504 45 0 0
sp_fill_shopping_cart_clo_res 446708 446708 0 0.00 0 21592 17 0 0
sp_get_clo_for_invoice 4740 4740 0 0.00 0 38494 472 0 0
sp_kill_cost_storno 15828 15828 0 0.00 0 10694 62 0 0
sp_kill_qstorno_ret_qs2qd 1056173 1056173 0 0.00 0 55299 39 0 0
sp_kill_qty_storno 557032 557032 0 0.00 0 55302 83 0 0
sp_lock_selected_doc 19561 19561 0 0.00 0 10013 5 0 0
sp_make_cost_storno 26255 26255 0 0.00 0 41526 67 0 0
sp_multiply_rows_for_pdistr 26255 26255 0 0.00 0 19863 42 0 0
sp_multiply_rows_for_qdistr 19433 19433 0 0.00 0 46480 229 0 0
sp_pay_from_customer 6652 6652 0 0.00 4 24420 132 0 0
sp_pay_to_supplier 5499 5499 0 0.00 5 20111 154 0 0
sp_payment_common 12151 12151 0 0.00 1 19837 35 0 0
sp_qd_handle_on_cancel_clo 1703 1703 0 0.00 0 17165 342 0 0
sp_qd_handle_on_invoice_upd_sts 10004 10004 0 0.00 0 20656 388 0 0
sp_qd_handle_on_reserve_upd_sts 9364 9364 0 0.00 0 18821 57 0 0
sp_reserve_write_off 9364 9364 0 0.00 6 23126 214 0 0
sp_supplier_invoice 5103 5103 0 0.00 7 106066 3829 0 0
sp_supplier_order 5265 5265 0 0.00 6 116888 1445 0 0
srv_aggregate_perf_data 108 108 0 0.00 3046 165915 16653 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1135 121069 45708 0 0
srv_find_qd_qs_mism 1001925 1001925 0 0.00 0 17745 5 0 0
srv_make_invnt_saldo 442 442 0 0.00 39 111259 3778 0 0
srv_make_money_saldo 701 701 0 0.00 1 11544 560 0 0
srv_recalc_idx_stat 4 4 0 0.00 281942 515896 410924 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 4741 4741 0 0.00 0 18147 194 0 0
t$perf-norm:sp_cancel_adding_invoice 388 388 0 0.00 0 2044 68 0 0
t$perf-norm:sp_cancel_client_order 1703 1703 0 0.00 0 4157 13 0 0
t$perf-norm:sp_cancel_customer_reserve 602 602 0 0.00 0 5135 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 672 672 0 0.00 0 1073 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 383 383 0 0.00 0 316 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 872 20 0 0
t$perf-norm:sp_cancel_supplier_order 387 387 0 0.00 0 22719 1529 0 0
t$perf-norm:sp_cancel_write_off 928 928 0 0.00 0 2839 9 0 0
t$perf-norm:sp_client_order 19433 19433 0 0.00 0 8270 21 0 0
t$perf-norm:sp_customer_reserve 998 998 0 0.00 0 3999 10 0 0
t$perf-norm:sp_pay_from_customer 6652 6652 0 0.00 0 7025 10 0 0
t$perf-norm:sp_pay_to_supplier 5499 5499 0 0.00 0 5744 10 0 0
t$perf-norm:sp_reserve_write_off 9364 9364 0 0.00 0 7758 10 0 0
t$perf-norm:sp_supplier_invoice 5103 5103 0 0.00 0 14047 28 0 0
t$perf-norm:sp_supplier_order 5265 5265 0 0.00 0 5577 15 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 537 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 4 1 0 0
t$perf-norm:srv_make_invnt_saldo 442 442 0 0.00 0 545 4 0 0
t$perf-norm:srv_make_money_saldo 701 701 0 0.00 0 1253 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 17 8 0 0
v_add_invoice_to_stock 9670 9480 190 1.96 0 7120 20 0 190
v_all_customers 35518 35518 0 0.00 0 194 1 0 0
v_all_suppliers 28898 28898 0 0.00 0 17 1 0 0
v_all_wares 38866 38866 0 0.00 0 170 1 0 0
v_cancel_adding_invoice 776 776 0 0.00 0 3022 18 0 0
v_cancel_client_order 3406 3406 0 0.00 0 2533 6 0 0
v_cancel_customer_prepayment 1344 1344 0 0.00 0 4290 8 0 0
v_cancel_customer_reserve 1204 1204 0 0.00 0 1830 5 0 0
v_cancel_payment_to_supplier 766 766 0 0.00 0 536 4 0 0
v_cancel_supplier_invoice 812 788 24 2.95 0 2197 16 0 24
v_cancel_supplier_order 774 774 0 0.00 0 3608 12 0 0
v_cancel_write_off 1856 1856 0 0.00 0 1801 13 0 0
v_max_id_clo_ord 5261 5261 0 0.00 0 933 1 0 0
v_max_id_clo_res 998 998 0 0.00 0 6691 34 0 0
v_max_id_ord_sup 5096 5096 0 0.00 0 11058 7 0 0
v_max_non_paid_invoice 1407 1407 0 0.00 0 778 3 0 0
v_max_non_paid_realizn 3343 3343 0 0.00 0 1517 3 0 0
v_min_id_clo_ord 5261 5261 0 0.00 0 6003 6 0 0
v_min_id_clo_res 998 998 0 0.00 0 5546 21 0 0
v_min_id_ord_sup 5096 5096 0 0.00 0 9070 13 0 0
v_min_non_paid_invoice 5499 5499 0 0.00 0 19679 25 0 0
v_min_non_paid_realizn 6652 6652 0 0.00 0 19678 28 0 0
v_our_firm 9966 9966 0 0.00 0 14 1 0 0
v_reserve_write_off 18728 18728 0 0.00 0 3985 6 0 0
x_lock_depdocs_on_canc_invoice 10723 10723 0 0.00 0 20490 119 0 0
x_lock_depdocs_on_canc_sup_ord 387 387 0 0.00 0 5345 357 0 0
x_make_qstorno_1000_1200 5265 5265 0 0.00 2 114509 1368 0 0
x_make_qstorno_1200_2000 5103 5103 0 0.00 3 106055 3648 0 0
x_make_qstorno_2100_3300 446708 446708 0 0.00 0 105197 152 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 31-03-2021 13:29:16 to 31-03-2021 13:29:21.

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
10:57:01 1 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:58:02 31572 8192000000 9732568000 10032373760 813886000 9.037 100 90 180 0 842487504 7943456 20658048
10:59:37 22357 8192000000 9768378848 10074144768 839611968 9.296 100 91 182 1 875261696 14168160 21481568
11:01:07 6906 8192000000 9802318880 10116370432 866932816 9.569 100 86 172 0 912182704 25130960 20118928
11:02:21 14776 8192000000 9862313744 10192543744 909782592 9.995 100 85 169 1 974880880 47117952 17980336
11:03:39 8764 8192000000 9922241680 10264645632 951649856 10.407 100 84 168 0 1026572464 57156112 17766496
11:04:55 8451 8192000000 9975182816 10331549696 983903200 10.722 100 76 152 2 1092653760 93654768 15095792
11:06:06 4127 8192000000 9978737856 10335649792 1017769408 11.050 100 82 164 0 1086636208 52461008 16405792
11:07:11 2841 8192000000 10022020544 10388094976 1048196080 11.343 100 87 175 1 1129883056 63872400 17814576
11:08:16 1741 8192000000 10039571856 10413932544 1067812096 11.531 100 90 179 0 1156534896 70468704 18254096
11:09:19 9624 8192000000 10010808480 10391269376 1071434144 11.566 100 65 132 0 1117016160 31942352 13639664
11:10:29 5296 8192000000 10009215840 10399903744 1081286784 11.660 100 61 117 1 1117902304 24321440 12294080
11:11:36 8212 8192000000 10005514256 10406318080 1082052896 11.667 100 47 92 0 1125619488 34085216 9481376
11:12:47 32866 8192000000 10019665104 10414641152 1085141216 11.696 100 44 90 0 1112478384 17801600 9535568
11:14:24 4943 8192000000 9998105440 10398056448 1091962144 11.761 100 31 64 0 1123289232 22569952 8757136
11:15:31 5429 8192000000 10004852064 10403602432 1095834944 11.798 100 32 63 0 1121613056 17417712 8360400
11:16:39 11863 8192000000 10004041408 10414272512 1089517648 11.738 99 20 40 3 1116905920 22580752 4807520
11:17:54 45130 8192000000 9970013712 10379083776 1018022160 11.053 94 38 76 2 1036918016 10523120 8372736
11:19:39 6677 8192000000 10003775888 10400428032 1045509680 11.318 98 35 70 2 1075257040 22060160 7687200
11:20:47 6864 8192000000 9963005440 10351157248 1038178160 11.247 99 40 78 4 1071324480 24410016 8736304
11:21:55 10535 8192000000 9938470256 10332282880 1003129680 10.909 98 33 64 1 1037803632 27473200 7200752
11:23:07 49901 8192000000 9933597248 10321657856 980639008 10.690 95 27 54 1 1009610288 23254672 5716608
11:25:01 9791 8192000000 9985557456 10382184448 1030332928 11.172 99 44 88 1 1065673056 25340656 9999472
11:26:17 6036 8192000000 9965342816 10366279680 1054859184 11.407 100 28 55 2 1077264464 16453568 5951712
11:27:30 14574 8192000000 10004129504 10391257088 1081697728 11.664 100 48 95 0 1108049024 15825744 10525552
11:29:07 101951 8192000000 10067039328 10482032640 1100594464 11.843 100 42 84 0 1151302032 41101152 9606416
11:31:57 19761 8192000000 10070218592 10480742400 1114663008 11.977 100 28 56 1 1186008496 65092928 6252560
11:33:24 34092 8192000000 10159586016 10562596864 1137893424 12.196 100 57 114 1 1256766832 106760800 12112608
11:35:00 9396 8192000000 10197791392 10603376640 1152831984 12.336 100 67 129 0 1298096336 130783728 14480624
11:36:14 8244 8192000000 10235626032 10654957568 1158530000 12.389 100 54 108 1 1340583184 169580752 12472432
11:37:30 8216 8192000000 10265595328 10680012800 1169643536 12.493 100 65 128 0 1371882608 187821888 14417184
11:38:43 3482 8192000000 10303735504 10716815360 1184553536 12.633 100 77 149 1 1411519312 210280160 16685616
11:39:50 4928 8192000000 10317421408 10731294720 1182806048 12.616 100 57 114 0 1423417376 227470800 13140528
11:41:00 3315 8192000000 10378644640 10784706560 1181503184 12.604 100 82 164 0 1473529552 272678032 19348336
11:42:08 3321 8192000000 10375755232 10784337920 1184757344 12.635 100 84 168 0 1477690480 274829008 18104128
11:43:17 3530 8192000000 10406554192 10816049152 1194501712 12.725 100 80 157 3 1515193200 302876496 17814992
11:44:24 3425 8192000000 10457460592 10862596096 1178213568 12.574 100 83 166 1 1559606976 361907376 19486032
11:45:35 1133 8192000000 10424252096 10850906112 1180866096 12.598 100 85 173 0 1521318448 320350912 20101440
11:46:42 2762 8192000000 10487065728 10911088640 1169785232 12.495 99 73 145 0 1592801376 405624256 17391888
11:47:48 11952 8192000000 10435115712 10863882240 1164222720 12.443 98 42 84 1 1524878176 350622656 10032800
11:49:09 12393 8192000000 10462463760 10921660416 1158169936 12.386 100 43 86 1 1573641888 404163424 11308528
11:50:25 14258 8192000000 10462159840 10901364736 1139612384 12.212 99 37 74 2 1555983184 406378000 9992800
11:51:41 6097 8192000000 10351003152 10826002432 1138226640 12.199 100 45 87 3 1455035056 304679040 12129376
11:52:53 23019 8192000000 10286828560 10759503872 1121524976 12.041 100 24 48 1 1367071952 239105744 6441232
11:54:17 18886 8192000000 10223453744 10667261952 1089988800 11.743 97 21 42 3 1292325184 197011872 5324512
11:56:34 91915 8192000000 10075643392 10531803136 1068813328 11.541 95 15 29 0 1124617408 52736096 3067984
11:59:07 54544 8192000000 10084453168 10506723328 1134262992 12.162 100 45 86 1 1170578688 27168736 9146960
12:01:03 67047 8192000000 10089989808 10509852672 1123782832 12.063 98 28 54 0 1172648240 43690400 5175008
12:03:14 20172 8192000000 10114518496 10547679232 1150378336 12.313 100 42 83 1 1212506064 53243280 8884448
12:04:36 27259 8192000000 10112086496 10573955072 1134584864 12.165 99 38 75 1 1197961968 55262128 8114976
12:06:07 49729 8192000000 10077403920 10546458624 1127999072 12.102 99 17 34 0 1186091920 54149504 3943344
12:08:02 41536 8192000000 10132632416 10577649664 1144656752 12.259 99 41 82 0 1235083744 80484832 9942160
12:09:46 58947 8192000000 10169108960 10619158528 1129094656 12.113 99 21 42 0 1262895952 128746512 5054784
12:11:46 26091 8192000000 10156882032 10630180864 1135713360 12.175 100 9 18 0 1268740704 130926256 2101088
12:13:13 64316 8192000000 10063579824 10548432896 1125276064 12.077 98 14 28 0 1213857680 85632800 2948816
12:15:18 57344 8192000000 10080324896 10563547136 1133200688 12.152 99 17 35 0 1191136528 54281936 3653904
12:17:21 52168 8192000000 10076914368 10536108032 1120594976 12.033 98 17 34 1 1158747776 35137280 3015520
12:19:25 56120 8192000000 10062866672 10526265344 1111987184 11.951 97 11 22 0 1194793792 80143952 2662656
12:21:22 41740 8192000000 10133527584 10595074048 1150810976 12.317 100 38 76 0 1228914768 70772768 7331024
12:23:11 100465 8192000000 10089742384 10586058752 1120596976 12.033 97 24 48 2 1179780624 54352144 4831504
12:26:39 121069 8192000000 10065685536 10514677760 1115170128 11.981 97 26 52 0 1153687920 33761120 4756672
12:29:41 65153 8192000000 10074304544 10530058240 1129746720 12.119 99 9 18 0 1171681360 40223312 1711328
12:31:52 63148 8192000000 10113025056 10575773696 1119600176 12.023 98 15 30 1 1184201936 61373376 3228384
12:33:57 46071 8192000000 10168829296 10635780096 1095875792 11.798 96 20 40 2 1204927760 104332736 4719232
12:35:44 35105 8192000000 10087973472 10547707904 1097534144 11.814 99 12 24 0 1165241552 65099600 2607808
12:37:22 42097 8192000000 10066527616 10500341760 1085566976 11.700 97 39 76 0 1119141472 25952144 7622352
12:39:06 23606 8192000000 10066252960 10489561088 1101434672 11.851 98 48 93 2 1133920288 22370112 10115504
12:40:34 74808 8192000000 10109394496 10575589376 1113445808 11.965 99 26 52 0 1217458560 98202992 5809760
12:42:50 63087 8192000000 10125990416 10587320320 1101718848 11.854 97 27 54 2 1157733792 50526032 5488912
12:45:00 116571 8192000000 10086663008 10574790656 1102360080 11.860 97 15 30 2 1175545344 69926272 3258992
12:48:22 47529 8192000000 10050970592 10527703040 1126687568 12.090 100 14 28 0 1176556480 47208944 2659968
12:50:10 47940 8192000000 10069793200 10518265856 1125098880 12.075 100 21 43 0 1166665600 37118832 4447888
12:51:58 75810 8192000000 10093689744 10529378304 1135216512 12.171 100 11 22 1 1191332352 53840768 2275072
12:54:16 51273 8192000000 10112685504 10582204416 1151052064 12.319 100 17 33 0 1243447168 88472160 3922944
12:56:08 95814 8192000000 10141594272 10609864704 1113889712 11.969 97 17 34 1 1190141888 72748352 3503824
12:58:44 88975 8192000000 10127381456 10595250176 1126977072 12.093 98 18 36 1 1213410672 82537536 3896064
13:02:06 35420 8192000000 10167731200 10648166400 1137686080 12.194 99 27 54 1 1260875760 117375632 5814048
13:03:41 104346 8192000000 10066149712 10519965696 1128400896 12.106 99 18 34 4 1196660800 64562592 3697312
13:06:32 30232 8192000000 10067497792 10532032512 1132484176 12.145 100 4 8 0 1168561152 35117376 959600
13:08:02 81212 8192000000 10065925680 10541985792 1095289680 11.793 96 10 20 0 1153667872 55981088 2397104
13:10:29 108246 8192000000 10083294576 10521899008 1101757856 11.854 97 15 30 0 1153530528 48850720 2921952
13:13:20 34064 8192000000 10063875248 10501873664 1120722256 12.034 99 25 50 0 1153089728 27096528 5270944
13:15:00 40261 8192000000 10096818832 10536464384 1117565856 12.004 98 27 54 0 1167923408 44712096 5645456
13:16:42 31575 8192000000 10081563760 10524565504 1111772928 11.949 98 30 60 0 1155115184 37102912 6239344
13:18:18 80956 8192000000 10067927008 10538176512 1111727712 11.949 98 9 18 0 1182419600 68909648 1782240
13:20:39 91811 8192000000 10089703600 10593726464 1138804000 12.204 100 11 22 0 1204581424 63602816 2174608
13:23:13 47651 8192000000 10042815216 10488758272 1072967440 11.580 94 19 38 1 1101045248 24258064 3819744
13:25:12 63343 8192000000 10038000208 10485116928 1097901168 11.818 98 11 22 0 1123232720 23041392 2290160

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 31-03-2021 13:29:22 to 31-03-2021 13:29:38.

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 31-03-2021 13:29:38 to 31-03-2021 13:29:40.

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          225294
MON$OLDEST_ACTIVE               230478
MON$OLDEST_SNAPSHOT             230478
MON$NEXT_TRANSACTION            230478
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                       2079968
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             782
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2395 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2395 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2395 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2395 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:20: 
:21: mscs-test-161.budzdorov.ru    Wed Mar 31 13:27:24 2021
:22:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:23: 
--- end of diff output ---

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

31.03.21 13:29:41. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-03-31 13:29:44 No severe PSQL-related problems occured
31.03.21 13:29:45. End of report.