Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.01.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 e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16539268 kB
MemAvailable 31290824 kB
Buffers 20 kB
Cached 14987320 kB
SwapCached 4864 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-01-03 04:26:49
DTS_END                         2021-01-03 04:26:49.5090
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/48872
TRN_ID                          226541
ATT_ID                          600
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: 9165.87 25174 1099904
customer order: creation 151.81 412 18217
customer order: refuse 13.72 329 1646
order to supplier: creation 42.72 1387 5126
order to supplier: removal 3.70 116052 444
invoice (draft): creation 42.03 3442 5044
invoice (draft): removal 55.64 900 6677
invoice accept: apply 37.69 17501 4523
invoice accept: cancel 49.96 807 5995
customer reserve: creation 3561.80 166 427416
customer reserve: removal 4928.81 69 591457
realization accept: apply 75.58 219 9070
realization accept: cancel 85.41 123 10249
payment from customer: creation 53.71 135 6445
payment from customer: removal 5.11 107 613
payment to supplier: creation 45.18 175 5421
payment to supplier: removal 2.87 90 344
service: total inventory turnovers 3.36 3938 403
service: total monetary turnovers 5.96 524 715
service: aggregate perf. data 0.79 13991 95
service: refresh index statistics 0.03 343118 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-01-2021 04:28:19 to 03-01-2021 04:28:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3609
TEST_TIME 2 3116
TEST_TIME 3 3857
TEST_TIME 4 3458
TEST_TIME 5 3618
TEST_TIME 6 4026
TEST_TIME 7 4112
TEST_TIME 8 4338
TEST_TIME 9 5129
TEST_TIME 10 5020
TEST_TIME 11 5284
TEST_TIME 12 5226
TEST_TIME 13 5636
TEST_TIME 14 5838
TEST_TIME 15 6339
TEST_TIME 16 6803
TEST_TIME 17 6952
TEST_TIME 18 6986
TEST_TIME 19 7058
TEST_TIME 20 7717
TEST_TIME 21 8238
TEST_TIME 22 8369
TEST_TIME 23 8695
TEST_TIME 24 8824
TEST_TIME 25 9208
TEST_TIME 26 9456
TEST_TIME 27 10838
TEST_TIME 28 11260
TEST_TIME 29 11149
TEST_TIME 30 11005
TEST_TIME 31 10826
TEST_TIME 32 10680
TEST_TIME 33 10409
TEST_TIME 34 10331
TEST_TIME 35 10379
TEST_TIME 36 10245
TEST_TIME 37 10143
TEST_TIME 38 10266
TEST_TIME 39 10399
TEST_TIME 40 10257
TEST_TIME 41 10168
TEST_TIME 42 10049
TEST_TIME 43 9984
TEST_TIME 44 10117
TEST_TIME 45 10306
TEST_TIME 46 10251
TEST_TIME 47 10215
TEST_TIME 48 10237
TEST_TIME 49 10165
TEST_TIME 50 10352
TEST_TIME 51 10468
TEST_TIME 52 10390
TEST_TIME 53 10372
TEST_TIME 54 10338
TEST_TIME 55 10403
TEST_TIME 56 10495
TEST_TIME 57 10334
TEST_TIME 58 10191
TEST_TIME 59 10134
TEST_TIME 60 10092
TEST_TIME 61 10012
TEST_TIME 62 9913
TEST_TIME 63 9794
TEST_TIME 64 9694
TEST_TIME 65 9714
TEST_TIME 66 9769
TEST_TIME 67 9759
TEST_TIME 68 9794
TEST_TIME 69 9773
TEST_TIME 70 9874
TEST_TIME 71 9863
TEST_TIME 72 9877
TEST_TIME 73 9825
TEST_TIME 74 9952
TEST_TIME 75 9994
TEST_TIME 76 9983
TEST_TIME 77 10008
TEST_TIME 78 9969
TEST_TIME 79 9849
TEST_TIME 80 9734
TEST_TIME 81 9695
TEST_TIME 82 9627
TEST_TIME 83 9558
TEST_TIME 84 9504
TEST_TIME 85 9513
TEST_TIME 86 9454
TEST_TIME 87 9400
TEST_TIME 88 9386
TEST_TIME 89 9393
TEST_TIME 90 9373
TEST_TIME 91 9409
TEST_TIME 92 9427
TEST_TIME 93 9501
TEST_TIME 94 9541
TEST_TIME 95 9485
TEST_TIME 96 9486
TEST_TIME 97 9453
TEST_TIME 98 9388
TEST_TIME 99 9369
TEST_TIME 100 9328
TEST_TIME 101 9311
TEST_TIME 102 9274
TEST_TIME 103 9271
TEST_TIME 104 9262
TEST_TIME 105 9256
TEST_TIME 106 9274
TEST_TIME 107 9277
TEST_TIME 108 9264
TEST_TIME 109 9345
TEST_TIME 110 9382
TEST_TIME 111 9349
TEST_TIME 112 9375
TEST_TIME 113 9386
TEST_TIME 114 9384
TEST_TIME 115 9373
TEST_TIME 116 9293
TEST_TIME 117 9246
TEST_TIME 118 9247
TEST_TIME 119 9208
TEST_TIME 120 9166

