Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.01.2023 07:47
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 15118836 kB
MemAvailable 30347016 kB
Buffers 0 kB
Cached 16561648 kB
SwapCached 4428 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.3
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 2023-01-08 07:46:37
DTS_END                         2023-01-08 07:46:37.2820
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/46452
TRN_ID                          221643
ATT_ID                          628
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: 8845.79 29235 1061495
customer order: creation 164.76 465 19771
customer order: refuse 14.59 427 1751
order to supplier: creation 44.96 1402 5395
order to supplier: removal 3.45 155607 414
invoice (draft): creation 44.78 3322 5373
invoice (draft): removal 47.82 1025 5738
invoice accept: apply 42.15 24509 5058
invoice accept: cancel 43.85 1276 5262
customer reserve: creation 3877.28 235 465274
customer reserve: removal 4280.24 99 513629
realization accept: apply 82.63 297 9916
realization accept: cancel 71.86 196 8623
payment from customer: creation 57.71 195 6925
payment from customer: removal 5.62 204 674
payment to supplier: creation 48.67 200 5840
payment to supplier: removal 3.42 136 410
service: total inventory turnovers 3.74 4156 449
service: total monetary turnovers 7.36 343 883
service: aggregate perf. data 0.88 17047 105
service: refresh index statistics 0.04 373553 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 08-01-2023 07:47:30 to 08-01-2023 07:47:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9527
TEST_TIME 2 7167
TEST_TIME 3 7278
TEST_TIME 4 7097
TEST_TIME 5 6910
TEST_TIME 6 6478
TEST_TIME 7 6616
TEST_TIME 8 6596
TEST_TIME 9 6442
TEST_TIME 10 7052
TEST_TIME 11 7200
TEST_TIME 12 7153
TEST_TIME 13 7183
TEST_TIME 14 7364
TEST_TIME 15 7314
TEST_TIME 16 7296
TEST_TIME 17 7365
TEST_TIME 18 7430
TEST_TIME 19 7471
TEST_TIME 20 7629
TEST_TIME 21 7801
TEST_TIME 22 7969
TEST_TIME 23 8165
TEST_TIME 24 8471
TEST_TIME 25 8361
TEST_TIME 26 8554
TEST_TIME 27 8772
TEST_TIME 28 9272
TEST_TIME 29 9670
TEST_TIME 30 10452
TEST_TIME 31 10660
TEST_TIME 32 10652
TEST_TIME 33 10459
TEST_TIME 34 10336
TEST_TIME 35 10295
TEST_TIME 36 10153
TEST_TIME 37 10030
TEST_TIME 38 9871
TEST_TIME 39 9998
TEST_TIME 40 9963
TEST_TIME 41 9881
TEST_TIME 42 9786
TEST_TIME 43 9767
TEST_TIME 44 9695
TEST_TIME 45 9939
TEST_TIME 46 9824
TEST_TIME 47 9761
TEST_TIME 48 9717
TEST_TIME 49 9683
TEST_TIME 50 9787
TEST_TIME 51 9737
TEST_TIME 52 9682
TEST_TIME 53 9552
TEST_TIME 54 9412
TEST_TIME 55 9486
TEST_TIME 56 9337
TEST_TIME 57 9315
TEST_TIME 58 9248
TEST_TIME 59 9308
TEST_TIME 60 9257
TEST_TIME 61 9260
TEST_TIME 62 9204
TEST_TIME 63 9148
TEST_TIME 64 9139
TEST_TIME 65 9176
TEST_TIME 66 9149
TEST_TIME 67 9249
TEST_TIME 68 9249
TEST_TIME 69 9226
TEST_TIME 70 9222
TEST_TIME 71 9283
TEST_TIME 72 9299
TEST_TIME 73 9318
TEST_TIME 74 9365
TEST_TIME 75 9380
TEST_TIME 76 9390
TEST_TIME 77 9380
TEST_TIME 78 9299
TEST_TIME 79 9355
TEST_TIME 80 9287
TEST_TIME 81 9234
TEST_TIME 82 9223
TEST_TIME 83 9188
TEST_TIME 84 9147
TEST_TIME 85 9113
TEST_TIME 86 9119
TEST_TIME 87 9074
TEST_TIME 88 9082
TEST_TIME 89 9042
TEST_TIME 90 9009
TEST_TIME 91 8996
TEST_TIME 92 8979
TEST_TIME 93 9066
TEST_TIME 94 9024
TEST_TIME 95 9062
TEST_TIME 96 9110
TEST_TIME 97 9097
TEST_TIME 98 9120
TEST_TIME 99 9281
TEST_TIME 100 9231
TEST_TIME 101 9180
TEST_TIME 102 9181
TEST_TIME 103 9133
TEST_TIME 104 9077
TEST_TIME 105 9044
TEST_TIME 106 8986
TEST_TIME 107 8943
TEST_TIME 108 8937
TEST_TIME 109 8892
TEST_TIME 110 8875
TEST_TIME 111 8875
TEST_TIME 112 8846
TEST_TIME 113 8841
TEST_TIME 114 8859
TEST_TIME 115 8855
TEST_TIME 116 8817
TEST_TIME 117 8857
TEST_TIME 118 8880
TEST_TIME 119 8885
TEST_TIME 120 8869

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-01-2023 07:47:34 to 08-01-2023 07:47:37.

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 1487734 1487734 0 0.00 0 23123 14 0 0
doc_list_biud 1487734 1487734 0 0.00 0 36441 40 0 0
sp_add_invoice_to_stock 5183 5058 125 2.41 34 165579 24509 0 125
sp_cancel_adding_invoice 5262 5262 0 0.00 7 111516 1277 0 0
sp_cancel_client_order 1751 1751 0 0.00 6 18664 428 0 0
sp_cancel_customer_reserve 513629 513629 0 0.00 1 32781 100 0 0
sp_cancel_pay_from_customer 674 674 0 0.00 4 21714 204 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 4 2006 137 0 0
sp_cancel_supplier_invoice 5747 5738 9 0.15 3 30713 1025 0 9
sp_cancel_supplier_order 414 414 0 0.00 9 1217475 155608 0 0
sp_cancel_write_off 8623 8623 0 0.00 2 9861 196 0 0
sp_client_order 19771 19771 0 0.00 5 28162 466 0 0
sp_customer_reserve 465274 465274 0 0.00 0 140437 235 0 0
sp_fill_shopping_cart 30529 30529 0 0.00 1 12920 85 0 0
sp_fill_shopping_cart_clo_res 465236 465236 0 0.00 0 12331 18 0 0
sp_get_clo_for_invoice 5014 5014 0 0.00 0 32910 339 0 0
sp_kill_cost_storno 14969 14969 0 0.00 0 7591 80 0 0
sp_kill_qstorno_ret_qs2qd 1042033 1042033 0 0.00 0 32681 44 0 0
sp_kill_qty_storno 550343 550343 0 0.00 0 36441 101 0 0
sp_lock_selected_doc 20634 20634 0 0.00 0 9496 8 0 0
sp_make_cost_storno 27691 27691 0 0.00 0 19447 82 0 0
sp_multiply_rows_for_pdistr 27691 27691 0 0.00 0 9399 39 0 0
sp_multiply_rows_for_qdistr 19771 19771 0 0.00 0 15547 175 0 0
sp_pay_from_customer 6925 6925 0 0.00 6 16263 195 0 0
sp_pay_to_supplier 5840 5840 0 0.00 6 19020 201 0 0
sp_payment_common 12765 12765 0 0.00 2 12941 50 0 0
sp_qd_handle_on_cancel_clo 1751 1751 0 0.00 0 7035 331 0 0
sp_qd_handle_on_invoice_upd_sts 10272 10272 0 0.00 0 36441 486 0 0
sp_qd_handle_on_reserve_upd_sts 9916 9916 0 0.00 0 11964 61 0 0
sp_reserve_write_off 9916 9916 0 0.00 8 26301 297 0 0
sp_supplier_invoice 5373 5373 0 0.00 10 73795 3322 0 0
sp_supplier_order 5395 5395 0 0.00 8 29366 1403 0 0
srv_aggregate_perf_data 105 105 0 0.00 2411 120852 17048 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 711 128028 38879 0 0
srv_find_qd_qs_mism 1012130 1012130 0 0.00 0 12440 11 0 0
srv_make_invnt_saldo 449 449 0 0.00 72 48594 4157 0 0
srv_make_money_saldo 883 883 0 0.00 1 17498 344 0 0
srv_recalc_idx_stat 5 5 0 0.00 245646 445676 373554 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5061 5061 0 0.00 1 29356 860 0 0
t$perf-norm:sp_cancel_adding_invoice 440 440 0 0.00 1 6879 456 0 0
t$perf-norm:sp_cancel_client_order 1751 1751 0 0.00 0 2128 28 0 0
t$perf-norm:sp_cancel_customer_reserve 639 639 0 0.00 0 1378 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 674 674 0 0.00 0 663 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 2187 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 653 37 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 98189 5764 0 0
t$perf-norm:sp_cancel_write_off 982 982 0 0.00 0 2208 20 0 0
t$perf-norm:sp_client_order 19771 19771 0 0.00 0 6179 33 0 0
t$perf-norm:sp_customer_reserve 1004 1004 0 0.00 0 3295 18 0 0
t$perf-norm:sp_pay_from_customer 6925 6925 0 0.00 0 11017 22 0 0
t$perf-norm:sp_pay_to_supplier 5840 5840 0 0.00 0 6884 21 0 0
t$perf-norm:sp_reserve_write_off 9916 9916 0 0.00 0 6171 20 0 0
t$perf-norm:sp_supplier_invoice 5373 5373 0 0.00 0 5547 31 0 0
t$perf-norm:sp_supplier_order 5395 5395 0 0.00 0 3311 29 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 691 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 2296 54 0 0
t$perf-norm:srv_make_invnt_saldo 449 449 0 0.00 0 1088 19 0 0
t$perf-norm:srv_make_money_saldo 883 883 0 0.00 0 250 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 174 46 0 0
v_add_invoice_to_stock 10270 10020 250 2.43 0 16636 33 0 250
v_all_customers 36194 36194 0 0.00 0 183 4 0 0
v_all_suppliers 30112 30112 0 0.00 0 75 4 0 0
v_all_wares 39542 39542 0 0.00 0 126 4 0 0
v_cancel_adding_invoice 880 880 0 0.00 0 349 16 0 0
v_cancel_client_order 3502 3502 0 0.00 0 18326 25 0 0
v_cancel_customer_prepayment 1348 1348 0 0.00 0 21320 37 0 0
v_cancel_customer_reserve 1278 1278 0 0.00 1 4056 18 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 1999 21 0 0
v_cancel_supplier_invoice 814 796 18 2.21 0 21165 47 0 18
v_cancel_supplier_order 828 828 0 0.00 0 2346 16 0 0
v_cancel_write_off 1964 1964 0 0.00 0 7928 31 0 0
v_max_id_clo_ord 5392 5392 0 0.00 0 414 5 0 0
v_max_id_clo_res 1004 1004 0 0.00 0 1279 11 0 0
v_max_id_ord_sup 5366 5366 0 0.00 0 2447 7 0 0
v_max_non_paid_invoice 1542 1542 0 0.00 0 595 8 0 0
v_max_non_paid_realizn 3696 3696 0 0.00 0 6603 10 0 0
v_min_id_clo_ord 5392 5392 0 0.00 0 3317 8 0 0
v_min_id_clo_res 1004 1004 0 0.00 0 1805 11 0 0
v_min_id_ord_sup 5366 5366 0 0.00 0 4936 12 0 0
v_min_non_paid_invoice 5840 5840 0 0.00 0 5067 15 0 0
v_min_non_paid_realizn 6925 6925 0 0.00 0 10471 25 0 0
v_our_firm 9806 9806 0 0.00 0 64 4 0 0
v_reserve_write_off 19832 19832 0 0.00 0 26149 19 0 0
x_lock_depdocs_on_canc_invoice 10602 10602 0 0.00 0 15007 135 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 15933 359 0 0
x_make_qstorno_1000_1200 5395 5395 0 0.00 3 29213 1237 0 0
x_make_qstorno_1200_2000 5373 5373 0 0.00 4 73780 3083 0 0
x_make_qstorno_2100_3300 465274 465274 0 0.00 0 140435 206 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 08-01-2023 07:47:38 to 08-01-2023 07:47:43.

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:16:41 9 8192000000 8804879120 8818253824 8567648 0.104 4 2 3 0 8723184 94176 61360
05:17:42 23196 8192000000 9719140656 10018058240 797269840 8.869 100 91 182 0 827555824 7615776 22670208
05:19:10 19246 8192000000 9767686032 10074259456 841565040 9.315 100 96 190 1 877532032 12960192 23006800
05:20:31 10342 8192000000 9805141360 10124947456 872065888 9.621 100 87 172 0 910477408 18922768 19488752
05:21:45 10231 8192000000 9865844048 10198650880 918851296 10.085 100 86 168 1 977628528 40473440 18303792
05:22:58 4664 8192000000 9920764208 10264948736 960208592 10.491 100 84 166 0 1040238832 62465072 17565168
05:24:09 2227 8192000000 9963275744 10316423168 997797824 10.857 100 90 181 0 1082791184 66931200 18062160
05:25:14 1693 8192000000 10008871536 10370646016 1024367760 11.114 100 93 176 1 1128017856 86213840 17436256
05:26:18 1393 8192000000 10033924512 10402258944 1042759664 11.291 100 92 172 1 1150190048 91189488 16240896
05:27:21 1104 8192000000 10040191376 10417336320 1064465088 11.499 100 96 188 2 1159065744 75543552 19057104
05:28:23 7974 8192000000 10039032880 10414579712 1075542848 11.605 100 97 184 0 1160450336 66880992 18026496
05:29:33 1001 8192000000 10048252752 10425294848 1085418560 11.699 100 89 168 1 1169447744 68255728 15773456
05:30:35 1093 8192000000 10067744384 10451439616 1086466240 11.709 100 95 179 1 1189936736 85875376 17595120
05:31:37 1077 8192000000 10058315216 10445828096 1092012288 11.762 100 89 176 1 1184302576 75210512 17079776
05:32:39 1119 8192000000 10053913024 10443309056 1092949920 11.771 100 95 188 0 1174138816 61752176 19436720
05:33:42 1116 8192000000 10043193600 10434392064 1097070960 11.810 100 92 179 0 1167883376 52265168 18547248
05:34:44 1013 8192000000 10044851760 10435940352 1102488528 11.861 100 95 183 0 1170547904 49195424 18863952
05:35:46 948 8192000000 10073998768 10461868032 1104479696 11.880 100 97 193 1 1192986624 68356944 20149984
05:36:48 1027 8192000000 10060160704 10451701760 1100360864 11.841 100 93 177 1 1181801584 63472128 17968592
05:37:52 6171 8192000000 10032659216 10417848320 1091386912 11.756 100 83 158 2 1152855760 47182656 14286192
05:38:59 918 8192000000 10016483760 10400182272 1081369328 11.661 100 88 166 4 1137146384 36549584 19227472
05:40:01 1254 8192000000 10022623392 10410459136 1070119936 11.553 99 78 147 2 1141498112 56166784 15211392
05:41:03 1263 8192000000 10025751824 10404487168 1069011296 11.543 100 96 188 0 1147845904 59941008 18893600
05:42:05 1342 8192000000 10038840784 10414800896 1042619088 11.290 100 92 175 1 1160746240 101037520 17089632
05:43:07 1626 8192000000 9978031184 10353983488 1025458480 11.125 99 76 144 0 1101034112 59797648 15777984
05:44:10 1274 8192000000 10017711472 10396200960 1045678784 11.319 100 95 181 3 1142856144 77255312 19922048
05:45:12 1114 8192000000 10009036336 10382860288 1040266624 11.267 100 94 182 1 1128685056 70801232 17617200
05:46:14 3431 8192000000 9994636464 10371973120 1034794928 11.215 99 74 136 2 1118522832 70213952 13513952
05:47:21 1864 8192000000 9995008544 10375671808 1056353440 11.422 100 76 150 0 1119446816 46835520 16257856
05:48:32 12370 8192000000 10016990720 10403287040 1062087616 11.476 100 63 118 2 1136063584 62386192 11589776
05:49:48 2237 8192000000 10062093328 10445127680 1083117584 11.677 100 81 158 1 1182361472 83024608 16219280
05:50:56 2226 8192000000 10048241088 10443046912 1092001872 11.762 100 73 136 0 1168092656 61319824 14770960
05:52:07 4626 8192000000 10104106720 10497908736 1099524096 11.833 100 70 137 0 1212415360 98711888 14179376
05:53:16 4555 8192000000 10130665488 10541236224 1112383248 11.955 100 62 124 1 1247587296 122178032 13026016
05:54:24 5969 8192000000 10178341056 10567413760 1119321344 12.021 100 81 152 1 1286374320 152440352 14612624
05:55:34 1644 8192000000 10208989824 10603577344 1133129328 12.151 100 90 175 0 1326707984 175321456 18257200
05:56:38 1436 8192000000 10204504816 10597011456 1143893840 12.252 100 92 184 4 1316164032 152023392 20246800
05:57:45 3721 8192000000 10240272192 10639929344 1158635824 12.390 100 82 150 0 1349325328 174748944 15940560
05:58:52 2402 8192000000 10260921920 10657452032 1164113328 12.442 100 90 164 3 1371726320 189014336 18598656
05:59:58 772 8192000000 10283539952 10692743168 1174880848 12.542 100 92 181 0 1394127232 198597280 20649104
06:01:01 975 8192000000 10314231920 10719649792 1176168512 12.554 100 92 176 1 1421999760 226493344 19337904
06:02:05 1265 8192000000 10389746832 10791337984 1194799632 12.728 100 98 188 2 1494888336 279236576 20852128
06:03:09 707 8192000000 10411067280 10810535936 1193302176 12.714 100 98 190 1 1517627328 301404016 22921136
06:04:13 774 8192000000 10473379776 10872250368 1189865648 12.682 100 97 192 0 1580846240 368232240 22748352
06:05:18 1032 8192000000 10490455712 10916601856 1193314864 12.714 100 99 193 0 1599393328 383486048 22592416
06:06:20 1120 8192000000 10480369696 10923569152 1191971920 12.702 100 99 196 0 1586250496 371143008 23135568
06:07:26 750 8192000000 10513808224 10954117120 1197006592 12.749 100 96 187 2 1617989600 399851056 21131952
06:08:29 699 8192000000 10510000624 10964611072 1209264768 12.862 100 94 187 2 1613564320 382103488 22196064
06:09:32 890 8192000000 10527524080 10976223232 1199389712 12.771 100 83 165 0 1631714176 412557264 19767200
06:10:36 19570 8192000000 10486453136 10934620160 1187923168 12.664 100 44 88 0 1586046608 386524512 11598928
06:11:58 9508 8192000000 10501986560 10949300224 1181493872 12.604 100 50 96 1 1604469968 409596624 13379472
06:13:14 11941 8192000000 10453888272 10920312832 1169770896 12.495 100 39 77 0 1580719104 400617712 10330496
06:14:35 19839 8192000000 10388098992 10916143104 1138915536 12.205 99 27 54 2 1507737952 361344096 7478320
06:16:16 12374 8192000000 10183986528 10676727808 1136604368 12.184 100 27 52 0 1329038832 185545168 6889296
06:17:29 23281 8192000000 10083985200 10518720512 1127103680 12.094 99 44 87 1 1193557616 57124416 9329520
06:18:56 61420 8192000000 10086817264 10518159360 1109174800 11.925 97 25 50 0 1179902000 65540768 5186432
06:20:59 24651 8192000000 10142323744 10583896064 1128971408 12.112 99 33 63 0 1227541712 92187248 6383056
06:22:27 55767 8192000000 10100157008 10568257536 1118965248 12.017 99 9 19 0 1226200064 104953632 2281184
06:24:23 88279 8192000000 10095786784 10582781952 1114657008 11.976 98 31 60 1 1224460448 102632656 7170784
06:26:53 77775 8192000000 10068095616 10493489152 1110177248 11.934 98 35 66 2 1137276192 20758128 6340816
06:29:11 123133 8192000000 10148463440 10629652480 1130765136 12.129 99 16 30 0 1256543040 122409440 3368464
06:32:18 30762 8192000000 10049964864 10484297728 1117029936 11.999 99 47 89 2 1142165776 14724528 10411312
06:33:50 36214 8192000000 10101331984 10522517504 1115548960 11.985 98 46 85 2 1168896336 44769232 8578144
06:35:27 107590 8192000000 10077912944 10551218176 1060124416 11.458 93 13 25 0 1120326112 58130880 2070816
06:38:15 107119 8192000000 10108776112 10561032192 1136936512 12.187 100 16 32 0 1182779824 41931168 3912144
06:41:30 98383 8192000000 10108565488 10549080064 1132735104 12.147 99 36 68 1 1186552240 46668608 7148528
06:44:11 35819 8192000000 10142148176 10566176768 1142099136 12.235 100 54 104 2 1242571984 88762704 11710144
06:45:48 37585 8192000000 10140129680 10590494720 1137068512 12.188 99 30 59 0 1233269408 89293584 6907312
06:47:34 117160 8192000000 10124083360 10589347840 1109555536 11.928 97 13 22 1 1219347136 107285056 2506544
06:50:34 33965 8192000000 10108219952 10624540672 1101870704 11.855 97 12 22 0 1203075888 98650768 2554416
06:52:09 24640 8192000000 10094848880 10534854656 1119876448 12.026 100 25 50 0 1196265104 70883680 5504976
06:53:49 22624 8192000000 10113229888 10549850112 1129770192 12.119 100 51 100 2 1192956704 53540592 9645920
06:55:14 18763 8192000000 10081823168 10510958592 1098666976 11.825 97 60 116 1 1155156144 43219344 13269824
06:56:37 46594 8192000000 10101944992 10555142144 1065277696 11.507 94 47 94 0 1141863968 66353008 10233264
06:58:28 95351 8192000000 10089749728 10523176960 1067021648 11.524 95 19 38 0 1148496464 77440240 4034576
07:01:11 119850 8192000000 10096246592 10555711488 1055420672 11.413 94 19 38 0 1120351056 60846640 4083744
07:04:12 58801 8192000000 10088218352 10554331136 1091204352 11.754 99 22 44 0 1190282208 93709120 5368736
07:06:13 71337 8192000000 10067177936 10514374656 1089554432 11.738 98 44 86 2 1137198512 38835072 8809008
07:08:25 90179 8192000000 10070436320 10542764032 1124354240 12.068 100 14 28 0 1186122416 58638576 3129600
07:10:58 66597 8192000000 10068685440 10533863424 1106551776 11.900 98 13 24 0 1173955984 64678832 2725376
07:13:19 49026 8192000000 10114490128 10563530752 1136074064 12.179 100 37 72 0 1178443616 33793536 8576016
07:15:08 37159 8192000000 10090801056 10528169984 1096107152 11.801 96 19 36 0 1161766640 61022928 4636560
07:16:48 24235 8192000000 10153494960 10603069440 1136009312 12.178 100 42 84 0 1251220128 106058576 9152240
07:18:14 41358 8192000000 10189391120 10620760064 1117378736 12.002 97 30 60 0 1247984528 123937568 6668224
07:20:07 19318 8192000000 10160901040 10599464960 1129601328 12.118 99 32 64 1 1251756272 115267696 6887248
07:21:29 18182 8192000000 10199851248 10672717824 1142986560 12.244 100 37 74 0 1297463552 146304784 8172208
07:22:57 44910 8192000000 10210363984 10679181312 1139243696 12.208 99 22 43 0 1279164496 135028192 4892608
07:24:42 44281 8192000000 10108637504 10601553920 1098141936 11.820 97 13 26 0 1224148944 123227552 2779456
07:26:27 128028 8192000000 10064590304 10525696000 1107957840 11.913 98 33 65 1 1160360944 46477952 5925152
07:29:44 54756 8192000000 10025334432 10476027904 1093319824 11.774 98 6 12 0 1137956880 43576704 1060352
07:31:48 83006 8192000000 10045984912 10504499200 1094868928 11.789 98 16 32 0 1108641904 10301648 3471328
07:34:11 65303 8192000000 10054915632 10494537728 1118704896 12.015 99 19 36 1 1151797952 29459072 3633984
07:36:17 40422 8192000000 10063702624 10517430272 1117215728 12.001 99 23 46 0 1177247952 54647536 5384688
07:37:59 84163 8192000000 10091040096 10557497344 1124712304 12.071 99 12 24 0 1169118464 42016368 2389792
07:41:00 64770 8192000000 10082267360 10524008448 1095614896 11.796 96 27 51 1 1156820080 55810064 5395120
07:43:09 64613 8192000000 10131427184 10588680192 1135622592 12.174 100 30 60 0 1250342480 108261584 6458304
07:45:18 19834 8192000000 10078106304 10523893760 1123135216 12.057 99 59 112 3 1172422944 36779840 12507888

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 08-01-2023 07:47:45 to 08-01-2023 07:48:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 125 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-01-2023 07:48:02 to 08-01-2023 07:48:04.

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          218122
MON$OLDEST_ACTIVE               221933
MON$OLDEST_SNAPSHOT             221933
MON$NEXT_TRANSACTION            221933
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                       2051744
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             775
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2887 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2887 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2887 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2887 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sun Jan  8 07:46:38 2023
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Sun Jan  8 07:46:40 2023
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Sun Jan  8 07:46:40 2023
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
--- end of diff output ---

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

08.01.23 07:48:05. 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
2023-01-08 07:48:08 No severe PSQL-related problems occured
08.01.23 07:48:09. End of report.