Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.08.2021 07:37
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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16180408 kB
MemAvailable 30994632 kB
Buffers 0 kB
Cached 15467584 kB
SwapCached 2580 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-08-11 07:37:04
DTS_END                         2021-08-11 07:37:04.9710
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/52054
TRN_ID                          237944
ATT_ID                          608
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: 9147.77 26992 1097732
customer order: creation 170.52 431 20462
customer order: refuse 14.72 299 1766
order to supplier: creation 44.40 1322 5328
order to supplier: removal 3.28 111263 394
invoice (draft): creation 44.85 3391 5382
invoice (draft): removal 51.06 836 6127
invoice accept: apply 41.26 19609 4951
invoice accept: cancel 46.54 875 5585
customer reserve: creation 3846.74 194 461609
customer reserve: removal 4590.25 64 550830
realization accept: apply 82.15 187 9858
realization accept: cancel 85.40 111 10248
payment from customer: creation 58.65 132 7038
payment from customer: removal 5.18 98 622
payment to supplier: creation 48.27 132 5792
payment to supplier: removal 3.68 97 442
service: total inventory turnovers 3.95 3384 474
service: total monetary turnovers 5.98 430 718
service: aggregate perf. data 0.85 17802 102
service: refresh index statistics 0.03 379188 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-08-2021 07:37:50 to 11-08-2021 07:37:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6302
TEST_TIME 2 3918
TEST_TIME 3 3531
TEST_TIME 4 3969
TEST_TIME 5 3981
TEST_TIME 6 4044
TEST_TIME 7 4106
TEST_TIME 8 4803
TEST_TIME 9 5168
TEST_TIME 10 5141
TEST_TIME 11 5495
TEST_TIME 12 5923
TEST_TIME 13 6641
TEST_TIME 14 7075
TEST_TIME 15 7452
TEST_TIME 16 7480
TEST_TIME 17 8163
TEST_TIME 18 7995
TEST_TIME 19 8319
TEST_TIME 20 8549
TEST_TIME 21 8882
TEST_TIME 22 8768
TEST_TIME 23 9523
TEST_TIME 24 9588
TEST_TIME 25 10167
TEST_TIME 26 10792
TEST_TIME 27 11254
TEST_TIME 28 11222
TEST_TIME 29 10909
TEST_TIME 30 10679
TEST_TIME 31 10400
TEST_TIME 32 10552
TEST_TIME 33 10453
TEST_TIME 34 10592
TEST_TIME 35 10474
TEST_TIME 36 10389
TEST_TIME 37 10316
TEST_TIME 38 10146
TEST_TIME 39 9989
TEST_TIME 40 9905
TEST_TIME 41 9802
TEST_TIME 42 9747
TEST_TIME 43 9561
TEST_TIME 44 9681
TEST_TIME 45 9744
TEST_TIME 46 9645
TEST_TIME 47 9592
TEST_TIME 48 9560
TEST_TIME 49 9571
TEST_TIME 50 9587
TEST_TIME 51 9651
TEST_TIME 52 9614
TEST_TIME 53 9669
TEST_TIME 54 9606
TEST_TIME 55 9681
TEST_TIME 56 9670
TEST_TIME 57 9633
TEST_TIME 58 9805
TEST_TIME 59 9755
TEST_TIME 60 9665
TEST_TIME 61 9584
TEST_TIME 62 9518
TEST_TIME 63 9466
TEST_TIME 64 9393
TEST_TIME 65 9338
TEST_TIME 66 9280
TEST_TIME 67 9372
TEST_TIME 68 9352
TEST_TIME 69 9391
TEST_TIME 70 9351
TEST_TIME 71 9347
TEST_TIME 72 9354
TEST_TIME 73 9332
TEST_TIME 74 9380
TEST_TIME 75 9437
TEST_TIME 76 9455
TEST_TIME 77 9519
TEST_TIME 78 9512
TEST_TIME 79 9427
TEST_TIME 80 9374
TEST_TIME 81 9305
TEST_TIME 82 9284
TEST_TIME 83 9287
TEST_TIME 84 9207
TEST_TIME 85 9173
TEST_TIME 86 9168
TEST_TIME 87 9176
TEST_TIME 88 9135
TEST_TIME 89 9119
TEST_TIME 90 9125
TEST_TIME 91 9102
TEST_TIME 92 9114
TEST_TIME 93 9284
TEST_TIME 94 9293
TEST_TIME 95 9285
TEST_TIME 96 9316
TEST_TIME 97 9329
TEST_TIME 98 9372
TEST_TIME 99 9452
TEST_TIME 100 9362
TEST_TIME 101 9331
TEST_TIME 102 9286
TEST_TIME 103 9278
TEST_TIME 104 9230
TEST_TIME 105 9189
TEST_TIME 106 9183
TEST_TIME 107 9139
TEST_TIME 108 9133
TEST_TIME 109 9098
TEST_TIME 110 9164
TEST_TIME 111 9139
TEST_TIME 112 9114
TEST_TIME 113 9113
TEST_TIME 114 9158
TEST_TIME 115 9158
TEST_TIME 116 9149
TEST_TIME 117 9211
TEST_TIME 118 9188
TEST_TIME 119 9205
TEST_TIME 120 9150

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-08-2021 07:37:54 to 11-08-2021 07:37:57.

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 1523248 1523248 0 0.00 0 25722 8 0 0
doc_list_biud 1523248 1523248 0 0.00 0 56115 29 0 0
sp_add_invoice_to_stock 5068 4951 117 2.30 46 188537 19609 0 117
sp_cancel_adding_invoice 5585 5585 0 0.00 5 63726 875 0 0
sp_cancel_client_order 1766 1766 0 0.00 4 9656 300 0 0
sp_cancel_customer_reserve 550830 550830 0 0.00 1 56120 65 0 0
sp_cancel_pay_from_customer 622 622 0 0.00 3 13520 99 0 0
sp_cancel_pay_to_supplier 442 442 0 0.00 3 8128 97 0 0
sp_cancel_supplier_invoice 6134 6127 7 0.11 2 43793 837 0 7
sp_cancel_supplier_order 394 394 0 0.00 10 1050702 111263 0 0
sp_cancel_write_off 10248 10248 0 0.00 1 13489 111 0 0
sp_client_order 20462 20462 0 0.00 4 39592 431 0 0
sp_customer_reserve 461609 461609 0 0.00 0 91201 195 0 0
sp_fill_shopping_cart 31158 31158 0 0.00 1 12664 41 0 0
sp_fill_shopping_cart_clo_res 461602 461602 0 0.00 0 17628 17 0 0
sp_get_clo_for_invoice 4944 4944 0 0.00 0 32570 360 0 0
sp_kill_cost_storno 16897 16897 0 0.00 0 12594 52 0 0
sp_kill_qstorno_ret_qs2qd 1118429 1118429 0 0.00 0 56074 35 0 0
sp_kill_qty_storno 589752 589752 0 0.00 0 56115 74 0 0
sp_lock_selected_doc 20478 20478 0 0.00 0 7049 3 0 0
sp_make_cost_storno 27632 27632 0 0.00 0 25709 63 0 0
sp_multiply_rows_for_pdistr 27632 27632 0 0.00 0 13004 36 0 0
sp_multiply_rows_for_qdistr 20462 20462 0 0.00 0 35760 229 0 0
sp_pay_from_customer 7038 7038 0 0.00 4 23679 133 0 0
sp_pay_to_supplier 5792 5792 0 0.00 4 23466 132 0 0
sp_payment_common 12830 12830 0 0.00 1 13229 35 0 0
sp_qd_handle_on_cancel_clo 1766 1766 0 0.00 0 9629 280 0 0
sp_qd_handle_on_invoice_upd_sts 10529 10529 0 0.00 0 22498 312 0 0
sp_qd_handle_on_reserve_upd_sts 9858 9858 0 0.00 0 8798 50 0 0
sp_reserve_write_off 9858 9858 0 0.00 7 22170 188 0 0
sp_supplier_invoice 5382 5382 0 0.00 7 104760 3392 0 0
sp_supplier_order 5328 5328 0 0.00 6 38458 1322 0 0
srv_aggregate_perf_data 102 102 0 0.00 2963 156727 17802 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1456 142459 47307 0 0
srv_find_qd_qs_mism 1049559 1049559 0 0.00 0 22424 7 0 0
srv_make_invnt_saldo 474 474 0 0.00 56 103481 3385 0 0
srv_make_money_saldo 718 718 0 0.00 1 15078 431 0 0
srv_recalc_idx_stat 4 4 0 0.00 249636 514345 379188 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4951 4951 0 0.00 0 17066 147 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 0 2995 88 0 0
t$perf-norm:sp_cancel_client_order 1766 1766 0 0.00 0 8156 15 0 0
t$perf-norm:sp_cancel_customer_reserve 704 704 0 0.00 0 15830 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 622 622 0 0.00 0 1253 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 442 442 0 0.00 0 6312 33 0 0
t$perf-norm:sp_cancel_supplier_invoice 382 382 0 0.00 0 1111 12 0 0
t$perf-norm:sp_cancel_supplier_order 394 394 0 0.00 0 19223 1618 0 0
t$perf-norm:sp_cancel_write_off 952 952 0 0.00 0 2000 7 0 0
t$perf-norm:sp_client_order 20462 20462 0 0.00 0 11553 17 0 0
t$perf-norm:sp_customer_reserve 971 971 0 0.00 0 3735 12 0 0
t$perf-norm:sp_pay_from_customer 7038 7038 0 0.00 0 7590 13 0 0
t$perf-norm:sp_pay_to_supplier 5792 5792 0 0.00 0 7043 12 0 0
t$perf-norm:sp_reserve_write_off 9858 9858 0 0.00 0 4702 10 0 0
t$perf-norm:sp_supplier_invoice 5382 5382 0 0.00 0 6875 21 0 0
t$perf-norm:sp_supplier_order 5328 5328 0 0.00 0 4543 14 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 314 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 681 15 0 0
t$perf-norm:srv_make_invnt_saldo 474 474 0 0.00 0 1504 9 0 0
t$perf-norm:srv_make_money_saldo 718 718 0 0.00 0 4000 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10122 9888 234 2.31 0 6297 19 0 234
v_all_customers 37562 37562 0 0.00 0 38 1 0 0
v_all_suppliers 29526 29526 0 0.00 0 41 1 0 0
v_all_wares 40924 40924 0 0.00 0 201 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 359 6 0 0
v_cancel_client_order 3532 3532 0 0.00 0 1753 4 0 0
v_cancel_customer_prepayment 1244 1244 0 0.00 0 4420 9 0 0
v_cancel_customer_reserve 1408 1408 0 0.00 0 975 6 0 0
v_cancel_payment_to_supplier 884 884 0 0.00 0 1250 9 0 0
v_cancel_supplier_invoice 778 764 14 1.79 0 1364 15 0 14
v_cancel_supplier_order 788 788 0 0.00 0 252 3 0 0
v_cancel_write_off 1904 1904 0 0.00 0 1922 14 0 0
v_max_id_clo_ord 5327 5327 0 0.00 0 4128 2 0 0
v_max_id_clo_res 971 971 0 0.00 0 2543 17 0 0
v_max_id_ord_sup 5368 5368 0 0.00 0 2930 3 0 0
v_max_non_paid_invoice 1724 1724 0 0.00 0 1454 2 0 0
v_max_non_paid_realizn 3587 3587 0 0.00 0 2043 5 0 0
v_min_id_clo_ord 5327 5327 0 0.00 0 6371 14 0 0
v_min_id_clo_res 971 971 0 0.00 0 3031 21 0 0
v_min_id_ord_sup 5368 5368 0 0.00 0 6517 8 0 0
v_min_non_paid_invoice 5792 5792 0 0.00 0 12978 22 0 0
v_min_non_paid_realizn 7038 7038 0 0.00 0 11185 31 0 0
v_our_firm 10264 10264 0 0.00 0 35 1 0 0
v_reserve_write_off 19716 19716 0 0.00 0 4631 5 0 0
x_lock_depdocs_on_canc_invoice 11330 11330 0 0.00 0 11562 114 0 0
x_lock_depdocs_on_canc_sup_ord 394 394 0 0.00 0 8793 355 0 0
x_make_qstorno_1000_1200 5328 5328 0 0.00 2 37064 1237 0 0
x_make_qstorno_1200_2000 5382 5382 0 0.00 4 104754 3246 0 0
x_make_qstorno_2100_3300 461609 461609 0 0.00 0 91200 176 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-08-2021 07:37:58 to 11-08-2021 07:38:02.

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:08 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:09 24445 8192000000 9725038048 10025865216 807851056 8.976 100 83 166 0 836204784 7476752 20876976
05:09:39 14952 8192000000 9763044336 10068299776 838653584 9.286 100 89 178 0 872193040 12266208 21273248
05:11:06 22161 8192000000 9815521792 10135085056 876060048 9.660 100 84 168 1 924539808 29251824 19227936
05:12:47 16001 8192000000 9888383216 10226769920 923808048 10.134 100 85 169 0 998073440 55796768 18468624
05:14:06 6208 8192000000 9927109488 10271256576 970212736 10.589 100 82 164 2 1045473424 57975264 17285424
05:15:15 4227 8192000000 9997730608 10352541696 1000216704 10.881 100 80 158 0 1102398368 86677760 15503904
05:16:22 2468 8192000000 9992219600 10352857088 1023181616 11.103 100 87 174 0 1104159120 64016544 16960960
05:17:26 2284 8192000000 10025202224 10393116672 1053982096 11.399 100 91 181 1 1143029712 70376208 18671408
05:18:30 8816 8192000000 10047010624 10430472192 1066869216 11.522 100 73 146 2 1160629744 79463984 14296544
05:19:41 22054 8192000000 9998040688 10391351296 1069611056 11.548 100 38 77 0 1113578848 35868672 8099120
05:21:06 3995 8192000000 10019324000 10400305152 1078848624 11.636 100 54 104 1 1120669152 31190560 10629968
05:22:11 6323 8192000000 9990754304 10383003648 1082905200 11.675 100 35 68 1 1119214768 28519600 7789968
05:23:21 15527 8192000000 9997300944 10404249600 1091353392 11.756 100 24 48 0 1113745056 17446128 4945536
05:24:36 22924 8192000000 10009055968 10409795584 1088923552 11.732 99 30 60 1 1122644624 27874848 5846224
05:26:00 5389 8192000000 10020977408 10415050752 1098720640 11.826 100 46 91 0 1127847776 19177568 9949568
05:27:07 9687 8192000000 10007658576 10416312320 1068326464 11.536 98 29 57 2 1104783648 29366016 7091168
05:28:19 63259 8192000000 9974041184 10364809216 1016209712 11.035 95 37 75 2 1043952352 19494256 8248384
05:30:22 4248 8192000000 9940615344 10324013056 1019042688 11.063 100 38 77 0 1053457600 26181232 8233680
05:31:28 5999 8192000000 9943833392 10330054656 1019346096 11.066 100 55 112 1 1055455584 23118016 12991472
05:32:36 7818 8192000000 9968962416 10360156160 1037395504 11.240 100 37 72 2 1069965568 24923520 7646544
05:33:47 26920 8192000000 9947534640 10347319296 1033098432 11.198 99 22 43 0 1054096848 16123440 4874976
05:35:15 6787 8192000000 9982040016 10371026944 1059084880 11.448 100 47 92 1 1087239440 17942064 10212496
05:36:24 9486 8192000000 9978499552 10386993152 1076343024 11.613 100 29 58 2 1100413616 16888016 7182576
05:37:36 142458 8192000000 10072815216 10484109312 1105783664 11.892 100 52 99 4 1150074384 32568416 11722304
05:41:03 20693 8192000000 10083611584 10501029888 1124385472 12.068 100 31 62 1 1198559824 67978288 6196064
05:42:31 29224 8192000000 10155629840 10574749696 1140260640 12.218 100 43 86 1 1262958448 113760128 8937680
05:44:05 5691 8192000000 10173446848 10585440256 1148706848 12.297 100 44 89 1 1290540784 131832208 10001728
05:45:15 18814 8192000000 10219089264 10645770240 1156305472 12.369 100 45 90 1 1329412720 163250496 9856752
05:46:36 4005 8192000000 10235531024 10648170496 1168130528 12.479 100 64 126 1 1342809984 160525184 14154272
05:47:47 8698 8192000000 10267110080 10683715584 1171492592 12.511 100 60 119 2 1374256416 189656944 13106880
05:48:59 1964 8192000000 10328930832 10737274880 1170437696 12.501 100 87 171 1 1431766624 242012096 19316832
05:50:05 5047 8192000000 10341882000 10756513792 1163985648 12.441 99 77 155 0 1435676720 254509328 17181744
05:51:19 2600 8192000000 10323785568 10751598592 1170480512 12.501 100 74 146 0 1427555312 240537424 16537376
05:52:27 13061 8192000000 10310876400 10744922112 1137120624 12.188 100 44 87 1 1429677520 281830496 10726400
05:53:51 1452 8192000000 10360295600 10773143552 1143926864 12.252 99 74 144 1 1454942160 294077136 16938160
05:54:57 12676 8192000000 10319044016 10756505600 1132614560 12.146 99 40 80 0 1417447440 275243936 9588944
05:56:12 12886 8192000000 10327370080 10744999936 1120407136 12.031 99 37 72 0 1412357088 282689104 9260848
05:57:31 10300 8192000000 10372363312 10801205248 1135427072 12.172 100 46 92 0 1460576608 314317152 10832384
05:58:47 18809 8192000000 10349077488 10781880320 1132805456 12.148 100 31 62 3 1444533872 303965200 7763216
06:00:15 15328 8192000000 10336004384 10786639872 1132374992 12.144 99 29 58 0 1439479248 299723312 7380944
06:01:32 11177 8192000000 10263667680 10703089664 1119706032 12.024 100 16 32 0 1364019024 240030864 4282128
06:02:54 26032 8192000000 10175149872 10648985600 1109581536 11.928 99 19 38 0 1329919984 214999424 5339024
06:06:06 42897 8192000000 10141063952 10573676544 1125234688 12.076 100 26 52 1 1212038080 81192992 5610400
06:07:50 104537 8192000000 10159765120 10626445312 1140101280 12.216 100 36 72 0 1280834640 132761088 7972272
06:10:35 24133 8192000000 10129440544 10587398144 1121776016 12.044 99 23 46 2 1232769232 105775056 5218160
06:12:35 36578 8192000000 10072937392 10516959232 1119133936 12.019 99 15 30 0 1167061456 45145888 2781632
06:14:18 141227 8192000000 10089354224 10538098688 1107207152 11.906 97 20 39 0 1140666144 29547072 3911920
06:18:00 38351 8192000000 10123162288 10562650112 1137769264 12.195 99 29 58 1 1224363328 80616704 5977360
06:19:51 77456 8192000000 10140563792 10631540736 1151220944 12.321 100 20 40 0 1272049824 116105488 4723392
06:22:11 65411 8192000000 10052608224 10519597056 1124977296 12.074 98 26 52 0 1165492112 34743424 5771392
06:24:16 11818 8192000000 10079864272 10522402816 1152753248 12.335 100 31 62 0 1178560864 19647088 6160528
06:25:30 17960 8192000000 10102711648 10540523520 1148984672 12.300 100 46 90 2 1189607600 31508624 9114304
06:26:51 32565 8192000000 10114037232 10598227968 1136221712 12.180 99 20 40 0 1208821504 68642064 3957728
06:28:31 35209 8192000000 10085329104 10521014272 1132493472 12.145 98 24 48 0 1164116528 26832384 4790672
06:30:09 58368 8192000000 10078812480 10519724032 1127412832 12.097 98 23 46 2 1195324608 63276000 4635776
06:32:08 43963 8192000000 10133304064 10594516992 1125726080 12.081 98 37 74 0 1185988880 53009184 7253616
06:34:36 42566 8192000000 10126658432 10593161216 1109414544 11.927 98 30 60 1 1231109648 115174336 6520768
06:36:30 97739 8192000000 10043281712 10488807424 1107148864 11.905 98 21 41 0 1138076880 26882512 4045504
06:39:09 32986 8192000000 10055087728 10494345216 1111683696 11.948 99 39 78 0 1154128864 34649456 7795712
06:40:42 54413 8192000000 10063063120 10511392768 1110393712 11.936 99 21 42 1 1157665760 43216784 4055264
06:42:41 37009 8192000000 10101450096 10577301504 1124072624 12.065 99 7 14 0 1186934912 61666192 1196096
06:44:18 71649 8192000000 10081004240 10545213440 1104133840 11.877 97 14 28 0 1167305200 60253184 2918176
06:46:33 53618 8192000000 10067489408 10502299648 1098769264 11.826 97 15 30 3 1136533424 34645680 3118480
06:48:30 68415 8192000000 10041957648 10476109824 1049863040 11.359 93 25 50 2 1112341456 56876192 5602224
06:50:39 84034 8192000000 10117533952 10596012032 1111323872 11.945 99 18 36 0 1213216992 97977568 3915552
06:53:18 64685 8192000000 10109762912 10597642240 1109729520 11.930 98 12 24 0 1168274832 56102464 2442848
06:55:24 81739 8192000000 10091924320 10518913024 1098748736 11.826 97 26 49 0 1142567328 38778928 5039664
06:57:46 99385 8192000000 10075953360 10521890816 1123636784 12.061 99 35 70 1 1178258000 47583200 7038016
07:00:26 56836 8192000000 10067338464 10545778688 1136835088 12.186 100 7 14 1 1188113488 49668736 1609664
07:02:23 70291 8192000000 10073345584 10557997056 1135904608 12.177 100 20 41 1 1206978240 66947696 4125936
07:04:54 43067 8192000000 10111704096 10559975424 1147044288 12.282 100 22 42 1 1209433632 58224496 4164848
07:06:38 83437 8192000000 10149540368 10618707968 1107892080 11.912 96 24 46 4 1210858656 97183808 5782768
07:09:24 37014 8192000000 10181772496 10674544640 1145124864 12.264 99 28 56 0 1308532240 157064144 6343232
07:11:10 78760 8192000000 10092815872 10531344384 1095193200 11.792 95 25 48 0 1161228096 60841424 5193472
07:13:42 71623 8192000000 10096181184 10524577792 1130777840 12.129 99 21 42 0 1170869296 35691312 4400144
07:15:54 128536 8192000000 10092481968 10614104064 1140418432 12.219 100 10 20 1 1196446000 54361584 1665984
07:19:24 18542 8192000000 10072034000 10514632704 1135758144 12.176 99 39 77 0 1162534960 18645584 8131232
07:20:42 74371 8192000000 10082865472 10561253376 1122690432 12.052 98 7 14 3 1168562096 44408656 1463008
07:23:01 77073 8192000000 10128734880 10609692672 1129978816 12.121 98 15 30 1 1195016080 61488352 3548912
07:25:18 65197 8192000000 10134096672 10633916416 1148747888 12.298 100 10 20 0 1254271392 103053152 2470352
07:27:44 53062 8192000000 10049159360 10511544320 1078785296 11.636 95 12 24 0 1112731408 31710848 2235264
07:29:43 33362 8192000000 10123115040 10552631296 1104155232 11.877 97 46 91 2 1190299712 76383408 9761072
07:31:21 87674 8192000000 10049579504 10512150528 1064669968 11.501 95 12 20 2 1127975248 61237440 2067840
07:33:50 33231 8192000000 10094775104 10529837056 1094827600 11.789 98 33 66 2 1155385024 54209904 6347520
07:35:27 41249 8192000000 10075304960 10524758016 1118648496 12.014 100 29 58 1 1179235312 54200592 6386224

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-08-2021 07:38:04 to 11-08-2021 07:38:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 117 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-08-2021 07:38:20 to 11-08-2021 07:38:22.

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          236046
MON$OLDEST_ACTIVE               238063
MON$OLDEST_SNAPSHOT             238063
MON$NEXT_TRANSACTION            238063
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                       2046368
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.1.2567 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2567 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2567 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2567 Firebird 4.0/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 ---
:70: 
:71: mscs-test-161.budzdorov.ru    Wed Aug 11 07:37:05 2021
:72:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:73: 
:74: 
:75: mscs-test-161.budzdorov.ru    Wed Aug 11 07:37:08 2021
:76:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:77: 
--- end of diff output ---

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

11.08.21 07:38:23. 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-08-11 07:38:25 No severe PSQL-related problems occured
11.08.21 07:38:26. End of report.