Performance per minute, chart


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

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 1494527 1494527 0 0.00 0 25275 7 0 0
doc_list_biud 1494525 1494525 0 0.00 0 68131 34 0 0
sp_add_invoice_to_stock 4656 4523 133 2.85 29 195178 17502 0 133
sp_cancel_adding_invoice 5995 5995 0 0.00 4 60226 807 0 0
sp_cancel_client_order 1646 1646 0 0.00 4 14088 330 0 0
sp_cancel_customer_reserve 591457 591457 0 0.00 1 68133 69 0 0
sp_cancel_pay_from_customer 613 613 0 0.00 3 14561 107 0 0
sp_cancel_pay_to_supplier 344 344 0 0.00 3 3766 90 0 0
sp_cancel_supplier_invoice 6683 6677 6 0.08 2 30656 900 0 6
sp_cancel_supplier_order 444 444 0 0.00 7 1233349 116053 0 0
sp_cancel_write_off 10249 10249 0 0.00 1 13125 123 0 0
sp_client_order 18217 18217 0 0.00 4 20745 412 0 0
sp_customer_reserve 427416 427416 0 0.00 0 39819 166 0 0
sp_fill_shopping_cart 28377 28377 0 0.00 1 13190 40 0 0
sp_fill_shopping_cart_clo_res 427416 427416 0 0.00 0 22385 16 0 0
sp_get_clo_for_invoice 4523 4523 0 0.00 0 29892 372 0 0
sp_kill_cost_storno 17201 17201 0 0.00 0 13118 59 0 0
sp_kill_qstorno_ret_qs2qd 1200284 1200284 0 0.00 0 68129 37 0 0
sp_kill_qty_storno 630057 630057 0 0.00 0 68130 80 0 0
sp_lock_selected_doc 18955 18955 0 0.00 0 14552 9 0 0
sp_make_cost_storno 25457 25457 0 0.00 0 23812 80 0 0
sp_multiply_rows_for_pdistr 25455 25455 0 0.00 0 9027 38 0 0
sp_multiply_rows_for_qdistr 18217 18217 0 0.00 0 17327 221 0 0
sp_pay_from_customer 6445 6445 0 0.00 4 27469 136 0 0
sp_pay_to_supplier 5421 5421 0 0.00 5 24004 175 0 0
sp_payment_common 11866 11866 0 0.00 1 8770 32 0 0
sp_qd_handle_on_cancel_clo 1646 1646 0 0.00 0 14081 301 0 0
sp_qd_handle_on_invoice_upd_sts 10514 10514 0 0.00 0 36575 399 0 0
sp_qd_handle_on_reserve_upd_sts 9070 9070 0 0.00 0 31333 66 0 0
sp_reserve_write_off 9070 9070 0 0.00 7 31358 220 0 0
sp_supplier_invoice 5044 5044 0 0.00 7 128518 3443 0 0
sp_supplier_order 5126 5126 0 0.00 5 31847 1387 0 0
srv_aggregate_perf_data 95 95 0 0.00 2780 163062 13991 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2484 150997 51894 0 0
srv_find_qd_qs_mism 1055884 1055884 0 0.00 0 25275 5 0 0
srv_make_invnt_saldo 403 403 0 0.00 46 113585 3938 0 0
srv_make_money_saldo 715 715 0 0.00 1 16450 524 0 0
srv_recalc_idx_stat 4 4 0 0.00 176140 533026 343119 0 0
t$perf-abend:sp_add_invoice_to_stock 133 133 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4523 4523 0 0.00 0 18617 215 0 0
t$perf-norm:sp_cancel_adding_invoice 379 379 0 0.00 1 2195 74 0 0
t$perf-norm:sp_cancel_client_order 1646 1646 0 0.00 0 8852 19 0 0
t$perf-norm:sp_cancel_customer_reserve 606 606 0 0.00 0 4018 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 613 613 0 0.00 0 365 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 344 344 0 0.00 0 67 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 1563 19 0 0
t$perf-norm:sp_cancel_supplier_order 444 444 0 0.00 0 16441 1432 0 0
t$perf-norm:sp_cancel_write_off 945 945 0 0.00 0 12425 26 0 0
t$perf-norm:sp_client_order 18217 18217 0 0.00 0 25554 22 0 0
t$perf-norm:sp_customer_reserve 958 958 0 0.00 0 5417 16 0 0
t$perf-norm:sp_pay_from_customer 6446 6446 0 0.00 0 5969 10 0 0
t$perf-norm:sp_pay_to_supplier 5421 5421 0 0.00 0 16872 21 0 0
t$perf-norm:sp_reserve_write_off 9070 9070 0 0.00 0 23739 20 0 0
t$perf-norm:sp_supplier_invoice 5044 5044 0 0.00 0 15863 24 0 0
t$perf-norm:sp_supplier_order 5126 5126 0 0.00 0 4422 18 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 423 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 696 23 0 0
t$perf-norm:srv_make_invnt_saldo 403 403 0 0.00 0 16866 51 0 0
t$perf-norm:srv_make_money_saldo 715 715 0 0.00 0 4181 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 3 0 0
v_add_invoice_to_stock 9304 9038 266 2.85 0 3897 17 0 266
v_all_customers 33486 33486 0 0.00 0 79 1 0 0
v_all_suppliers 28634 28634 0 0.00 0 19 1 0 0
v_all_wares 36434 36434 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 758 758 0 0.00 0 2795 10 0 0
v_cancel_client_order 3292 3292 0 0.00 0 1438 4 0 0
v_cancel_customer_prepayment 1226 1226 0 0.00 0 1661 6 0 0
v_cancel_customer_reserve 1212 1212 0 0.00 0 2238 8 0 0
v_cancel_payment_to_supplier 688 688 0 0.00 0 1032 6 0 0
v_cancel_supplier_invoice 790 778 12 1.51 0 7226 33 0 12
v_cancel_supplier_order 888 888 0 0.00 0 2156 8 0 0
v_cancel_write_off 1890 1890 0 0.00 0 277 11 0 0
v_max_id_clo_ord 5123 5123 0 0.00 0 296 1 0 0
v_max_id_clo_res 958 958 0 0.00 0 4316 22 0 0
v_max_id_ord_sup 5034 5034 0 0.00 0 6132 5 0 0
v_max_non_paid_invoice 1261 1261 0 0.00 0 984 4 0 0
v_max_non_paid_realizn 3380 3380 0 0.00 0 5280 6 0 0
v_min_id_clo_ord 5123 5123 0 0.00 0 4943 11 0 0
v_min_id_clo_res 958 958 0 0.00 0 2851 8 0 0
v_min_id_ord_sup 5034 5034 0 0.00 0 9448 11 0 0
v_min_non_paid_invoice 5421 5421 0 0.00 0 7025 24 0 0
v_min_non_paid_realizn 6445 6445 0 0.00 0 8444 18 0 0
v_our_firm 9078 9078 0 0.00 0 21 1 0 0
v_reserve_write_off 18140 18140 0 0.00 0 3200 5 0 0
x_lock_depdocs_on_canc_invoice 12283 12283 0 0.00 0 12149 105 0 0
x_lock_depdocs_on_canc_sup_ord 444 444 0 0.00 0 4124 310 0 0
x_make_qstorno_1000_1200 5126 5126 0 0.00 2 31838 1298 0 0
x_make_qstorno_1200_2000 5044 5044 0 0.00 4 128457 3307 0 0
x_make_qstorno_2100_3300 427416 427416 0 0.00 0 39818 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-01-2021 04:28:27 to 03-01-2021 04:28:31.

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:56:53 6 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
01:57:54 21064 8192000000 9721104064 10015465472 795686384 8.853 100 84 168 0 823292736 7195536 20410816
01:59:20 23360 8192000000 9764461712 10070560768 839271136 9.292 100 88 174 1 872178688 12358528 20549024
02:00:57 13456 8192000000 9814416640 10130305024 875447408 9.654 100 88 176 0 921568048 25592672 20527968
02:02:19 13501 8192000000 9870107968 10197831680 906783296 9.965 100 84 168 1 969294480 43946176 18565008
02:03:36 13906 8192000000 9912148032 10252066816 945601040 10.348 100 77 154 1 1020976560 59410144 15965376
02:04:52 6235 8192000000 9950348400 10303696896 990325520 10.785 100 77 153 0 1064351376 57837616 16188240
02:06:00 2945 8192000000 10019031248 10375905280 1021468800 11.086 100 89 178 0 1130182928 90784320 17929808
02:07:05 2336 8192000000 10015288896 10382389248 1047856576 11.340 100 86 173 0 1128652336 63267504 17528256
02:08:09 1892 8192000000 10052955952 10419720192 1064406400 11.499 100 91 181 2 1162121424 78703792 19011232
02:09:14 5190 8192000000 10026558016 10405588992 1071177536 11.563 100 69 140 2 1150012688 64103200 14731952
02:10:22 3831 8192000000 10007136048 10394542080 1073170992 11.582 100 40 80 3 1122010672 40751296 8088384
02:11:26 7151 8192000000 9998034800 10397155328 1078774688 11.636 100 35 69 3 1102650608 16759792 7116128
02:12:40 4940 8192000000 10008120528 10396082176 1090907472 11.751 100 41 82 2 1119553456 18961104 9684880
02:13:46 42423 8192000000 9991665104 10408947712 1089488384 11.738 100 23 45 3 1118193744 23324368 5380992
02:15:30 9816 8192000000 10010236016 10421035008 1087267056 11.717 99 42 82 2 1118860992 21316272 10277664
02:16:40 6515 8192000000 9996921008 10397851648 1073225232 11.583 98 24 48 1 1095505664 16789888 5490544
02:17:48 8818 8192000000 9974821840 10378985472 1054616848 11.405 99 23 46 0 1077799424 18517968 4664608
02:18:59 7887 8192000000 9949509632 10337447936 1024139504 11.112 98 47 92 2 1051217088 15714912 11362672
02:20:07 8253 8192000000 9920142464 10309627904 988223760 10.764 96 31 62 1 1014355856 19013008 7119088
02:21:16 8456 8192000000 9943574000 10326540288 1016999952 11.043 100 40 80 3 1055838368 30096960 8741456
02:22:28 5747 8192000000 9956109680 10339688448 1028198224 11.151 99 47 95 3 1049024896 9720816 11105856
02:23:37 31135 8192000000 9962516832 10343276544 1042349056 11.287 100 55 107 2 1065071712 11128768 11593888
02:25:08 5407 8192000000 9981908848 10371002368 1060906096 11.465 100 32 62 2 1090759856 22368048 7485712
02:26:17 8754 8192000000 9986367136 10389958656 1070397152 11.556 100 38 77 2 1089355600 10297904 8660544
02:27:38 34104 8192000000 10013642752 10412175360 1093208816 11.773 100 43 84 2 1122177568 19616736 9352016
02:29:29 95111 8192000000 10086258464 10498113536 1109907632 11.932 100 33 66 0 1191446672 74557536 6981504
02:32:06 31215 8192000000 10144462512 10564026368 1128052352 12.103 100 40 77 0 1242400256 106082752 8265152
02:33:56 7346 8192000000 10199378464 10603421696 1140270944 12.218 100 54 108 0 1305533168 153894560 11367664
02:35:07 18923 8192000000 10227293920 10636574720 1148643248 12.297 100 58 115 0 1335803008 174643856 12515904
02:36:35 4919 8192000000 10253608160 10661494784 1168168480 12.480 100 56 109 0 1364314448 184289296 11856672
02:37:44 8149 8192000000 10295182704 10707128320 1169922080 12.496 100 55 110 2 1399312624 216195312 13195232
02:38:56 3232 8192000000 10330009392 10734796800 1169031472 12.488 100 69 138 1 1428751376 243866384 15853520
02:40:03 2482 8192000000 10369505152 10771038208 1172254640 12.518 100 75 149 1 1473409552 284769216 16385696
02:41:08 2480 8192000000 10401724768 10804248576 1172614176 12.521 100 91 182 1 1496244544 302436560 21193808
02:42:19 4283 8192000000 10364886272 10779111424 1145079296 12.263 99 84 168 1 1461751840 297146224 19526320
02:43:28 3489 8192000000 10357870832 10767511552 1126051552 12.084 98 79 156 2 1465481584 321526160 17903872
02:44:38 8785 8192000000 10415653504 10824880128 1163417056 12.435 100 67 134 2 1517928176 338766192 15744928
02:45:51 2748 8192000000 10467824592 10869964800 1139540880 12.211 100 69 138 0 1565306960 408404944 17361136
02:47:00 10842 8192000000 10416411152 10857902080 1146457120 12.276 99 52 101 2 1525409904 366445792 12506992
02:48:18 20158 8192000000 10378295936 10829676544 1148526896 12.296 100 35 70 2 1488605920 331467024 8612000
02:50:00 7885 8192000000 10376757056 10823184384 1139797040 12.214 99 34 67 0 1465700704 316871408 9032256
02:51:10 19117 8192000000 10378561392 10878459904 1140315808 12.219 100 34 68 0 1496289072 346902400 9070864
02:52:30 20769 8192000000 10394314608 10886098944 1121804608 12.044 100 23 46 0 1518698256 389989856 6903792
02:53:53 34806 8192000000 10079470720 10569895936 1112674848 11.958 99 13 26 3 1240327584 124517616 3135120
02:55:30 62080 8192000000 10054586128 10483736576 1100477040 11.842 98 9 16 1 1137257616 35045776 1734800
02:57:34 73354 8192000000 10088112272 10515177472 1120142608 12.028 99 32 64 3 1183865200 56301584 7421008
02:59:47 107883 8192000000 10058449184 10525683712 1103040016 11.866 98 24 48 1 1230470064 121853440 5576608
03:02:37 60442 8192000000 10169931392 10598105088 1116750240 11.996 98 38 74 2 1232140208 107445472 7944496
03:04:38 44611 8192000000 10110057552 10652446720 1135507056 12.173 100 21 42 0 1263671888 123177856 4986976
03:06:24 56418 8192000000 10113563824 10557906944 1105062704 11.886 97 17 34 1 1174714704 65937584 3714416
03:08:43 63364 8192000000 10149848000 10614960128 1135136864 12.170 99 17 34 0 1226309152 87617488 3554800
03:10:47 75400 8192000000 10060412288 10525384704 1120770944 12.034 98 19 38 2 1197074176 72155360 4147872
03:13:04 44510 8192000000 10126436448 10581364736 1139769552 12.213 99 17 34 2 1212561248 69090672 3701024
03:14:53 43193 8192000000 10175303360 10612469760 1137345216 12.191 99 48 95 0 1226322768 79259040 9718512
03:16:36 39386 8192000000 10114160640 10559426560 1133220464 12.152 99 16 32 1 1212637808 75738160 3679184
03:18:16 60112 8192000000 10094593312 10547281920 1127229824 12.095 98 14 28 0 1168239344 38276128 2733392
03:20:19 41363 8192000000 10098436496 10543140864 1124323328 12.068 98 33 67 0 1163078720 32082448 6672944
03:22:05 150996 8192000000 10093914352 10631245824 1132982544 12.149 99 15 30 0 1198847920 62971136 2894240
03:26:00 76865 8192000000 10081840144 10565001216 1116942176 11.998 98 9 18 0 1165965504 47384880 1638448
03:28:51 31480 8192000000 10125346560 10568826880 1136355488 12.181 99 17 34 2 1204678480 64566688 3756304
03:30:24 85228 8192000000 10140792784 10595917824 1128965952 12.112 98 20 40 1 1230588272 96846640 4775680
03:33:12 55237 8192000000 10112595168 10560397312 1126232608 12.086 98 26 52 2 1204346864 71931552 6182704
03:35:08 60834 8192000000 10111951120 10557681664 1040024544 11.265 91 29 58 3 1160931408 114671632 6235232
03:37:16 44091 8192000000 10081775424 10522599424 1081799440 11.665 96 28 56 1 1137713696 50441296 5472960
03:39:10 38022 8192000000 10089490208 10506113024 1083673296 11.682 97 40 78 4 1147040192 54878144 8488752
03:40:50 85469 8192000000 10071328368 10576035840 1079962880 11.647 96 14 28 1 1128018032 45042640 3012512
03:43:17 109490 8192000000 10069178672 10544701440 1095130464 11.791 98 10 20 0 1129643360 32606672 1906224
03:46:12 89990 8192000000 10028195360 10493112320 1099534352 11.833 99 17 34 1 1148609744 45775248 3300144
03:48:54 47697 8192000000 10057858624 10502844416 1121239088 12.039 100 7 14 0 1159482896 37049664 1194144
03:50:42 42846 8192000000 10057801040 10522624000 1118586256 12.014 100 10 20 1 1186420208 65758656 2075296
03:52:29 51715 8192000000 10072283136 10501836800 1094442256 11.785 97 17 33 1 1128556128 30035328 4078544
03:54:29 48552 8192000000 10126792400 10566590464 1131008880 12.131 99 28 56 0 1211482176 73782608 6690688
03:56:21 35273 8192000000 10181037168 10619285504 1142826800 12.242 100 41 82 1 1252020560 100096064 9097696
03:58:06 61106 8192000000 10138135840 10629410816 1114673872 11.977 98 25 50 0 1233716112 113371872 5670368
04:00:13 111579 8192000000 10102112720 10533445632 1110416416 11.936 98 24 48 0 1162660624 47357376 4886832
04:03:07 73998 8192000000 10098908560 10549542912 1129782880 12.119 99 33 66 1 1196160080 60096240 6280960
04:05:58 61467 8192000000 10064717504 10537209856 1110169904 11.934 98 10 20 0 1146442176 34003872 2268400
04:08:00 132289 8192000000 10102561984 10546679808 1116556944 11.994 98 18 36 0 1171144384 50978144 3609296
04:11:17 94575 8192000000 10138561952 10615586816 1159566896 12.399 100 17 34 1 1258030688 94353744 4110048
04:13:58 65349 8192000000 10142477024 10594471936 1162819712 12.430 100 26 52 2 1251343392 82541888 5981792
04:16:05 127211 8192000000 10115978368 10565087232 1122356192 12.049 97 6 12 0 1165751024 42487552 907280
04:19:13 59575 8192000000 10100692144 10568982528 1134455984 12.163 99 12 24 1 1171874064 34986720 2431360
04:21:14 110426 8192000000 10115200160 10598309888 1132990640 12.150 99 10 20 0 1208049232 72869600 2188992
04:24:20 54859 8192000000 10054335040 10533912576 1111932736 11.951 98 22 44 0 1171878160 54772032 5173392
04:26:16 46258 8192000000 9448200400 9805328384 560301808 6.401 51 4 8 0 591352864 30266960 784096

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-01-2021 04:28:33 to 03-01-2021 04:28:48.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-01-2021 04:28:49 to 03-01-2021 04:28:51.

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          226534
MON$OLDEST_ACTIVE               226830
MON$OLDEST_SNAPSHOT             226830
MON$NEXT_TRANSACTION            226830
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                       2105216
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             781
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2315 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2315 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2315 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.2315 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 ---
:85: 
:86: mscs-test-161.budzdorov.ru    Sun Jan  3 04:27:10 2021
:87:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:88: 
:89: 
:90: mscs-test-161.budzdorov.ru    Sun Jan  3 04:27:13 2021
:91:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:92: 
--- end of diff output ---

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

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