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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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.33
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 16697044 kB
MemAvailable 31588040 kB
Buffers 0 kB
Cached 15021216 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.1
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-11-27 07:37:07
DTS_END                         2021-11-27 07:37:07.7160
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/45298
TRN_ID                          230226
ATT_ID                          586
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: 8980.39 20297 1077647
customer order: creation 164.96 367 19795
customer order: refuse 13.98 316 1677
order to supplier: creation 44.01 1380 5281
order to supplier: removal 3.06 119734 367
invoice (draft): creation 44.61 3704 5353
invoice (draft): removal 50.84 893 6101
invoice accept: apply 40.88 18707 4905
invoice accept: cancel 46.06 803 5527
customer reserve: creation 3765.79 184 451895
customer reserve: removal 4513.70 64 541644
realization accept: apply 81.74 177 9809
realization accept: cancel 84.97 116 10196
payment from customer: creation 57.43 102 6892
payment from customer: removal 5.93 69 711
payment to supplier: creation 47.92 118 5750
payment to supplier: removal 3.51 94 421
service: total inventory turnovers 4.08 3105 490
service: total monetary turnovers 5.99 606 719
service: aggregate perf. data 0.91 14841 109
service: refresh index statistics 0.04 240562 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-11-2021 07:38:49 to 27-11-2021 07:38:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4777
TEST_TIME 2 4696
TEST_TIME 3 4439
TEST_TIME 4 5308
TEST_TIME 5 5826
TEST_TIME 6 5529
TEST_TIME 7 5414
TEST_TIME 8 5440
TEST_TIME 9 5871
TEST_TIME 10 5992
TEST_TIME 11 6038
TEST_TIME 12 6023
TEST_TIME 13 6312
TEST_TIME 14 6303
TEST_TIME 15 6455
TEST_TIME 16 7201
TEST_TIME 17 7488
TEST_TIME 18 7693
TEST_TIME 19 7801
TEST_TIME 20 8145
TEST_TIME 21 8762
TEST_TIME 22 9270
TEST_TIME 23 9394
TEST_TIME 24 9621
TEST_TIME 25 9877
TEST_TIME 26 10237
TEST_TIME 27 10715
TEST_TIME 28 11041
TEST_TIME 29 10673
TEST_TIME 30 10407
TEST_TIME 31 10255
TEST_TIME 32 10051
TEST_TIME 33 9993
TEST_TIME 34 9958
TEST_TIME 35 9730
TEST_TIME 36 9924
TEST_TIME 37 9737
TEST_TIME 38 9654
TEST_TIME 39 9576
TEST_TIME 40 9615
TEST_TIME 41 9656
TEST_TIME 42 9699
TEST_TIME 43 9697
TEST_TIME 44 9612
TEST_TIME 45 9613
TEST_TIME 46 9562
TEST_TIME 47 9570
TEST_TIME 48 9601
TEST_TIME 49 9627
TEST_TIME 50 9585
TEST_TIME 51 9803
TEST_TIME 52 9827
TEST_TIME 53 9761
TEST_TIME 54 9685
TEST_TIME 55 9530
TEST_TIME 56 9531
TEST_TIME 57 9488
TEST_TIME 58 9478
TEST_TIME 59 9411
TEST_TIME 60 9359
TEST_TIME 61 9263
TEST_TIME 62 9263
TEST_TIME 63 9230
TEST_TIME 64 9161
TEST_TIME 65 9138
TEST_TIME 66 9150
TEST_TIME 67 9198
TEST_TIME 68 9322
TEST_TIME 69 9457
TEST_TIME 70 9435
TEST_TIME 71 9405
TEST_TIME 72 9462
TEST_TIME 73 9470
TEST_TIME 74 9456
TEST_TIME 75 9526
TEST_TIME 76 9427
TEST_TIME 77 9423
TEST_TIME 78 9373
TEST_TIME 79 9337
TEST_TIME 80 9267
TEST_TIME 81 9291
TEST_TIME 82 9253
TEST_TIME 83 9178
TEST_TIME 84 9123
TEST_TIME 85 9130
TEST_TIME 86 9115
TEST_TIME 87 9158
TEST_TIME 88 9159
TEST_TIME 89 9139
TEST_TIME 90 9138
TEST_TIME 91 9140
TEST_TIME 92 9148
TEST_TIME 93 9222
TEST_TIME 94 9216
TEST_TIME 95 9229
TEST_TIME 96 9222
TEST_TIME 97 9260
TEST_TIME 98 9290
TEST_TIME 99 9219
TEST_TIME 100 9187
TEST_TIME 101 9178
TEST_TIME 102 9124
TEST_TIME 103 9093
TEST_TIME 104 9055
TEST_TIME 105 9059
TEST_TIME 106 9062
TEST_TIME 107 9025
TEST_TIME 108 8997
TEST_TIME 109 9037
TEST_TIME 110 9027
TEST_TIME 111 9054
TEST_TIME 112 9039
TEST_TIME 113 9056
TEST_TIME 114 9152
TEST_TIME 115 9129
TEST_TIME 116 9130
TEST_TIME 117 9121
TEST_TIME 118 9054
TEST_TIME 119 9055
TEST_TIME 120 8983

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-11-2021 07:38:52 to 27-11-2021 07:38:55.

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 1496277 1496277 0 0.00 0 24555 7 0 0
doc_list_biud 1496277 1496277 0 0.00 0 59084 30 0 0
sp_add_invoice_to_stock 5026 4905 121 2.40 27 215680 18707 0 121
sp_cancel_adding_invoice 5527 5527 0 0.00 5 62645 803 0 0
sp_cancel_client_order 1677 1677 0 0.00 5 18785 316 0 0
sp_cancel_customer_reserve 541644 541644 0 0.00 1 59088 65 0 0
sp_cancel_pay_from_customer 711 711 0 0.00 3 6342 69 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 3 6893 95 0 0
sp_cancel_supplier_invoice 6114 6101 13 0.21 2 19266 893 0 13
sp_cancel_supplier_order 367 367 0 0.00 5 1062002 119735 0 0
sp_cancel_write_off 10196 10196 0 0.00 1 23312 116 0 0
sp_client_order 19795 19795 0 0.00 4 22140 368 0 0
sp_customer_reserve 451895 451895 0 0.00 0 48551 185 0 0
sp_fill_shopping_cart 30425 30425 0 0.00 1 23416 54 0 0
sp_fill_shopping_cart_clo_res 451894 451894 0 0.00 0 12550 14 0 0
sp_get_clo_for_invoice 4901 4901 0 0.00 0 22730 401 0 0
sp_kill_cost_storno 16855 16855 0 0.00 0 8642 57 0 0
sp_kill_qstorno_ret_qs2qd 1099952 1099952 0 0.00 0 59066 35 0 0
sp_kill_qty_storno 580222 580222 0 0.00 0 59083 75 0 0
sp_lock_selected_doc 20303 20303 0 0.00 0 8602 6 0 0
sp_make_cost_storno 27352 27352 0 0.00 0 14682 54 0 0
sp_multiply_rows_for_pdistr 27352 27352 0 0.00 0 9714 31 0 0
sp_multiply_rows_for_qdistr 19795 19795 0 0.00 0 16484 190 0 0
sp_pay_from_customer 6892 6892 0 0.00 4 9777 103 0 0
sp_pay_to_supplier 5750 5750 0 0.00 4 14633 118 0 0
sp_payment_common 12642 12642 0 0.00 1 14590 27 0 0
sp_qd_handle_on_cancel_clo 1677 1677 0 0.00 0 18779 291 0 0
sp_qd_handle_on_invoice_upd_sts 10428 10428 0 0.00 0 35122 352 0 0
sp_qd_handle_on_reserve_upd_sts 9809 9809 0 0.00 0 15508 54 0 0
sp_reserve_write_off 9809 9809 0 0.00 7 15767 178 0 0
sp_supplier_invoice 5353 5353 0 0.00 6 97190 3705 0 0
sp_supplier_order 5281 5281 0 0.00 5 47975 1380 0 0
srv_aggregate_perf_data 109 109 0 0.00 2964 246102 14841 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1592 198861 49140 0 0
srv_find_qd_qs_mism 1031365 1031365 0 0.00 0 24553 6 0 0
srv_make_invnt_saldo 490 490 0 0.00 55 112420 3105 0 0
srv_make_money_saldo 719 719 0 0.00 1 13779 606 0 0
srv_recalc_idx_stat 5 5 0 0.00 80755 469426 240562 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4905 4905 0 0.00 0 12747 162 0 0
t$perf-norm:sp_cancel_adding_invoice 371 371 0 0.00 0 6029 134 0 0
t$perf-norm:sp_cancel_client_order 1677 1677 0 0.00 0 5208 19 0 0
t$perf-norm:sp_cancel_customer_reserve 669 669 0 0.00 0 1528 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 711 711 0 0.00 0 3932 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 116 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 355 355 0 0.00 0 2281 22 0 0
t$perf-norm:sp_cancel_supplier_order 367 367 0 0.00 0 20629 1401 0 0
t$perf-norm:sp_cancel_write_off 1025 1025 0 0.00 0 2527 11 0 0
t$perf-norm:sp_client_order 19795 19795 0 0.00 0 18793 17 0 0
t$perf-norm:sp_customer_reserve 950 950 0 0.00 0 4718 10 0 0
t$perf-norm:sp_pay_from_customer 6892 6892 0 0.00 0 4588 7 0 0
t$perf-norm:sp_pay_to_supplier 5750 5750 0 0.00 0 3384 8 0 0
t$perf-norm:sp_reserve_write_off 9809 9809 0 0.00 0 7673 13 0 0
t$perf-norm:sp_supplier_invoice 5353 5353 0 0.00 0 5031 16 0 0
t$perf-norm:sp_supplier_order 5281 5281 0 0.00 0 3324 15 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 247 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 29 1 0 0
t$perf-norm:srv_make_invnt_saldo 490 490 0 0.00 0 685 4 0 0
t$perf-norm:srv_make_money_saldo 719 719 0 0.00 0 5000 18 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 17 7 0 0
v_add_invoice_to_stock 10038 9796 242 2.41 0 5050 15 0 242
v_all_customers 36346 36346 0 0.00 0 20 1 0 0
v_all_suppliers 29414 29414 0 0.00 0 16 1 0 0
v_all_wares 39588 39588 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 742 742 0 0.00 0 1844 8 0 0
v_cancel_client_order 3354 3354 0 0.00 0 2441 5 0 0
v_cancel_customer_prepayment 1422 1422 0 0.00 0 503 3 0 0
v_cancel_customer_reserve 1338 1338 0 0.00 0 1271 5 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 1921 6 0 0
v_cancel_supplier_invoice 736 710 26 3.53 0 3631 24 0 26
v_cancel_supplier_order 734 734 0 0.00 0 533 4 0 0
v_cancel_write_off 2050 2050 0 0.00 0 4116 18 0 0
v_max_id_clo_ord 5280 5280 0 0.00 0 309 1 0 0
v_max_id_clo_res 950 950 0 0.00 0 1649 12 0 0
v_max_id_ord_sup 5351 5351 0 0.00 0 5672 8 0 0
v_max_non_paid_invoice 1674 1674 0 0.00 0 300 2 0 0
v_max_non_paid_realizn 3635 3635 0 0.00 0 5738 6 0 0
v_min_id_clo_ord 5280 5280 0 0.00 0 5789 11 0 0
v_min_id_clo_res 950 950 0 0.00 0 5667 26 0 0
v_min_id_ord_sup 5351 5351 0 0.00 0 8608 16 0 0
v_min_non_paid_invoice 5750 5750 0 0.00 0 14587 19 0 0
v_min_non_paid_realizn 6892 6892 0 0.00 0 7382 19 0 0
v_our_firm 9756 9756 0 0.00 0 15 1 0 0
v_reserve_write_off 19618 19618 0 0.00 0 5924 5 0 0
x_lock_depdocs_on_canc_invoice 11273 11273 0 0.00 0 8056 104 0 0
x_lock_depdocs_on_canc_sup_ord 367 367 0 0.00 0 19479 520 0 0
x_make_qstorno_1000_1200 5281 5281 0 0.00 2 47927 1296 0 0
x_make_qstorno_1200_2000 5353 5353 0 0.00 3 97180 3485 0 0
x_make_qstorno_2100_3300 451895 451895 0 0.00 0 48548 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-11-2021 07:38:56 to 27-11-2021 07:39:00.

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
05:07:11 1246 8192000000 8969868416 9038032896 17578144 0.214 4 2 4 0 18039040 138608 322288
05:08:14 34486 8192000000 9728599328 10027651072 791597008 8.811 100 85 170 1 821130416 7895616 21637792
05:09:53 17446 8192000000 9762528864 10068115456 830922288 9.209 100 91 182 0 866629440 13564912 22142240
05:11:24 18308 8192000000 9801304192 10124115968 858451344 9.485 100 77 154 0 903203760 26773472 17978944
05:12:56 14215 8192000000 9869663440 10206879744 910192480 9.999 100 75 150 0 967677744 41571232 15914032
05:14:13 7447 8192000000 9935025216 10278510592 952873920 10.419 100 86 171 1 1038584432 67095920 18614592
05:15:22 5299 8192000000 9965035584 10320084992 988168192 10.764 100 82 162 1 1075019264 70724384 16126688
05:16:28 2746 8192000000 9993525184 10355384320 1007596448 10.952 100 83 162 0 1099693680 76542176 15555056
05:17:33 1632 8192000000 10033154976 10398760960 1032720256 11.195 100 94 190 1 1133187120 80897200 19569664
05:18:36 27062 8192000000 10024367696 10410606592 1050848864 11.369 100 65 128 3 1135659744 71278960 13531920
05:20:04 4378 8192000000 9980930192 10371321856 1060212432 11.459 100 35 70 4 1097369264 29150192 8006640
05:21:10 3610 8192000000 9994714608 10392260608 1073883568 11.589 100 49 99 0 1101321760 17208944 10229248
05:22:16 6442 8192000000 10015095360 10416656384 1075255968 11.602 100 45 90 1 1122921696 38041376 9624352
05:23:25 33657 8192000000 10004931024 10422194176 1080091616 11.648 100 33 64 1 1108632832 21488704 7052512
05:25:04 4292 8192000000 10007079504 10404425728 1087362576 11.718 100 43 85 0 1106986368 10131872 9491920
05:26:09 10973 8192000000 10001165024 10414678016 1087691072 11.721 100 23 45 1 1116531840 23722304 5118464
05:27:21 9475 8192000000 9991747616 10401562624 1057205664 11.430 98 23 46 2 1082317312 20508944 4602704
05:28:34 29521 8192000000 9965518032 10377162752 1018741024 11.060 95 23 46 1 1034709296 10914240 5054032
05:30:10 8275 8192000000 9924602688 10309451776 963558928 10.524 93 30 60 3 994687664 24686624 6442112
05:31:19 8285 8192000000 9932176752 10317688832 984133584 10.724 96 45 90 2 1012642032 17628832 10879616
05:32:32 63014 8192000000 9974329456 10378481664 1014783856 11.022 97 35 69 1 1051444272 29457888 7202528
05:34:36 5254 8192000000 9979912000 10357673984 1053550016 11.395 100 51 105 2 1083277136 18484848 11242272
05:35:42 9082 8192000000 9967156448 10368638976 1066244160 11.516 100 35 70 1 1097063408 23358640 7460608
05:36:53 11156 8192000000 9993607744 10406060032 1075321792 11.603 100 30 61 0 1098027280 16143632 6561856
05:38:08 105497 8192000000 10042090064 10445258752 1114999056 11.980 100 45 87 0 1157526064 33501776 9025232
05:40:58 7536 8192000000 10102624400 10502766592 1135580160 12.174 100 61 122 0 1219610832 70425824 13604848
05:42:09 15125 8192000000 10169396944 10578825216 1147936688 12.290 100 55 110 0 1275497360 115967232 11593440
05:43:26 7718 8192000000 10190211104 10602037248 1160226880 12.405 100 58 114 2 1301765360 128909088 12629392
05:44:40 3001 8192000000 10243493632 10653597696 1171850144 12.514 100 67 133 1 1354718288 168964592 13903552
05:46:09 10533 8192000000 10262601152 10696949760 1184378720 12.631 100 36 72 0 1377690080 184976416 8334944
05:47:23 22968 8192000000 10268823744 10698838016 1180939568 12.599 100 44 88 1 1387843936 195434608 11469760
05:48:52 10433 8192000000 10328140368 10737172480 1168934160 12.487 99 70 138 0 1425606992 241009456 15663376
05:50:06 6265 8192000000 10343849904 10763784192 1174831184 12.542 100 62 123 0 1449870768 260496208 14543376
05:51:20 3651 8192000000 10337921696 10754183168 1172047456 12.516 100 53 106 0 1437326544 252835536 12443552
05:52:31 4658 8192000000 10378017296 10795421696 1165220432 12.452 100 65 130 1 1485516144 305324416 14971296
05:53:42 1591 8192000000 10366553088 10768084992 1149745904 12.307 100 86 172 2 1468984976 299184944 20054128
05:54:49 7846 8192000000 10337303792 10753048576 1139318656 12.209 99 61 122 1 1433713648 280474048 13920944
05:56:02 9919 8192000000 10329384032 10750787584 1114810160 11.978 98 49 96 1 1428152000 301583904 11757936
05:57:16 8542 8192000000 10324596160 10766823424 1112166656 11.953 98 33 66 1 1422047520 301318336 8562528
05:58:30 7257 8192000000 10320768304 10751201280 1142716064 12.241 100 54 107 0 1435149072 279009120 13423888
05:59:40 10086 8192000000 10241787264 10698903552 1135705072 12.175 100 32 64 1 1358770688 215434368 7631248
06:00:57 14969 8192000000 10259343184 10711359488 1134722208 12.166 100 29 58 0 1361847744 219936976 7188560
06:02:20 30857 8192000000 10254598432 10713071616 1133449216 12.154 99 19 39 0 1346889376 208675568 4764592
06:03:51 32158 8192000000 10224755920 10756710400 1120738528 12.034 99 14 28 0 1306261168 181968144 3554496
06:05:24 93219 8192000000 10057853504 10474680320 1087633888 11.720 97 41 81 3 1120392144 23602192 9156064
06:08:00 72363 8192000000 10101488112 10538524672 1103626640 11.872 98 39 79 0 1175620208 63619024 8374544
06:10:19 50889 8192000000 10132440544 10604503040 1129153232 12.113 100 11 22 0 1225607264 94362448 2091584
06:12:13 71496 8192000000 10115516304 10639613952 1127454432 12.097 99 10 21 0 1238654560 108943968 2256160
06:14:29 37834 8192000000 10152846864 10597027840 1152413648 12.332 100 15 31 1 1232799264 76387696 3997920
06:16:28 46846 8192000000 10124023136 10583871488 1143880576 12.252 99 21 42 1 1246113424 97824528 4408320
06:18:45 87467 8192000000 10087746448 10578186240 1139942256 12.215 99 9 18 1 1218712816 77078432 1692128
06:21:13 42740 8192000000 10080997072 10518388736 1122542688 12.051 98 11 22 0 1164723008 40002672 2177648
06:22:56 56428 8192000000 10127513936 10584850432 1151988432 12.328 100 13 26 0 1214015168 59684240 2342496
06:25:06 110811 8192000000 10148758080 10643791872 1155172480 12.358 99 15 30 1 1224356640 65889360 3294800
06:27:57 198860 8192000000 10096488848 10612187136 1115630160 11.986 96 9 18 0 1167863984 50394784 1839040
06:32:57 9505 8192000000 10123411984 10554687488 1161383024 12.416 100 56 112 1 1210918016 38575600 10959392
06:34:07 46125 8192000000 10086493568 10545643520 1154310768 12.350 99 14 28 1 1201636272 44436480 2889024
06:35:54 63906 8192000000 10083536416 10553438208 1166118528 12.461 100 4 8 1 1198167360 31211792 837040
06:37:58 53513 8192000000 10115597840 10586992640 1166651472 12.466 99 14 28 1 1211596016 41826928 3117616
06:40:38 33768 8192000000 10122191920 10587492352 1156403712 12.370 99 12 24 0 1215806944 56863616 2539616
06:42:17 46637 8192000000 10201895696 10647015424 1161562528 12.418 99 23 46 2 1274334240 107721424 5050288
06:44:13 72613 8192000000 10161091072 10617843712 1079488848 11.643 94 19 38 1 1231710544 147790736 4430960
06:46:32 53112 8192000000 10042775536 10481655808 1059294288 11.450 94 16 32 0 1110702672 48175888 3232496
06:48:26 27885 8192000000 10100925200 10528624640 1113885344 11.969 100 62 123 1 1198670208 71971728 12813136
06:49:57 20146 8192000000 10091955472 10509787136 1096447584 11.804 99 44 88 0 1158905872 53693136 8765152
06:51:19 28083 8192000000 10099153200 10535972864 1098271568 11.821 98 35 70 0 1168695808 63607584 6816656
06:52:48 100920 8192000000 10051731584 10488320000 1078888944 11.637 97 24 48 0 1121703872 37662224 5152704
06:55:30 42476 8192000000 10034072848 10506653696 1087405680 11.718 98 25 49 3 1128120144 36012272 4702192
06:57:23 61165 8192000000 10063650464 10513571840 1102080224 11.857 99 10 20 0 1173930928 69577248 2273456
06:59:45 94004 8192000000 10082352240 10531872768 1111507440 11.947 99 20 40 1 1164464384 48310240 4646704
07:02:25 70203 8192000000 10049337168 10565373952 1065072416 11.505 96 6 11 2 1113252560 46986480 1193664
07:04:41 56655 8192000000 10033229024 10492129280 1072744352 11.578 97 15 30 0 1117401024 41277024 3379648
07:06:38 49285 8192000000 10083411232 10547228672 1107115248 11.905 99 16 31 1 1178572080 68114512 3342320
07:08:28 55727 8192000000 10111436352 10556891136 1083238064 11.678 96 34 68 0 1205792832 115026080 7528688
07:10:28 60238 8192000000 10080525248 10535694336 1111950192 11.951 99 30 60 0 1148374464 30788272 5636000
07:12:28 32779 8192000000 10114697648 10550300672 1126093856 12.085 99 54 108 1 1200970880 64133296 10743728
07:14:02 37469 8192000000 10084712640 10523250688 1117974512 12.008 98 36 72 1 1173450368 48478304 6997552
07:15:40 74042 8192000000 10116647280 10584961024 1138021744 12.197 100 23 46 0 1233639552 90369312 5248496
07:18:29 44237 8192000000 10062909280 10538008576 1133068704 12.150 99 10 20 0 1174562240 39111872 2381664
07:20:20 66013 8192000000 10065817904 10531897344 1126272272 12.086 99 13 25 2 1174449568 45443984 2733312
07:22:26 79518 8192000000 10082980944 10578481152 1139198080 12.208 99 10 19 0 1177908112 36868224 1841808
07:24:46 63313 8192000000 10138424288 10596794368 1153295792 12.340 100 22 44 0 1225185312 66793040 5096480
07:26:55 112602 8192000000 10106252912 10575806464 1140121680 12.217 98 21 43 0 1232462064 87665552 4674832
07:29:49 62067 8192000000 10149461648 10622308352 1126294208 12.086 97 36 72 2 1213639648 79961856 7383584
07:31:58 132014 8192000000 10058882032 10508484608 996601616 10.846 87 12 23 1 1026821872 28237936 1982320
07:35:16 107041 8192000000 10070432560 10536476672 1085977040 11.704 97 24 48 0 1129032336 38220672 4834624

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-11-2021 07:39:02 to 27-11-2021 07:39:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 121 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-11-2021 07:39:18 to 27-11-2021 07:39:20.

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          228672
MON$OLDEST_ACTIVE               230419
MON$OLDEST_SNAPSHOT             230419
MON$NEXT_TRANSACTION            230419
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                       2046496
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             789
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

Database statistics

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

Database validation

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

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

--- start of diff output ---
:62: 
:63: mscs-test-161.budzdorov.ru    Sat Nov 27 07:37:17 2021
:64:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:65: 
:66: 
:67: mscs-test-161.budzdorov.ru    Sat Nov 27 07:37:29 2021
:68:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:69: 
:70: 
:71: mscs-test-161.budzdorov.ru    Sat Nov 27 07:37:31 2021
:72:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:73: 
--- end of diff output ---

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

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