Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.02.2021 13:29
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 16364800 kB
MemAvailable 31243868 kB
Buffers 0 kB
Cached 15338704 kB
SwapCached 4876 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-02-06 13:26:58
DTS_END                         2021-02-06 13:26:58.1460
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/39718
TRN_ID                          238828
ATT_ID                          670
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: 9351.72 27161 1122206
customer order: creation 169.47 405 20336
customer order: refuse 14.98 379 1798
order to supplier: creation 45.82 1423 5498
order to supplier: removal 3.37 126142 404
invoice (draft): creation 46.63 3500 5595
invoice (draft): removal 52.18 967 6262
invoice accept: apply 43.10 19982 5172
invoice accept: cancel 46.84 1023 5621
customer reserve: creation 4004.18 193 480502
customer reserve: removal 4640.06 72 556807
realization accept: apply 81.46 182 9775
realization accept: cancel 74.18 136 8902
payment from customer: creation 60.02 128 7202
payment from customer: removal 5.74 138 689
payment to supplier: creation 48.78 134 5854
payment to supplier: removal 3.33 135 399
service: total inventory turnovers 3.58 4076 429
service: total monetary turnovers 7.03 610 843
service: aggregate perf. data 0.94 14905 113
service: refresh index statistics 0.04 368686 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-02-2021 13:29:58 to 06-02-2021 13:30:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3557
TEST_TIME 2 4004
TEST_TIME 3 4004
TEST_TIME 4 4431
TEST_TIME 5 4357
TEST_TIME 6 4650
TEST_TIME 7 4923
TEST_TIME 8 5348
TEST_TIME 9 5595
TEST_TIME 10 5899
TEST_TIME 11 5973
TEST_TIME 12 6007
TEST_TIME 13 6407
TEST_TIME 14 7006
TEST_TIME 15 7266
TEST_TIME 16 7885
TEST_TIME 17 7984
TEST_TIME 18 8272
TEST_TIME 19 8276
TEST_TIME 20 8464
TEST_TIME 21 8613
TEST_TIME 22 9031
TEST_TIME 23 8914
TEST_TIME 24 9052
TEST_TIME 25 8922
TEST_TIME 26 8940
TEST_TIME 27 8968
TEST_TIME 28 9846
TEST_TIME 29 10022
TEST_TIME 30 10782
TEST_TIME 31 11357
TEST_TIME 32 11503
TEST_TIME 33 11311
TEST_TIME 34 11314
TEST_TIME 35 11111
TEST_TIME 36 10998
TEST_TIME 37 10787
TEST_TIME 38 10516
TEST_TIME 39 10412
TEST_TIME 40 10428
TEST_TIME 41 10421
TEST_TIME 42 10325
TEST_TIME 43 10264
TEST_TIME 44 10128
TEST_TIME 45 10026
TEST_TIME 46 10003
TEST_TIME 47 10228
TEST_TIME 48 10264
TEST_TIME 49 10231
TEST_TIME 50 10385
TEST_TIME 51 10324
TEST_TIME 52 10272
TEST_TIME 53 10207
TEST_TIME 54 10279
TEST_TIME 55 10234
TEST_TIME 56 10307
TEST_TIME 57 10249
TEST_TIME 58 10270
TEST_TIME 59 10139
TEST_TIME 60 10066
TEST_TIME 61 9962
TEST_TIME 62 9899
TEST_TIME 63 9837
TEST_TIME 64 9759
TEST_TIME 65 9692
TEST_TIME 66 9575
TEST_TIME 67 9553
TEST_TIME 68 9533
TEST_TIME 69 9605
TEST_TIME 70 9589
TEST_TIME 71 9565
TEST_TIME 72 9567
TEST_TIME 73 9574
TEST_TIME 74 9615
TEST_TIME 75 9703
TEST_TIME 76 9632
TEST_TIME 77 9687
TEST_TIME 78 9687
TEST_TIME 79 9718
TEST_TIME 80 9695
TEST_TIME 81 9608
TEST_TIME 82 9561
TEST_TIME 83 9529
TEST_TIME 84 9512
TEST_TIME 85 9444
TEST_TIME 86 9395
TEST_TIME 87 9373
TEST_TIME 88 9369
TEST_TIME 89 9380
TEST_TIME 90 9347
TEST_TIME 91 9441
TEST_TIME 92 9409
TEST_TIME 93 9442
TEST_TIME 94 9416
TEST_TIME 95 9566
TEST_TIME 96 9538
TEST_TIME 97 9552
TEST_TIME 98 9480
TEST_TIME 99 9402
TEST_TIME 100 9343
TEST_TIME 101 9324
TEST_TIME 102 9324
TEST_TIME 103 9313
TEST_TIME 104 9293
TEST_TIME 105 9260
TEST_TIME 106 9234
TEST_TIME 107 9231
TEST_TIME 108 9390
TEST_TIME 109 9370
TEST_TIME 110 9395
TEST_TIME 111 9390
TEST_TIME 112 9344
TEST_TIME 113 9412
TEST_TIME 114 9426
TEST_TIME 115 9390
TEST_TIME 116 9466
TEST_TIME 117 9487
TEST_TIME 118 9491
TEST_TIME 119 9423
TEST_TIME 120 9357

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-02-2021 13:30:02 to 06-02-2021 13:30:05.

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 1564026 1564026 0 0.00 0 19400 7 0 0
doc_list_biud 1564023 1564023 0 0.00 0 43257 33 0 0
sp_add_invoice_to_stock 5275 5172 103 1.95 28 194646 19982 0 103
sp_cancel_adding_invoice 5621 5621 0 0.00 4 82510 1023 0 0
sp_cancel_client_order 1798 1798 0 0.00 4 13078 380 0 0
sp_cancel_customer_reserve 556807 556807 0 0.00 1 43261 73 0 0
sp_cancel_pay_from_customer 689 689 0 0.00 3 20901 138 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 3 10849 135 0 0
sp_cancel_supplier_invoice 6276 6262 14 0.22 2 28000 967 0 14
sp_cancel_supplier_order 404 404 0 0.00 12 1376905 126143 0 0
sp_cancel_write_off 8902 8902 0 0.00 1 14571 137 0 0
sp_client_order 20336 20336 0 0.00 4 42849 406 0 0
sp_customer_reserve 480502 480502 0 0.00 0 97702 193 0 0
sp_fill_shopping_cart 31415 31415 0 0.00 1 28180 43 0 0
sp_fill_shopping_cart_clo_res 480494 480494 0 0.00 0 26302 16 0 0
sp_get_clo_for_invoice 5164 5164 0 0.00 0 37401 400 0 0
sp_kill_cost_storno 15611 15611 0 0.00 0 7115 63 0 0
sp_kill_qstorno_ret_qs2qd 1129182 1129182 0 0.00 0 43247 39 0 0
sp_kill_qty_storno 594733 594733 0 0.00 0 43257 85 0 0
sp_lock_selected_doc 20743 20743 0 0.00 0 10618 7 0 0
sp_make_cost_storno 27995 27995 0 0.00 0 17566 63 0 0
sp_multiply_rows_for_pdistr 27995 27995 0 0.00 0 11345 34 0 0
sp_multiply_rows_for_qdistr 20336 20336 0 0.00 0 42839 222 0 0
sp_pay_from_customer 7202 7202 0 0.00 4 18757 129 0 0
sp_pay_to_supplier 5854 5854 0 0.00 5 15741 134 0 0
sp_payment_common 13055 13055 0 0.00 1 12438 22 0 0
sp_qd_handle_on_cancel_clo 1798 1798 0 0.00 0 13066 341 0 0
sp_qd_handle_on_invoice_upd_sts 10785 10785 0 0.00 0 37002 435 0 0
sp_qd_handle_on_reserve_upd_sts 9775 9775 0 0.00 0 8986 51 0 0
sp_reserve_write_off 9775 9775 0 0.00 6 27556 183 0 0
sp_supplier_invoice 5595 5595 0 0.00 8 136690 3500 0 0
sp_supplier_order 5498 5498 0 0.00 6 101272 1424 0 0
srv_aggregate_perf_data 113 113 0 0.00 3383 168215 14906 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1233 209635 40488 0 0
srv_find_qd_qs_mism 1072315 1072315 0 0.00 0 19399 6 0 0
srv_make_invnt_saldo 429 429 0 0.00 55 99898 4076 0 0
srv_make_money_saldo 843 843 0 0.00 0 18184 611 0 0
srv_recalc_idx_stat 5 5 0 0.00 177411 510845 368687 0 0
t$perf-abend:sp_add_invoice_to_stock 103 103 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5172 5172 0 0.00 0 33339 208 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 0 7211 141 0 0
t$perf-norm:sp_cancel_client_order 1798 1798 0 0.00 0 8191 20 0 0
t$perf-norm:sp_cancel_customer_reserve 697 697 0 0.00 0 2711 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 689 689 0 0.00 0 926 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 751 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 377 377 0 0.00 0 895 20 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 29398 1726 0 0
t$perf-norm:sp_cancel_write_off 1039 1039 0 0.00 0 4485 15 0 0
t$perf-norm:sp_client_order 20336 20336 0 0.00 0 6870 17 0 0
t$perf-norm:sp_customer_reserve 1046 1046 0 0.00 0 1192 8 0 0
t$perf-norm:sp_pay_from_customer 7202 7202 0 0.00 0 5412 7 0 0
t$perf-norm:sp_pay_to_supplier 5854 5854 0 0.00 0 5388 13 0 0
t$perf-norm:sp_reserve_write_off 9775 9775 0 0.00 0 6969 9 0 0
t$perf-norm:sp_supplier_invoice 5595 5595 0 0.00 0 8378 25 0 0
t$perf-norm:sp_supplier_order 5498 5498 0 0.00 0 3445 17 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 363 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 498 15 0 0
t$perf-norm:srv_make_invnt_saldo 429 429 0 0.00 0 369 5 0 0
t$perf-norm:srv_make_money_saldo 843 843 0 0.00 0 1203 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 11 5 0 0
v_add_invoice_to_stock 10530 10324 206 1.95 0 21750 23 0 206
v_all_customers 37934 37934 0 0.00 0 54 1 0 0
v_all_suppliers 30508 30508 0 0.00 0 27 1 0 0
v_all_wares 40672 40672 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 4318 12 0 0
v_cancel_client_order 3596 3596 0 0.00 0 5709 6 0 0
v_cancel_customer_prepayment 1378 1378 0 0.00 0 20881 22 0 0
v_cancel_customer_reserve 1394 1394 0 0.00 0 702 4 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 3360 15 0 0
v_cancel_supplier_invoice 782 754 28 3.58 0 1406 16 0 28
v_cancel_supplier_order 808 808 0 0.00 0 3400 11 0 0
v_cancel_write_off 2078 2078 0 0.00 0 1743 16 0 0
v_max_id_clo_ord 5490 5490 0 0.00 0 478 2 0 0
v_max_id_clo_res 1046 1046 0 0.00 0 1335 11 0 0
v_max_id_ord_sup 5588 5588 0 0.00 0 3974 4 0 0
v_max_non_paid_invoice 1677 1677 0 0.00 0 246 2 0 0
v_max_non_paid_realizn 3646 3646 0 0.00 0 7256 5 0 0
v_min_id_clo_ord 5490 5490 0 0.00 0 2626 6 0 0
v_min_id_clo_res 1046 1046 0 0.00 0 4334 8 0 0
v_min_id_ord_sup 5588 5588 0 0.00 0 6063 7 0 0
v_min_non_paid_invoice 5853 5853 0 0.00 0 7842 11 0 0
v_min_non_paid_realizn 7202 7202 0 0.00 0 12282 16 0 0
v_our_firm 9850 9850 0 0.00 0 14 1 0 0
v_reserve_write_off 19550 19550 0 0.00 0 27491 7 0 0
x_lock_depdocs_on_canc_invoice 11506 11506 0 0.00 0 27042 140 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 19285 452 0 0
x_make_qstorno_1000_1200 5498 5498 0 0.00 2 101260 1333 0 0
x_make_qstorno_1200_2000 5595 5595 0 0.00 4 131474 3349 0 0
x_make_qstorno_2100_3300 480502 480502 0 0.00 0 97494 174 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-02-2021 13:30:06 to 06-02-2021 13:30:10.

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:02 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:58:02 20618 8192000000 9698818080 9992200192 784893840 8.743 100 85 170 0 813384832 7834768 20656224
10:59:30 24075 8192000000 9750529408 10053160960 822302048 9.122 100 89 176 1 855316800 11837056 21177696
11:01:03 15638 8192000000 9787217216 10100617216 852901568 9.429 100 81 162 1 895331600 23721888 18708144
11:02:28 9776 8192000000 9853769168 10181439488 901112848 9.909 100 79 158 2 962289072 43778944 17397280
11:03:40 9116 8192000000 9901822608 10246844416 951117120 10.402 100 81 162 0 1023205360 54815424 17272816
11:04:51 4149 8192000000 9949650992 10302787584 997001008 10.849 100 85 169 1 1071226960 55921904 18304048
11:05:57 1650 8192000000 9996578784 10358001664 1026101520 11.131 100 84 168 1 1115405376 71508368 17795488
11:07:01 1978 8192000000 10010002640 10379046912 1045940336 11.322 100 88 176 1 1127045392 63258272 17846784
11:08:03 1755 8192000000 10041722160 10415742976 1065857936 11.513 100 88 176 0 1155282448 70502000 18922512
11:09:06 4178 8192000000 9993139584 10375933952 1070016944 11.552 100 67 135 2 1104258896 20207248 14034704
11:10:10 5006 8192000000 9991772912 10392023040 1075488304 11.604 100 39 78 0 1102052000 18373760 8189936
11:11:19 5546 8192000000 9992268192 10388623360 1088332544 11.727 100 48 94 2 1113618928 15830976 9455408
11:12:28 9364 8192000000 9997929632 10399576064 1088164720 11.725 100 19 35 2 1127889344 36146560 3578064
11:13:43 66789 8192000000 9995654768 10409951232 1093090864 11.772 100 31 62 2 1108749472 9257136 6401472
11:15:51 8525 8192000000 10026414768 10429767680 1094857200 11.789 100 41 81 1 1137731280 34703440 8170640
11:17:02 7981 8192000000 9975870480 10369994752 1045900016 11.321 96 36 73 4 1066577264 11969040 8708208
11:18:10 11763 8192000000 9955115968 10357399552 1008714192 10.963 95 20 40 0 1035111520 21972720 4424608
11:19:23 41092 8192000000 9942372720 10338746368 994248576 10.823 95 25 47 2 1010549376 11593776 4707024
11:21:08 7811 8192000000 9943201680 10335633408 1016552624 11.039 98 32 62 2 1050232400 26555280 7124496
11:22:19 12210 8192000000 9944144112 10334015488 1028737296 11.156 100 35 70 1 1047636240 10860176 8038768
11:23:32 5101 8192000000 9965992800 10351702016 1041183904 11.276 99 44 90 0 1064106816 13037888 9885024
11:24:40 34004 8192000000 9981135744 10384805888 1053473088 11.394 100 35 69 2 1098798944 37937328 7388528
11:26:15 5528 8192000000 9980462128 10372952064 1063895568 11.494 100 50 97 0 1106802096 32025008 10881520
11:27:32 16827 8192000000 10012085936 10403721216 1080978976 11.657 100 53 107 1 1111180400 18112592 12088832
11:29:02 33688 8192000000 10048181040 10451283968 1099159344 11.830 100 43 85 1 1164792784 56397952 9235488
11:31:03 51952 8192000000 10106598528 10520965120 1118076064 12.009 100 38 76 0 1215391184 89506208 7808912
11:33:07 12520 8192000000 10127296096 10537451520 1133555664 12.155 100 43 83 3 1235245664 92995744 8694256
11:34:29 5651 8192000000 10178661872 10595987456 1142722368 12.241 100 55 110 0 1277950112 122998144 12229600
11:35:39 3351 8192000000 10208780192 10609381376 1149684336 12.307 100 85 169 0 1311215248 143261760 18269152
11:36:47 8708 8192000000 10202859952 10612293632 1163368304 12.435 100 60 120 1 1308255360 131862944 13024112
11:37:59 6421 8192000000 10258399648 10660634624 1154273632 12.350 99 74 149 0 1348029232 177858848 15896752
11:39:15 10834 8192000000 10256392608 10682007552 1145789344 12.270 99 63 124 1 1347993152 188765424 13438384
11:40:33 3018 8192000000 10261102080 10675023872 1147126528 12.283 99 84 167 1 1355195008 189405408 18663072
11:41:41 4579 8192000000 10238662992 10640306176 1139102112 12.207 100 74 146 1 1345200432 189996544 16101776
11:42:50 1230 8192000000 10248639584 10649272320 1158962976 12.394 100 97 191 1 1359906304 180071616 20871712
11:43:55 2215 8192000000 10289390768 10687168512 1152963696 12.337 100 86 170 2 1384816352 213265696 18586960
11:45:02 1986 8192000000 10307209920 10705702912 1157503344 12.380 100 85 170 1 1399185968 222269344 19413280
11:46:09 3208 8192000000 10322531008 10752528384 1181722480 12.606 100 84 168 0 1427105680 225970016 19413184
11:47:19 1542 8192000000 10380889280 10778767360 1174198832 12.536 100 93 185 0 1478551312 283362752 20989728
11:48:26 7002 8192000000 10418064944 10848530432 1169628336 12.493 99 62 125 0 1522137168 336873232 15635600
11:49:41 9055 8192000000 10403914192 10823647232 1168025056 12.478 100 54 106 0 1505825232 325135904 12664272
11:50:53 14171 8192000000 10458929024 10880315392 1163735216 12.438 99 43 86 0 1545588032 370441280 11411536
11:52:15 4245 8192000000 10548260128 10990166016 1171944688 12.515 100 58 114 0 1649600272 462415024 15240560
11:53:43 11009 8192000000 10568223168 11025518592 1170807936 12.504 100 40 80 3 1665799408 483798624 11192848
11:54:57 19448 8192000000 10402072784 10842660864 1139309568 12.209 99 35 70 1 1492703312 343893392 9500352
11:56:38 27999 8192000000 10200525200 10755448832 1119018896 12.018 99 17 34 1 1339686032 216170160 4496976
11:58:11 73686 8192000000 10073252208 10534596608 1132825728 12.148 99 30 61 3 1194507536 55083696 6598112
12:00:38 46147 8192000000 10093065712 10543075328 1133092288 12.151 99 34 68 1 1184109104 43725600 7291216
12:02:27 36773 8192000000 10111920288 10568998912 1152047216 12.329 100 23 46 0 1202677184 45531312 5098656
12:06:29 20508 8192000000 10094758480 10569777152 1144716800 12.260 99 19 38 0 1211162096 62316592 4128704
12:07:50 42496 8192000000 10085585344 10533298176 1133098672 12.151 98 26 52 1 1189042624 50986688 4957264
12:09:37 65020 8192000000 10133203568 10589073408 1160703472 12.410 100 14 28 0 1216159120 52813344 2642304
12:11:44 66824 8192000000 10213767920 10707935232 1163996000 12.441 99 21 42 1 1296119088 127709472 4413616
12:13:52 37004 8192000000 10157496304 10622222336 1162900992 12.430 100 13 26 0 1241524064 75836432 2786640
12:15:31 63335 8192000000 10140937456 10592681984 1120285408 12.030 96 24 46 1 1190797568 65404112 5108048
12:17:35 43094 8192000000 10087763360 10532515840 1136959344 12.187 98 30 60 0 1171175424 27560624 6655456
12:19:19 39480 8192000000 10145089232 10601439232 1148862448 12.299 99 41 79 1 1217995344 61351760 7781136
12:21:02 74852 8192000000 10133874944 10599170048 1117414160 12.003 97 17 34 2 1208466400 87107968 3944272
12:23:30 65037 8192000000 10063312896 10526425088 1105865680 11.893 97 20 39 1 1154454384 44747632 3841072
12:25:46 102451 8192000000 10064013056 10536046592 1120735824 12.034 100 18 36 0 1169953392 45434944 3782624
12:29:29 33545 8192000000 10046767968 10510155776 1130247920 12.124 100 9 18 1 1152247056 20030256 1968880
12:31:03 70355 8192000000 10086534864 10546778112 1138647920 12.203 100 19 38 0 1190986640 48254288 4084432
12:33:31 70892 8192000000 10104593344 10608545792 1121934848 12.045 98 16 31 1 1220107424 94625584 3546992
12:35:45 40398 8192000000 10067405200 10511781888 1109454352 11.927 98 18 36 0 1141391584 27925568 4011664
12:37:26 23096 8192000000 10090054704 10529001472 1103706832 11.873 98 37 74 0 1152892768 41767440 7418496
12:39:02 41658 8192000000 10121062752 10575773696 1121306560 12.039 99 24 48 0 1199381616 73032464 5042592
12:40:44 44685 8192000000 10111232672 10566549504 1073301056 11.584 96 20 40 2 1169890432 92310624 4278752
12:42:33 12051 8192000000 10058558304 10472992768 1092667984 11.768 99 56 112 0 1133955072 29299072 11988016
12:43:47 30671 8192000000 10063038976 10491445248 1078974160 11.638 98 28 56 2 1154568112 70215248 5378704
12:45:19 28358 8192000000 10038831648 10480836608 1086302784 11.707 98 27 54 0 1136034464 43987632 5744048
12:46:53 105751 8192000000 10093518032 10555666432 1116806240 11.997 100 33 66 1 1185702576 62261920 6634416
12:50:08 39451 8192000000 10025683184 10515419136 1095943168 11.799 98 5 10 0 1123833008 27100000 789840
12:51:47 44012 8192000000 10060455760 10523484160 1122783648 12.053 100 16 32 2 1155461376 29602400 3075328
12:53:31 62628 8192000000 10090433808 10550702080 1102811616 11.864 98 17 34 2 1173542432 66878368 3852448
12:55:37 57044 8192000000 10129806496 10585997312 1124363344 12.068 99 25 48 4 1193937984 64223936 5350704
12:57:46 60398 8192000000 10148280816 10590236672 1146996864 12.281 100 31 61 0 1236918096 83630560 6290672
12:59:55 63613 8192000000 10179131744 10648432640 1137057184 12.188 99 11 22 1 1262032976 122297600 2678192
13:02:00 28481 8192000000 10074627584 10521878528 1140502096 12.220 100 27 53 1 1159847344 14233824 5111424
13:03:41 209635 8192000000 10095010320 10558267392 1107443904 11.908 96 23 46 0 1173178800 60271104 5463792
13:08:12 32808 8192000000 10083445440 10600386560 1140131776 12.217 100 5 10 0 1181927792 41096352 699664
13:09:58 78945 8192000000 10059867616 10510077952 1079944304 11.647 94 8 16 0 1099309056 17701760 1662992
13:12:17 15311 8192000000 10097851680 10538291200 1138845760 12.205 100 45 90 1 1196744720 48836976 9061984
13:13:38 89502 8192000000 10108728416 10714783744 1150216656 12.312 100 17 34 1 1259011008 104736800 4057552
13:16:22 81042 8192000000 10057995632 10535108608 1081944720 11.666 95 11 22 1 1145049360 60599328 2505312
13:18:52 53237 8192000000 10183748480 10660155392 1132275120 12.143 99 47 94 0 1232976800 91154896 9546784
13:20:57 23640 8192000000 10077506896 10508316672 1113982896 11.970 98 19 39 0 1157677136 40078592 3615648
13:22:36 50543 8192000000 10072497232 10542346240 1064042640 11.495 95 17 33 0 1159780800 91867984 3870176
13:24:27 102526 8192000000 10037546752 10508652544 1090314000 11.746 98 15 30 0 1130108192 36116160 3678032

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-02-2021 13:30:12 to 06-02-2021 13:30:27.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 103 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-02-2021 13:30:28 to 06-02-2021 13:30:30.

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          238663
MON$OLDEST_ACTIVE               239599
MON$OLDEST_SNAPSHOT             239599
MON$NEXT_TRANSACTION            239599
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                       2122496
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             808
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2365 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.2365 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 ---
:24: 
:25: mscs-test-161.budzdorov.ru    Sat Feb  6 13:27:03 2021
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:27: 
:28: 
:29: mscs-test-161.budzdorov.ru    Sat Feb  6 13:27:37 2021
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:31: 
--- end of diff output ---

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

06.02.21 13:30:31. 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-02-06 13:30:33 No severe PSQL-related problems occured
06.02.21 13:30:35. End of report.