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

Hardware and OS info

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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-02-22 13:26:58
DTS_END                         2021-02-22 13:26:58.0500
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/33462
TRN_ID                          231727
ATT_ID                          589
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: 8819.92 30064 1058390
customer order: creation 161.85 423 19422
customer order: refuse 13.98 320 1678
order to supplier: creation 44.24 1390 5309
order to supplier: removal 3.33 124692 400
invoice (draft): creation 44.08 3435 5289
invoice (draft): removal 52.22 1004 6266
invoice accept: apply 38.42 15385 4610
invoice accept: cancel 47.06 928 5647
customer reserve: creation 3517.59 149 422111
customer reserve: removal 4612.26 69 553471
realization accept: apply 78.78 212 9453
realization accept: cancel 85.96 128 10315
payment from customer: creation 54.86 132 6583
payment from customer: removal 5.53 78 663
payment to supplier: creation 46.09 146 5531
payment to supplier: removal 3.17 94 380
service: total inventory turnovers 3.62 3724 434
service: total monetary turnovers 5.97 488 716
service: aggregate perf. data 0.89 13660 107
service: refresh index statistics 0.04 434831 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-02-2021 13:28:07 to 22-02-2021 13:28:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2576
TEST_TIME 2 2700
TEST_TIME 3 2834
TEST_TIME 4 2796
TEST_TIME 5 3426
TEST_TIME 6 3633
TEST_TIME 7 4129
TEST_TIME 8 4197
TEST_TIME 9 4347
TEST_TIME 10 4441
TEST_TIME 11 4471
TEST_TIME 12 4643
TEST_TIME 13 5144
TEST_TIME 14 5942
TEST_TIME 15 6467
TEST_TIME 16 7040
TEST_TIME 17 7221
TEST_TIME 18 7527
TEST_TIME 19 7869
TEST_TIME 20 7731
TEST_TIME 21 8126
TEST_TIME 22 8324
TEST_TIME 23 8229
TEST_TIME 24 8680
TEST_TIME 25 8984
TEST_TIME 26 9094
TEST_TIME 27 10015
TEST_TIME 28 10885
TEST_TIME 29 10752
TEST_TIME 30 10408
TEST_TIME 31 10317
TEST_TIME 32 10265
TEST_TIME 33 10109
TEST_TIME 34 9921
TEST_TIME 35 9837
TEST_TIME 36 9728
TEST_TIME 37 9552
TEST_TIME 38 9584
TEST_TIME 39 9659
TEST_TIME 40 9655
TEST_TIME 41 9576
TEST_TIME 42 9550
TEST_TIME 43 9423
TEST_TIME 44 9582
TEST_TIME 45 9562
TEST_TIME 46 9557
TEST_TIME 47 9514
TEST_TIME 48 9483
TEST_TIME 49 9420
TEST_TIME 50 9488
TEST_TIME 51 9559
TEST_TIME 52 9487
TEST_TIME 53 9544
TEST_TIME 54 9524
TEST_TIME 55 9576
TEST_TIME 56 9612
TEST_TIME 57 9454
TEST_TIME 58 9407
TEST_TIME 59 9360
TEST_TIME 60 9303
TEST_TIME 61 9223
TEST_TIME 62 9140
TEST_TIME 63 9052
TEST_TIME 64 9045
TEST_TIME 65 9135
TEST_TIME 66 9140
TEST_TIME 67 9081
TEST_TIME 68 9106
TEST_TIME 69 9148
TEST_TIME 70 9170
TEST_TIME 71 9165
TEST_TIME 72 9239
TEST_TIME 73 9234
TEST_TIME 74 9218
TEST_TIME 75 9241
TEST_TIME 76 9268
TEST_TIME 77 9310
TEST_TIME 78 9193
TEST_TIME 79 9101
TEST_TIME 80 9104
TEST_TIME 81 9041
TEST_TIME 82 9002
TEST_TIME 83 8953
TEST_TIME 84 8925
TEST_TIME 85 8913
TEST_TIME 86 8942
TEST_TIME 87 8927
TEST_TIME 88 8973
TEST_TIME 89 8941
TEST_TIME 90 9012
TEST_TIME 91 8976
TEST_TIME 92 9046
TEST_TIME 93 9079
TEST_TIME 94 9158
TEST_TIME 95 9194
TEST_TIME 96 9116
TEST_TIME 97 9047
TEST_TIME 98 9007
TEST_TIME 99 8976
TEST_TIME 100 8998
TEST_TIME 101 8942
TEST_TIME 102 8927
TEST_TIME 103 8926
TEST_TIME 104 8919
TEST_TIME 105 8891
TEST_TIME 106 9035
TEST_TIME 107 9021
TEST_TIME 108 8975
TEST_TIME 109 9018
TEST_TIME 110 9020
TEST_TIME 111 9057
TEST_TIME 112 9052
TEST_TIME 113 8984
TEST_TIME 114 8939
TEST_TIME 115 8901
TEST_TIME 116 8874
TEST_TIME 117 8870
TEST_TIME 118 8853
TEST_TIME 119 8846
TEST_TIME 120 8823

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-02-2021 13:28:10 to 22-02-2021 13:28:14.

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 1452040 1452040 0 0.00 0 17179 6 0 0
doc_list_biud 1452040 1452040 0 0.00 0 52295 34 0 0
sp_add_invoice_to_stock 4780 4610 170 3.55 38 168088 15385 0 170
sp_cancel_adding_invoice 5647 5647 0 0.00 6 82925 928 0 0
sp_cancel_client_order 1678 1678 0 0.00 4 22610 321 0 0
sp_cancel_customer_reserve 553471 553471 0 0.00 1 52296 70 0 0
sp_cancel_pay_from_customer 663 663 0 0.00 3 3892 79 0 0
sp_cancel_pay_to_supplier 380 380 0 0.00 3 2644 95 0 0
sp_cancel_supplier_invoice 6276 6266 10 0.15 1 23129 1005 0 10
sp_cancel_supplier_order 400 400 0 0.00 6 992089 124692 0 0
sp_cancel_write_off 10315 10315 0 0.00 1 23077 129 0 0
sp_client_order 19422 19422 0 0.00 4 30815 424 0 0
sp_customer_reserve 422111 422111 0 0.00 0 103830 150 0 0
sp_fill_shopping_cart 30013 30013 0 0.00 1 23271 46 0 0
sp_fill_shopping_cart_clo_res 422105 422105 0 0.00 0 19186 15 0 0
sp_get_clo_for_invoice 4604 4604 0 0.00 0 36810 409 0 0
sp_kill_cost_storno 17005 17005 0 0.00 0 17039 66 0 0
sp_kill_qstorno_ret_qs2qd 1123923 1123923 0 0.00 0 52200 38 0 0
sp_kill_qty_storno 591834 591834 0 0.00 0 52295 82 0 0
sp_lock_selected_doc 19494 19494 0 0.00 0 12391 5 0 0
sp_make_cost_storno 26171 26171 0 0.00 0 14698 73 0 0
sp_multiply_rows_for_pdistr 26171 26171 0 0.00 0 14649 37 0 0
sp_multiply_rows_for_qdistr 19422 19422 0 0.00 0 21302 223 0 0
sp_pay_from_customer 6583 6583 0 0.00 4 15210 132 0 0
sp_pay_to_supplier 5531 5531 0 0.00 5 14904 147 0 0
sp_payment_common 12114 12114 0 0.00 1 11475 31 0 0
sp_qd_handle_on_cancel_clo 1678 1678 0 0.00 0 22600 303 0 0
sp_qd_handle_on_invoice_upd_sts 10251 10251 0 0.00 0 40648 403 0 0
sp_qd_handle_on_reserve_upd_sts 9453 9453 0 0.00 0 29914 68 0 0
sp_reserve_write_off 9453 9453 0 0.00 7 29937 213 0 0
sp_supplier_invoice 5289 5289 0 0.00 6 107511 3435 0 0
sp_supplier_order 5309 5309 0 0.00 5 35793 1391 0 0
srv_aggregate_perf_data 107 107 0 0.00 2442 90014 13660 0 0
srv_fill_mon_cache_memory 58 58 0 0.00 2037 136307 51303 0 0
srv_find_qd_qs_mism 1015365 1015365 0 0.00 0 10670 4 0 0
srv_make_invnt_saldo 434 434 0 0.00 35 94904 3724 0 0
srv_make_money_saldo 716 716 0 0.00 1 14641 488 0 0
srv_recalc_idx_stat 5 5 0 0.00 215433 852897 434831 0 0
t$perf-abend:sp_add_invoice_to_stock 170 170 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4610 4610 0 0.00 0 20871 174 0 0
t$perf-norm:sp_cancel_adding_invoice 372 372 0 0.00 0 9308 119 0 0
t$perf-norm:sp_cancel_client_order 1678 1678 0 0.00 0 2226 10 0 0
t$perf-norm:sp_cancel_customer_reserve 636 636 0 0.00 0 666 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 663 663 0 0.00 0 4410 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 380 380 0 0.00 0 2265 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 356 356 0 0.00 0 684 11 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 27408 1725 0 0
t$perf-norm:sp_cancel_write_off 952 952 0 0.00 0 2362 16 0 0
t$perf-norm:sp_client_order 19422 19422 0 0.00 0 8971 18 0 0
t$perf-norm:sp_customer_reserve 989 989 0 0.00 0 4154 15 0 0
t$perf-norm:sp_pay_from_customer 6583 6583 0 0.00 0 10557 10 0 0
t$perf-norm:sp_pay_to_supplier 5531 5531 0 0.00 0 6621 10 0 0
t$perf-norm:sp_reserve_write_off 9453 9453 0 0.00 0 29669 15 0 0
t$perf-norm:sp_supplier_invoice 5289 5289 0 0.00 0 7469 18 0 0
t$perf-norm:sp_supplier_order 5309 5309 0 0.00 0 11673 18 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 398 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 58 58 0 0.00 0 643 13 0 0
t$perf-norm:srv_make_invnt_saldo 434 434 0 0.00 0 1878 12 0 0
t$perf-norm:srv_make_money_saldo 716 716 0 0.00 0 3789 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 9548 9208 340 3.56 0 4150 16 0 340
v_all_customers 35218 35218 0 0.00 0 23 1 0 0
v_all_suppliers 29472 29472 0 0.00 0 85 1 0 0
v_all_wares 38844 38844 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 744 744 0 0.00 0 858 9 0 0
v_cancel_client_order 3356 3356 0 0.00 0 518 3 0 0
v_cancel_customer_prepayment 1326 1326 0 0.00 0 1723 8 0 0
v_cancel_customer_reserve 1272 1272 0 0.00 0 1437 6 0 0
v_cancel_payment_to_supplier 760 760 0 0.00 0 2268 12 0 0
v_cancel_supplier_invoice 732 712 20 2.73 0 2054 18 0 20
v_cancel_supplier_order 800 800 0 0.00 0 948 5 0 0
v_cancel_write_off 1904 1904 0 0.00 0 5671 17 0 0
v_max_id_clo_ord 5308 5308 0 0.00 0 320 1 0 0
v_max_id_clo_res 989 989 0 0.00 0 7868 20 0 0
v_max_id_ord_sup 5283 5283 0 0.00 0 7497 7 0 0
v_max_non_paid_invoice 1386 1386 0 0.00 0 224 2 0 0
v_max_non_paid_realizn 3489 3489 0 0.00 0 3902 4 0 0
v_min_id_clo_ord 5308 5308 0 0.00 0 6588 8 0 0
v_min_id_clo_res 989 989 0 0.00 0 5064 15 0 0
v_min_id_ord_sup 5283 5283 0 0.00 0 6238 7 0 0
v_min_non_paid_invoice 5531 5531 0 0.00 0 11470 25 0 0
v_min_non_paid_realizn 6583 6583 0 0.00 0 7493 23 0 0
v_our_firm 9814 9814 0 0.00 0 18 1 0 0
v_reserve_write_off 18906 18906 0 0.00 0 5283 5 0 0
x_lock_depdocs_on_canc_invoice 11557 11557 0 0.00 0 16678 123 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 13370 371 0 0
x_make_qstorno_1000_1200 5309 5309 0 0.00 2 35786 1314 0 0
x_make_qstorno_1200_2000 5289 5289 0 0.00 3 107496 3264 0 0
x_make_qstorno_2100_3300 422111 422111 0 0.00 0 103829 132 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-02-2021 13:28:15 to 22-02-2021 13:28:19.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
10:57:01 5 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:58:02 25712 8192000000 9713215920 10005106688 791859648 8.814 100 89 178 0 821127024 7639328 21628048
10:59:35 22707 8192000000 9745679488 10049093632 823827392 9.137 100 84 168 2 856216112 12127552 20261168
11:00:59 17206 8192000000 9802881088 10117550080 858411152 9.484 100 72 144 0 903973664 29006736 16555776
11:02:20 11739 8192000000 9860559312 10190295040 907710544 9.975 100 77 154 2 965812992 41290576 16811872
11:03:34 7778 8192000000 9914293648 10257072128 939668176 10.290 100 83 165 1 1028047344 71163408 17215760
11:04:47 4918 8192000000 9935503296 10283831296 982430880 10.708 100 79 159 2 1051985696 53524272 16030544
11:05:53 2945 8192000000 9985873712 10343268352 1016811520 11.041 100 83 166 2 1103741120 70482064 16447536
11:06:58 2481 8192000000 10002293824 10366054400 1043733184 11.301 100 86 171 1 1113484720 52370016 17381520
11:08:01 1935 8192000000 10026195968 10398208000 1064395600 11.499 100 90 182 1 1142076256 59272624 18408032
11:09:04 3980 8192000000 9980328688 10355974144 1064875824 11.503 100 66 133 1 1097956624 18297072 14783728
11:10:09 8760 8192000000 9991023264 10387632128 1073893232 11.589 100 29 59 3 1115685072 35034448 6757392
11:11:18 7276 8192000000 9986094624 10384105472 1085650880 11.701 100 27 52 1 1112409536 21121616 5637040
11:12:27 5094 8192000000 10002961696 10412150784 1089474544 11.738 100 25 49 3 1119460112 24640768 5344800
11:13:34 39692 8192000000 10031935632 10423267328 1097360672 11.813 100 53 109 1 1124417328 15371728 11684928
11:15:14 11073 8192000000 10033181072 10449637376 1104655232 11.882 100 36 70 2 1133489696 21118944 7715520
11:16:33 6045 8192000000 10007788592 10396745728 1093742608 11.778 100 41 84 2 1124679008 21522544 9413856
11:17:41 7833 8192000000 9980437600 10370023424 1037981088 11.245 97 38 78 0 1072335056 26145024 8208944
11:18:50 21207 8192000000 9977946032 10373722112 1024763632 11.118 96 23 46 1 1057525568 27857552 4904384
11:20:13 3212 8192000000 9974246128 10350604288 1046586112 11.328 100 60 119 2 1086379520 26782128 13011280
11:21:19 5624 8192000000 9943583456 10331885568 1023639024 11.107 100 48 98 2 1061680592 27515472 10526096
11:22:25 70015 8192000000 9930581264 10334679040 1029932976 11.168 100 27 52 2 1053106128 17231088 5942064
11:24:35 6105 8192000000 9959084320 10354212864 1048081792 11.342 100 48 96 2 1076048112 16888640 11077680
11:25:42 4319 8192000000 9973540496 10368008192 1058789424 11.445 100 43 85 5 1082004320 13004912 10209984
11:26:48 6203 8192000000 9976572576 10375573504 1064619744 11.501 100 22 44 0 1085553424 15884352 5049328
11:28:01 136306 8192000000 10043504656 10456473600 1095643872 11.796 100 33 62 3 1142067008 40273536 6149600
11:31:21 7467 8192000000 10113943104 10521382912 1125670304 12.081 100 45 89 0 1229187360 93264064 10252992
11:32:31 22807 8192000000 10129625232 10543861760 1136535136 12.183 100 40 80 0 1234852736 89254096 9063504
11:33:57 8408 8192000000 10181930864 10593640448 1153849648 12.346 100 51 100 1 1287024032 122193792 10980592
11:35:12 9265 8192000000 10190305680 10604105728 1155640640 12.362 100 45 90 1 1297950944 131718832 10591472
11:36:28 2037 8192000000 10276037936 10674425856 1167087872 12.470 100 88 173 0 1374936720 188420112 19428736
11:37:36 12350 8192000000 10294170224 10700988416 1159950192 12.403 99 66 130 1 1394039376 218775504 15313680
11:38:57 2855 8192000000 10353050208 10764849152 1153969264 12.347 99 80 158 1 1452620720 279306544 19344912
11:40:06 6802 8192000000 10364484848 10800775168 1166383392 12.463 100 54 107 0 1472670144 293295024 12991728
11:41:18 3661 8192000000 10277038944 10698289152 1152002240 12.328 99 64 128 0 1390069264 222748144 15318880
11:42:29 2941 8192000000 10299710960 10718990336 1159549888 12.399 100 79 155 3 1406332560 227669584 19113088
11:43:37 3030 8192000000 10346514784 10751348736 1156831696 12.374 100 74 148 0 1443389056 269439344 17118016
11:44:44 2534 8192000000 10348666336 10754211840 1168912480 12.487 100 77 150 0 1447768784 261985760 16870544
11:45:50 10683 8192000000 10356484032 10782777344 1159815616 12.402 99 35 70 0 1454579712 285742256 9021840
11:47:04 5277 8192000000 10416147568 10829430784 1163300560 12.434 100 59 118 2 1516608064 338955040 14352464
11:48:13 10034 8192000000 10424300192 10847195136 1163954784 12.440 100 45 90 0 1518343024 342926304 11461936
11:49:24 21000 8192000000 10441701328 10884595712 1154057696 12.348 100 33 66 0 1539606352 376209040 9339616
11:50:51 19184 8192000000 10390949536 10864570368 1149496448 12.305 100 33 66 0 1537475840 379342048 8637344
11:52:15 22774 8192000000 10407862000 10859950080 1118468480 12.013 98 24 48 1 1504444944 379028592 6947872
11:54:59 9991 8192000000 10059846624 10482262016 1085539008 11.700 98 37 75 0 1123430816 29845616 8046192
11:57:05 52816 8192000000 10088848384 10546745344 1094365808 11.784 98 26 52 0 1187471840 87426896 5679136
11:59:01 98448 8192000000 10128365632 10568974336 1106753584 11.902 98 20 40 1 1202951744 91935072 4263088
12:01:44 86541 8192000000 10134617520 10580488192 1122514096 12.051 100 25 50 0 1251832304 123916112 5402096
12:04:29 118701 8192000000 10076399904 10543403008 1101815312 11.855 98 20 40 0 1176851392 70487056 4549024
12:07:36 66205 8192000000 10122248960 10604220416 1132379920 12.144 100 15 31 1 1233928336 98709952 2838464
12:09:58 69537 8192000000 10074142368 10531778560 1134706880 12.166 100 8 16 0 1185626304 49416704 1502720
12:12:07 65557 8192000000 10072222176 10528083968 1130416288 12.125 99 28 57 0 1181041808 44659472 5966048
12:14:22 62655 8192000000 10100102160 10561269760 1117958704 12.008 98 16 32 0 1144050496 22738128 3353664
12:16:38 97753 8192000000 10082757776 10551431168 1133371824 12.153 99 7 14 0 1190990144 56424560 1193760
12:19:22 57672 8192000000 10094277952 10571796480 1138394752 12.200 99 9 18 0 1179231536 38945344 1891440
12:21:20 55534 8192000000 10116513536 10620256256 1137443248 12.191 99 28 56 1 1198450960 54994704 6013008
12:25:54 39435 8192000000 10051384224 10501566464 1115610112 11.985 98 12 24 0 1145635776 27872160 2153504
12:28:53 87720 8192000000 10090966752 10566008832 1141772752 12.232 100 15 30 0 1214218224 69506208 2939264
12:31:27 61719 8192000000 10109645072 10566012928 1146604320 12.278 100 17 34 1 1188247328 37989312 3653696
12:33:40 56060 8192000000 10121511696 10579283968 1090958592 11.752 95 29 58 3 1151984144 54623056 6402496
12:35:36 68449 8192000000 10093493984 10525716480 1091300928 11.755 97 24 48 1 1179554368 82933168 5320272
12:37:55 43153 8192000000 10060065552 10538684416 1109730096 11.930 99 23 44 1 1189154768 75133776 4290896
12:39:41 23273 8192000000 10050056784 10475806720 1094005424 11.781 98 26 52 2 1128503712 29270640 5227648
12:41:04 29166 8192000000 10091667104 10519908352 1084394272 11.689 97 39 78 0 1140260608 48122752 7743584
12:42:34 106515 8192000000 10058818688 10521837568 1095378896 11.794 98 12 22 1 1159780192 61387184 3014112
12:46:04 87371 8192000000 10042932224 10515099648 1112928048 11.960 99 8 16 0 1150552432 36159456 1464928
12:48:33 85206 8192000000 10041705072 10492645376 1077493088 11.624 96 13 26 1 1110591440 30215184 2883168
12:50:58 78573 8192000000 10088754544 10527993856 1109670032 11.929 99 22 44 4 1176575088 62292064 4612992
12:53:28 98890 8192000000 10089793456 10542739456 1098708432 11.825 98 18 36 1 1155977488 53563568 3705488
12:56:20 47549 8192000000 10060289712 10493730816 1085453648 11.699 97 16 32 0 1130519904 42292592 2773664
12:58:12 60638 8192000000 10106927344 10565447680 1117788768 12.006 99 23 46 1 1200976992 78468432 4719792
13:00:21 98385 8192000000 10059410272 10538409984 1088212464 11.726 97 9 18 1 1130350304 40544144 1593696
13:02:59 84912 8192000000 10053124944 10509119488 1122627248 12.052 100 22 45 0 1150232816 23239088 4366480
13:05:24 117956 8192000000 10106704368 10614718464 1106083456 11.895 98 10 20 0 1182786192 74454608 2248128
13:08:23 38229 8192000000 10134294128 10555625472 1123755424 12.062 98 33 66 1 1202148384 70448416 7944544
13:10:05 65446 8192000000 10168589808 10638823424 1137941600 12.196 99 18 36 1 1258480432 116539504 3999328
13:12:23 66318 8192000000 10090550560 10540564480 1109225360 11.925 97 14 28 0 1175309904 62320240 3764304
13:14:29 10632 8192000000 10104527664 10533265408 1138085056 12.198 100 45 91 2 1199238112 50123680 11029376
13:15:43 93050 8192000000 10126705184 10597019648 1113541264 11.966 98 22 44 1 1166208144 48459552 4207328
13:18:18 129670 8192000000 10065729840 10521952256 1097616944 11.815 96 11 22 0 1151290704 51583248 2090512
13:21:44 35870 8192000000 10073524128 10544803840 1125854384 12.082 100 3 6 0 1185205680 58755712 595584
13:23:27 61308 8192000000 10059896416 10570756096 1129792576 12.119 100 5 10 0 1179331824 48393008 1146240
13:25:28 49175 8192000000 10041370528 10477969408 1086081904 11.705 96 20 40 1 1116647808 26652704 3913200

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-02-2021 13:28:21 to 22-02-2021 13:28:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 170 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

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

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          231523
MON$OLDEST_ACTIVE               232739
MON$OLDEST_SNAPSHOT             232739
MON$NEXT_TRANSACTION            232739
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                       2103936
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             791
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

Database statistics

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

Database validation

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

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

--- start of diff output ---
:96: 
:97: mscs-test-161.budzdorov.ru    Mon Feb 22 13:27:01 2021
:98:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:99: 
--- end of diff output ---

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

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