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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16233672 kB
MemAvailable 31050976 kB
Buffers 20 kB
Cached 15422212 kB
SwapCached 2572 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-07 04:37:05
DTS_END                         2021-08-07 04:37:05.6810
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/44466
TRN_ID                          228649
ATT_ID                          659
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: 8977.24 24276 1077269
customer order: creation 167.50 444 20100
customer order: refuse 14.50 391 1740
order to supplier: creation 45.62 1457 5474
order to supplier: removal 3.13 148294 376
invoice (draft): creation 45.08 3416 5409
invoice (draft): removal 50.23 1171 6028
invoice accept: apply 40.72 21272 4886
invoice accept: cancel 45.45 968 5454
customer reserve: creation 3835.88 207 460306
customer reserve: removal 4440.77 81 532892
realization accept: apply 81.82 236 9818
realization accept: cancel 82.46 160 9895
payment from customer: creation 56.53 125 6783
payment from customer: removal 5.53 75 664
payment to supplier: creation 47.63 137 5715
payment to supplier: removal 3.31 141 397
service: total inventory turnovers 4.12 3387 494
service: total monetary turnovers 6.01 589 721
service: aggregate perf. data 0.94 14123 113
service: refresh index statistics 0.03 288849 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 07-08-2021 04:39:09 to 07-08-2021 04:39:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5179
TEST_TIME 2 6323
TEST_TIME 3 6558
TEST_TIME 4 5920
TEST_TIME 5 5491
TEST_TIME 6 5108
TEST_TIME 7 5060
TEST_TIME 8 5132
TEST_TIME 9 5400
TEST_TIME 10 5755
TEST_TIME 11 5926
TEST_TIME 12 5917
TEST_TIME 13 5851
TEST_TIME 14 6296
TEST_TIME 15 6382
TEST_TIME 16 6389
TEST_TIME 17 6796
TEST_TIME 18 7366
TEST_TIME 19 7640
TEST_TIME 20 7663
TEST_TIME 21 7916
TEST_TIME 22 7901
TEST_TIME 23 7977
TEST_TIME 24 8015
TEST_TIME 25 8724
TEST_TIME 26 9379
TEST_TIME 27 11456
TEST_TIME 28 11425
TEST_TIME 29 11481
TEST_TIME 30 11259
TEST_TIME 31 10898
TEST_TIME 32 10624
TEST_TIME 33 10441
TEST_TIME 34 10252
TEST_TIME 35 10231
TEST_TIME 36 10312
TEST_TIME 37 10215
TEST_TIME 38 10121
TEST_TIME 39 10031
TEST_TIME 40 9976
TEST_TIME 41 9839
TEST_TIME 42 9801
TEST_TIME 43 9752
TEST_TIME 44 9589
TEST_TIME 45 9559
TEST_TIME 46 9692
TEST_TIME 47 9615
TEST_TIME 48 9556
TEST_TIME 49 9650
TEST_TIME 50 9702
TEST_TIME 51 9688
TEST_TIME 52 9626
TEST_TIME 53 9634
TEST_TIME 54 9659
TEST_TIME 55 9718
TEST_TIME 56 9721
TEST_TIME 57 9860
TEST_TIME 58 9697
TEST_TIME 59 9538
TEST_TIME 60 9555
TEST_TIME 61 9610
TEST_TIME 62 9518
TEST_TIME 63 9460
TEST_TIME 64 9418
TEST_TIME 65 9367
TEST_TIME 66 9329
TEST_TIME 67 9317
TEST_TIME 68 9304
TEST_TIME 69 9305
TEST_TIME 70 9262
TEST_TIME 71 9230
TEST_TIME 72 9252
TEST_TIME 73 9357
TEST_TIME 74 9363
TEST_TIME 75 9360
TEST_TIME 76 9409
TEST_TIME 77 9476
TEST_TIME 78 9549
TEST_TIME 79 9430
TEST_TIME 80 9333
TEST_TIME 81 9294
TEST_TIME 82 9214
TEST_TIME 83 9158
TEST_TIME 84 9193
TEST_TIME 85 9171
TEST_TIME 86 9141
TEST_TIME 87 9110
TEST_TIME 88 9201
TEST_TIME 89 9174
TEST_TIME 90 9186
TEST_TIME 91 9149
TEST_TIME 92 9220
TEST_TIME 93 9261
TEST_TIME 94 9210
TEST_TIME 95 9269
TEST_TIME 96 9361
TEST_TIME 97 9315
TEST_TIME 98 9306
TEST_TIME 99 9241
TEST_TIME 100 9224
TEST_TIME 101 9173
TEST_TIME 102 9102
TEST_TIME 103 9062
TEST_TIME 104 9131
TEST_TIME 105 9114
TEST_TIME 106 9101
TEST_TIME 107 9076
TEST_TIME 108 9088
TEST_TIME 109 9079
TEST_TIME 110 9122
TEST_TIME 111 9163
TEST_TIME 112 9168
TEST_TIME 113 9144
TEST_TIME 114 9140
TEST_TIME 115 9165
TEST_TIME 116 9129
TEST_TIME 117 9058
TEST_TIME 118 9097
TEST_TIME 119 9051
TEST_TIME 120 8981

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-08-2021 04:39:13 to 07-08-2021 04:39:16.

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 1502167 1502167 0 0.00 0 42973 8 0 0
doc_list_biud 1502164 1502164 0 0.00 0 58690 37 0 0
sp_add_invoice_to_stock 5024 4886 138 2.74 37 200035 21273 0 138
sp_cancel_adding_invoice 5454 5454 0 0.00 4 57937 969 0 0
sp_cancel_client_order 1740 1740 0 0.00 4 19455 392 0 0
sp_cancel_customer_reserve 532892 532892 0 0.00 1 52531 82 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 3 2790 75 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 3 12685 141 0 0
sp_cancel_supplier_invoice 6037 6028 9 0.14 3 28110 1171 0 9
sp_cancel_supplier_order 376 376 0 0.00 16 1479060 148295 0 0
sp_cancel_write_off 9895 9895 0 0.00 1 53134 160 0 0
sp_client_order 20100 20100 0 0.00 4 59328 445 0 0
sp_customer_reserve 460306 460306 0 0.00 0 75926 208 0 0
sp_fill_shopping_cart 30982 30982 0 0.00 1 14456 46 0 0
sp_fill_shopping_cart_clo_res 460299 460299 0 0.00 0 32435 17 0 0
sp_get_clo_for_invoice 4879 4879 0 0.00 0 38004 358 0 0
sp_kill_cost_storno 16410 16410 0 0.00 0 23525 68 0 0
sp_kill_qstorno_ret_qs2qd 1082083 1082083 0 0.00 0 53025 45 0 0
sp_kill_qty_storno 571082 571082 0 0.00 0 58690 96 0 0
sp_lock_selected_doc 20306 20306 0 0.00 0 12115 7 0 0
sp_make_cost_storno 27195 27195 0 0.00 0 32946 53 0 0
sp_multiply_rows_for_pdistr 27195 27195 0 0.00 0 42963 39 0 0
sp_multiply_rows_for_qdistr 20100 20100 0 0.00 0 32997 209 0 0
sp_pay_from_customer 6783 6783 0 0.00 4 42977 126 0 0
sp_pay_to_supplier 5715 5715 0 0.00 5 23490 137 0 0
sp_payment_common 12498 12498 0 0.00 1 6293 21 0 0
sp_qd_handle_on_cancel_clo 1740 1740 0 0.00 0 19450 352 0 0
sp_qd_handle_on_invoice_upd_sts 10333 10333 0 0.00 0 51623 413 0 0
sp_qd_handle_on_reserve_upd_sts 9818 9818 0 0.00 0 58689 115 0 0
sp_reserve_write_off 9818 9818 0 0.00 7 59284 237 0 0
sp_supplier_invoice 5409 5409 0 0.00 6 93246 3416 0 0
sp_supplier_order 5474 5474 0 0.00 5 51866 1457 0 0
srv_aggregate_perf_data 113 113 0 0.00 2509 100990 14123 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1767 141986 44224 0 0
srv_find_qd_qs_mism 1029847 1029847 0 0.00 0 24379 8 0 0
srv_make_invnt_saldo 494 494 0 0.00 61 116915 3388 0 0
srv_make_money_saldo 721 721 0 0.00 1 12627 589 0 0
srv_recalc_idx_stat 4 4 0 0.00 147364 435578 288850 0 0
t$perf-abend:sp_add_invoice_to_stock 138 138 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 4886 4886 0 0.00 0 12112 153 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 0 3711 83 0 0
t$perf-norm:sp_cancel_client_order 1740 1740 0 0.00 0 4171 18 0 0
t$perf-norm:sp_cancel_customer_reserve 675 675 0 0.00 0 1403 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 1037 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 270 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 1520 20 0 0
t$perf-norm:sp_cancel_supplier_order 376 376 0 0.00 0 26079 1881 0 0
t$perf-norm:sp_cancel_write_off 973 973 0 0.00 0 1336 5 0 0
t$perf-norm:sp_client_order 20100 20100 0 0.00 0 13979 18 0 0
t$perf-norm:sp_customer_reserve 941 941 0 0.00 0 1794 6 0 0
t$perf-norm:sp_pay_from_customer 6783 6783 0 0.00 0 3738 7 0 0
t$perf-norm:sp_pay_to_supplier 5715 5715 0 0.00 0 7686 11 0 0
t$perf-norm:sp_reserve_write_off 9818 9818 0 0.00 0 6458 9 0 0
t$perf-norm:sp_supplier_invoice 5409 5409 0 0.00 0 9104 17 0 0
t$perf-norm:sp_supplier_order 5474 5474 0 0.00 0 4359 18 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 394 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 517 10 0 0
t$perf-norm:srv_make_invnt_saldo 494 494 0 0.00 0 989 9 0 0
t$perf-norm:srv_make_money_saldo 721 721 0 0.00 0 377 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10024 9748 276 2.75 0 9924 18 0 276
v_all_customers 36514 36514 0 0.00 0 73 1 0 0
v_all_suppliers 30502 30502 0 0.00 0 17 1 0 0
v_all_wares 40200 40200 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 376 6 0 0
v_cancel_client_order 3480 3480 0 0.00 0 3691 9 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 2351 8 0 0
v_cancel_customer_reserve 1350 1350 0 0.00 0 1927 7 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 1593 10 0 0
v_cancel_supplier_invoice 808 790 18 2.22 0 3429 24 0 18
v_cancel_supplier_order 752 752 0 0.00 0 3344 10 0 0
v_cancel_write_off 1946 1946 0 0.00 0 5468 17 0 0
v_max_id_clo_ord 5473 5473 0 0.00 0 924 1 0 0
v_max_id_clo_res 941 941 0 0.00 0 8663 19 0 0
v_max_id_ord_sup 5409 5409 0 0.00 0 3633 5 0 0
v_max_non_paid_invoice 1346 1346 0 0.00 0 410 2 0 0
v_max_non_paid_realizn 3677 3677 0 0.00 0 1400 3 0 0
v_min_id_clo_ord 5473 5473 0 0.00 0 2972 6 0 0
v_min_id_clo_res 941 941 0 0.00 0 9109 25 0 0
v_min_id_ord_sup 5409 5409 0 0.00 0 5483 8 0 0
v_min_non_paid_invoice 5715 5715 0 0.00 0 4895 12 0 0
v_min_non_paid_realizn 6783 6783 0 0.00 0 6290 17 0 0
v_our_firm 9898 9898 0 0.00 0 14 1 0 0
v_reserve_write_off 19636 19636 0 0.00 0 3987 5 0 0
x_lock_depdocs_on_canc_invoice 11087 11087 0 0.00 0 9777 116 0 0
x_lock_depdocs_on_canc_sup_ord 376 376 0 0.00 0 39147 609 0 0
x_make_qstorno_1000_1200 5474 5474 0 0.00 2 51825 1379 0 0
x_make_qstorno_1200_2000 5409 5409 0 0.00 3 93226 3234 0 0
x_make_qstorno_2100_3300 460306 460306 0 0.00 0 75919 188 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-08-2021 04:39:17 to 07-08-2021 04:39:21.

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
02:07:09 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:10 29322 8192000000 9726197328 10027814912 806730144 8.964 100 84 168 0 834884640 7645712 20508784
02:09:46 14255 8192000000 9773490048 10082168832 847319440 9.373 100 89 175 2 881217760 13444368 20453952
02:11:03 23402 8192000000 9807851904 10129063936 874606400 9.646 100 85 170 0 916069824 22683648 18779776
02:12:30 10546 8192000000 9880533056 10210660352 920301568 10.099 100 88 176 1 996766992 57099696 19365728
02:13:46 6826 8192000000 9892789568 10238795776 944726480 10.339 100 73 144 1 1008947184 48567280 15653424
02:14:56 5386 8192000000 9952785760 10300743680 984629376 10.729 100 81 163 0 1063788080 62579696 16579008
02:16:04 4094 8192000000 9986002960 10349178880 1021913584 11.090 100 77 154 2 1100281968 62686800 15681584
02:17:10 2019 8192000000 10016764736 10385272832 1043165616 11.295 100 84 168 0 1129659840 69550208 16944016
02:18:13 1795 8192000000 10029038384 10400509952 1056258704 11.421 100 84 168 1 1141736064 68418944 17058416
02:19:16 4005 8192000000 10041757904 10420551680 1064753152 11.502 100 76 154 0 1158284720 77589280 15942288
02:20:20 7273 8192000000 10003120448 10402095104 1073971040 11.590 100 38 75 2 1132532352 51217232 7344080
02:21:31 9744 8192000000 10011879488 10417602560 1085539440 11.700 100 26 53 1 1124286784 33013312 5734032
02:22:41 19115 8192000000 10008538512 10396909568 1091562352 11.758 100 70 144 1 1117871232 10000432 16308448
02:24:01 5831 8192000000 10029368784 10430218240 1096611632 11.805 100 38 74 1 1125056528 20955568 7489328
02:25:08 5771 8192000000 10018969824 10421698560 1101220864 11.849 100 56 112 0 1135204112 21776144 12207104
02:26:16 4402 8192000000 10012805856 10410676224 1096111984 11.801 100 33 66 0 1123508928 20383792 7013152
02:27:22 27534 8192000000 10007277152 10412351488 1101298736 11.850 100 25 49 1 1128320832 21276560 5745536
02:28:51 7497 8192000000 9995695744 10398982144 1054188656 11.401 97 44 84 3 1094022192 30092672 9740864
02:30:02 6371 8192000000 9959404480 10337218560 993195344 10.813 95 48 94 1 1022260384 18761008 10304032
02:31:12 53137 8192000000 9911966928 10296680448 926021712 10.155 90 38 74 2 948134512 13202352 8910448
02:33:12 6055 8192000000 9933920928 10319572992 1020045504 11.072 99 23 46 1 1044105504 18904480 5155520
02:34:23 8499 8192000000 9959810304 10361958400 1048764480 11.349 100 28 56 0 1073018128 18018432 6235216
02:35:34 4991 8192000000 9983966640 10369961984 1060973136 11.466 100 49 97 1 1089809920 18245552 10591232
02:36:39 39156 8192000000 9987590944 10385276928 1071987920 11.571 100 36 70 1 1104963168 25172912 7802336
02:38:24 17670 8192000000 10051132672 10447441920 1097065680 11.810 100 55 110 0 1147914416 39902064 10946672
02:39:51 32497 8192000000 10098743632 10499661824 1125234800 12.076 100 41 80 1 1191352272 57192304 8925168
02:41:31 30031 8192000000 10149306336 10560139264 1140706080 12.222 100 38 77 2 1249205120 100163616 8335424
02:43:06 29782 8192000000 10185843984 10595639296 1151661408 12.325 100 51 102 0 1282344208 119712384 10970416
02:44:38 10510 8192000000 10227757728 10640166912 1163815856 12.439 100 48 96 0 1337863168 163005088 11042224
02:45:57 16249 8192000000 10269852608 10690154496 1173390880 12.529 100 45 89 0 1369668912 185990416 10287616
02:47:17 2751 8192000000 10324708192 10730098688 1181400880 12.603 100 80 160 1 1426903440 227637856 17864704
02:48:25 10906 8192000000 10326340528 10742751232 1189485728 12.679 100 67 134 0 1428398032 224142064 14770240
02:49:47 3224 8192000000 10394537872 10801405952 1202467056 12.799 100 81 163 1 1497240960 276447152 18326752
02:51:00 1765 8192000000 10415626064 10833494016 1210797008 12.876 100 83 165 1 1519215536 289207904 19210624
02:52:08 3350 8192000000 10477061600 10892201984 1211246720 12.881 100 86 171 0 1578479376 347003664 20228992
02:53:18 3148 8192000000 10540095168 10957033472 1211161904 12.880 100 89 174 3 1642041760 409697760 21182096
02:54:27 2855 8192000000 10572264720 11000147968 1218596336 12.949 100 78 156 0 1662669952 425503776 18569840
02:55:36 2398 8192000000 10583355664 11010363392 1229732624 13.052 100 82 164 0 1683263392 433637840 19892928
02:56:44 10160 8192000000 10531264560 10973028352 1194707520 12.727 99 47 94 0 1646338960 439846096 11785344
02:58:07 7153 8192000000 10610063776 11045703680 1220265568 12.964 100 57 115 0 1713719872 479051616 14402688
02:59:16 17061 8192000000 10595641792 11062321152 1186717472 12.653 99 42 84 0 1697429200 499555312 11156416
03:00:42 13653 8192000000 10662148352 11143192576 1162273024 12.425 99 46 92 1 1759639984 584916976 12449984
03:01:58 20602 8192000000 10711056832 11183894528 1152351328 12.332 100 35 70 0 1825178640 662319856 10507456
03:03:46 13461 8192000000 10454001728 10913959936 1098604752 11.824 98 29 58 1 1548275648 440788736 8882160
03:05:31 97419 8192000000 10061288624 10549305344 1094991680 11.790 98 16 32 0 1136878800 38368368 3518752
03:09:25 141986 8192000000 10105489808 10546352128 1076113840 11.610 95 32 60 4 1157503824 74982848 6407136
03:12:57 3349 8192000000 10080171536 10497187840 1111862576 11.950 100 70 135 3 1169982992 43218448 14901968
03:14:02 33165 8192000000 10070807072 10496655360 1108499904 11.918 99 54 100 2 1151145456 30641792 12003760
03:15:36 9529 8192000000 10066027312 10497449984 1106153760 11.896 99 35 70 1 1154846048 41904512 6787776
03:16:49 43546 8192000000 10080236512 10546196480 1131805232 12.138 100 17 34 0 1196977152 61527424 3644496
03:19:44 35692 8192000000 10081098944 10530385920 1093238480 11.773 98 15 30 0 1164829376 68158416 3432480
03:21:28 84114 8192000000 10081596288 10548391936 1111430144 11.946 99 27 52 0 1202810992 85499632 5881216
03:23:54 57925 8192000000 10063132480 10501959680 1111349520 11.945 99 13 26 0 1141269536 27396736 2523280
03:25:56 51304 8192000000 10108407328 10574876672 1135001152 12.168 100 27 54 0 1201048080 60275696 5771232
03:27:56 50033 8192000000 10076182528 10523549696 1120807616 12.035 99 11 22 0 1154005264 30966192 2231456
03:30:02 30559 8192000000 10151545184 10598637568 1135172576 12.170 99 29 58 0 1216408384 75411344 5824464
03:31:33 38185 8192000000 10120789152 10566242304 1123613088 12.061 98 18 36 0 1189223248 61111936 4498224
03:33:12 78960 8192000000 10145951472 10631528448 1123490480 12.060 98 12 24 0 1216748592 90236480 3021632
03:35:31 109671 8192000000 10135587520 10576142336 1133855520 12.158 99 29 57 1 1223953728 83739104 6359104
03:38:26 50170 8192000000 10037523360 10508767232 1080719168 11.654 95 12 24 0 1118276016 35297584 2259264
03:40:23 23731 8192000000 10045623232 10499420160 1130402032 12.125 100 6 12 1 1153599952 22344096 853824
03:41:51 49457 8192000000 10076308624 10526330880 1133149488 12.151 99 9 18 0 1167305024 32522176 1633360
03:43:43 66332 8192000000 10099327888 10543742976 1134229408 12.161 99 26 52 0 1191655056 52040512 5385136
03:45:52 42723 8192000000 10109629008 10548707328 1143815392 12.251 99 29 59 1 1231507376 81443872 6248112
03:47:40 70516 8192000000 10132166080 10570264576 1067497040 11.528 94 17 34 2 1177797776 106729824 3570912
03:49:52 22697 8192000000 10099865664 10567438336 1097532080 11.814 97 44 86 1 1168849584 62593056 8724448
03:51:22 22692 8192000000 10039533248 10462982144 1078190208 11.630 96 38 75 4 1104403568 17576208 8637152
03:52:45 83379 8192000000 10084777648 10553921536 1047492304 11.337 93 24 48 1 1120439200 68274032 4672864
03:55:18 119897 8192000000 10027319584 10472988672 1101034992 11.847 99 16 32 0 1136132192 31990784 3106416
03:58:27 73973 8192000000 10086264048 10537197568 1082476240 11.671 97 11 22 1 1167734272 83074672 2183360
04:00:46 67659 8192000000 10034263760 10523332608 1066469936 11.518 97 14 28 0 1157699056 87624272 3604848
04:03:46 92950 8192000000 10060479824 10567540736 1100009520 11.838 99 19 37 1 1190335360 85999936 4325904
04:06:23 62127 8192000000 10080767008 10503626752 1086192960 11.706 98 34 68 1 1138861056 45425504 7242592
04:08:29 47496 8192000000 10109954736 10532753408 1094819280 11.788 98 40 80 0 1175373664 72374336 8180048
04:10:21 53571 8192000000 10057100080 10505519104 1103589024 11.872 99 20 40 0 1155621680 48080032 3952624
04:12:15 56661 8192000000 10123018176 10646568960 1115450864 11.984 99 31 62 1 1215705504 93933856 6320784
04:14:35 20208 8192000000 10113865024 10534535168 1133613120 12.155 100 48 95 2 1177161712 34439728 9108864
04:15:56 82957 8192000000 10103216352 10566496256 1138966528 12.206 100 39 78 0 1210462896 63871504 7624864
04:18:20 46621 8192000000 10072570160 10532405248 1121126560 12.038 98 7 14 0 1163013184 40672336 1214288
04:20:51 47555 8192000000 10102725328 10616487936 1133634848 12.156 99 13 26 0 1235576096 99086208 2855040
04:22:39 52104 8192000000 10089027696 10533670912 1145453792 12.267 100 19 33 2 1176324464 27406128 3464544
04:24:34 36453 8192000000 10122824576 10577719296 1137085360 12.188 99 27 54 1 1200216896 57707296 5424240
04:26:12 35682 8192000000 10138142144 10569338880 1120864720 12.035 97 38 75 0 1200747024 71815616 8066688
04:27:59 122472 8192000000 10061681072 10511470592 1115463008 11.984 98 22 44 0 1197839552 77273376 5103168
04:31:02 20611 8192000000 10084691312 10514722816 1123001040 12.055 99 46 92 1 1165588080 33497232 9089808
04:32:23 78068 8192000000 10083025088 10579406848 1102984448 11.866 97 24 48 0 1163565840 55691008 4890384
04:35:22 127626 8192000000 9551731952 9957740544 641372480 7.260 58 4 8 0 677014672 35015616 626576

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 138 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 07-08-2021 04:39:39 to 07-08-2021 04:39:41.

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          228869
MON$OLDEST_ACTIVE               228870
MON$OLDEST_SNAPSHOT             228870
MON$NEXT_TRANSACTION            228870
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                       2041440
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             782
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2563 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2563 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2563 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2563 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 ---
:41: 
:42: mscs-test-161.budzdorov.ru    Sat Aug  7 04:37:42 2021
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:44: 
--- end of diff output ---

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

07.08.21 04:39:42. 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-07 04:39:45 No severe PSQL-related problems occured
07.08.21 04:39:46. End of report.