Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.08.2021 22: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 16224004 kB
MemAvailable 30990008 kB
Buffers 0 kB
Cached 15404640 kB
SwapCached 2568 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 3.0.8
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-08 22:37:41
DTS_END                         2021-08-08 22:37:41.7330
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(57:17)
IP                              ::1/37760
TRN_ID                          238725
ATT_ID                          699
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: 9259.41 21935 1111129
customer order: creation 168.38 395 20206
customer order: refuse 14.40 310 1728
order to supplier: creation 45.00 1371 5400
order to supplier: removal 3.58 101420 429
invoice (draft): creation 45.19 3497 5423
invoice (draft): removal 53.38 731 6406
invoice accept: apply 41.99 18485 5039
invoice accept: cancel 48.45 793 5814
customer reserve: creation 3854.93 183 462591
customer reserve: removal 4684.23 62 562107
realization accept: apply 81.68 164 9802
realization accept: cancel 89.42 109 10730
payment from customer: creation 59.83 88 7180
payment from customer: removal 5.61 71 673
payment to supplier: creation 47.88 111 5746
payment to supplier: removal 3.23 70 388
service: total inventory turnovers 4.70 1339 564
service: total monetary turnovers 6.84 26 821
service: aggregate perf. data 0.65 30913 78
service: refresh index statistics 0.03 278566 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-08-2021 22:38:48 to 08-08-2021 22:38:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1738
TEST_TIME 2 2704
TEST_TIME 3 3700
TEST_TIME 4 4332
TEST_TIME 5 4970
TEST_TIME 6 4734
TEST_TIME 7 4995
TEST_TIME 8 4910
TEST_TIME 9 4895
TEST_TIME 10 4986
TEST_TIME 11 5685
TEST_TIME 12 6474
TEST_TIME 13 6557
TEST_TIME 14 6444
TEST_TIME 15 6712
TEST_TIME 16 7678
TEST_TIME 17 7828
TEST_TIME 18 7728
TEST_TIME 19 7765
TEST_TIME 20 8389
TEST_TIME 21 8343
TEST_TIME 22 8505
TEST_TIME 23 9146
TEST_TIME 24 9285
TEST_TIME 25 9803
TEST_TIME 26 9838
TEST_TIME 27 9928
TEST_TIME 28 10639
TEST_TIME 29 11038
TEST_TIME 30 11125
TEST_TIME 31 10857
TEST_TIME 32 10685
TEST_TIME 33 10548
TEST_TIME 34 10472
TEST_TIME 35 10224
TEST_TIME 36 10112
TEST_TIME 37 10082
TEST_TIME 38 9943
TEST_TIME 39 9842
TEST_TIME 40 9859
TEST_TIME 41 9905
TEST_TIME 42 9836
TEST_TIME 43 9845
TEST_TIME 44 10098
TEST_TIME 45 9956
TEST_TIME 46 9780
TEST_TIME 47 9858
TEST_TIME 48 9836
TEST_TIME 49 9823
TEST_TIME 50 9776
TEST_TIME 51 9799
TEST_TIME 52 9799
TEST_TIME 53 9753
TEST_TIME 54 9867
TEST_TIME 55 9801
TEST_TIME 56 9968
TEST_TIME 57 9893
TEST_TIME 58 9873
TEST_TIME 59 9928
TEST_TIME 60 9953
TEST_TIME 61 9948
TEST_TIME 62 9868
TEST_TIME 63 9745
TEST_TIME 64 9704
TEST_TIME 65 9706
TEST_TIME 66 9626
TEST_TIME 67 9607
TEST_TIME 68 9552
TEST_TIME 69 9569
TEST_TIME 70 9492
TEST_TIME 71 9451
TEST_TIME 72 9518
TEST_TIME 73 9519
TEST_TIME 74 9571
TEST_TIME 75 9546
TEST_TIME 76 9525
TEST_TIME 77 9563
TEST_TIME 78 9582
TEST_TIME 79 9550
TEST_TIME 80 9533
TEST_TIME 81 9563
TEST_TIME 82 9565
TEST_TIME 83 9596
TEST_TIME 84 9633
TEST_TIME 85 9672
TEST_TIME 86 9624
TEST_TIME 87 9539
TEST_TIME 88 9619
TEST_TIME 89 9567
TEST_TIME 90 9536
TEST_TIME 91 9487
TEST_TIME 92 9510
TEST_TIME 93 9466
TEST_TIME 94 9450
TEST_TIME 95 9477
TEST_TIME 96 9465
TEST_TIME 97 9457
TEST_TIME 98 9469
TEST_TIME 99 9549
TEST_TIME 100 9489
TEST_TIME 101 9511
TEST_TIME 102 9556
TEST_TIME 103 9516
TEST_TIME 104 9560
TEST_TIME 105 9534
TEST_TIME 106 9560
TEST_TIME 107 9561
TEST_TIME 108 9514
TEST_TIME 109 9467
TEST_TIME 110 9413
TEST_TIME 111 9397
TEST_TIME 112 9384
TEST_TIME 113 9351
TEST_TIME 114 9333
TEST_TIME 115 9291
TEST_TIME 116 9291
TEST_TIME 117 9273
TEST_TIME 118 9259
TEST_TIME 119 9292
TEST_TIME 120 9263

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-08-2021 22:38:51 to 08-08-2021 22:38:55.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1539106 1539106 0 0.00 0 19924 6 0 0
doc_list_biud 1539106 1539106 0 0.00 0 46129 28 0 0
sp_add_invoice_to_stock 5183 5039 144 2.77 21 171256 18486 0 144
sp_cancel_adding_invoice 5814 5814 0 0.00 4 56713 793 0 0
sp_cancel_client_order 1728 1728 0 0.00 4 7080 311 0 0
sp_cancel_customer_reserve 562107 562107 0 0.00 1 46130 62 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 3 5229 71 0 0
sp_cancel_pay_to_supplier 388 388 0 0.00 3 4001 71 0 0
sp_cancel_supplier_invoice 6415 6406 9 0.14 2 20059 732 0 9
sp_cancel_supplier_order 429 429 0 0.00 5 981375 101420 0 0
sp_cancel_write_off 10730 10730 0 0.00 1 10572 109 0 0
sp_client_order 20206 20206 0 0.00 3 37672 395 0 0
sp_customer_reserve 462591 462591 0 0.00 0 61622 184 0 0
sp_fill_shopping_cart 31013 31013 0 0.00 1 19498 36 0 0
sp_fill_shopping_cart_clo_res 462589 462589 0 0.00 0 17679 13 0 0
sp_get_clo_for_invoice 5033 5033 0 0.00 0 27284 304 0 0
sp_kill_cost_storno 17605 17605 0 0.00 0 10670 55 0 0
sp_kill_qstorno_ret_qs2qd 1141779 1141779 0 0.00 0 46127 33 0 0
sp_kill_qty_storno 602047 602047 0 0.00 0 46129 71 0 0
sp_lock_selected_doc 20448 20448 0 0.00 0 7931 5 0 0
sp_make_cost_storno 27759 27759 0 0.00 0 12366 46 0 0
sp_multiply_rows_for_pdistr 27759 27759 0 0.00 0 5614 26 0 0
sp_multiply_rows_for_qdistr 20206 20206 0 0.00 0 37660 191 0 0
sp_pay_from_customer 7180 7180 0 0.00 4 10242 88 0 0
sp_pay_to_supplier 5746 5746 0 0.00 5 10180 112 0 0
sp_payment_common 12926 12926 0 0.00 1 7101 19 0 0
sp_qd_handle_on_cancel_clo 1728 1728 0 0.00 0 7071 287 0 0
sp_qd_handle_on_invoice_upd_sts 10845 10845 0 0.00 0 31269 309 0 0
sp_qd_handle_on_reserve_upd_sts 9802 9802 0 0.00 0 12792 57 0 0
sp_reserve_write_off 9802 9802 0 0.00 6 12803 164 0 0
sp_supplier_invoice 5423 5423 0 0.00 7 91173 3497 0 0
sp_supplier_order 5400 5400 0 0.00 5 28220 1372 0 0
srv_aggregate_perf_data 78 78 0 0.00 2836 578996 30914 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2090 130368 46658 0 0
srv_find_qd_qs_mism 1063591 1063591 0 0.00 0 19924 6 0 0
srv_make_invnt_saldo 564 564 0 0.00 39 96175 1339 0 0
srv_make_money_saldo 821 821 0 0.00 1 2184 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 139471 423370 278567 0 0
t$perf-abend:sp_add_invoice_to_stock 144 144 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 5039 5039 0 0.00 0 14042 167 0 0
t$perf-norm:sp_cancel_adding_invoice 390 390 0 0.00 1 8805 145 0 0
t$perf-norm:sp_cancel_client_order 1728 1728 0 0.00 0 1175 8 0 0
t$perf-norm:sp_cancel_customer_reserve 656 656 0 0.00 0 3218 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 3958 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 388 388 0 0.00 0 739 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 387 387 0 0.00 0 556 10 0 0
t$perf-norm:sp_cancel_supplier_order 429 429 0 0.00 0 23377 1294 0 0
t$perf-norm:sp_cancel_write_off 965 965 0 0.00 0 790 5 0 0
t$perf-norm:sp_client_order 20206 20206 0 0.00 0 7883 17 0 0
t$perf-norm:sp_customer_reserve 924 924 0 0.00 0 836 4 0 0
t$perf-norm:sp_pay_from_customer 7180 7180 0 0.00 0 4093 9 0 0
t$perf-norm:sp_pay_to_supplier 5746 5746 0 0.00 0 3439 9 0 0
t$perf-norm:sp_reserve_write_off 9802 9802 0 0.00 0 8553 9 0 0
t$perf-norm:sp_supplier_invoice 5423 5423 0 0.00 0 9825 19 0 0
t$perf-norm:sp_supplier_order 5400 5400 0 0.00 0 4371 18 0 0
t$perf-norm:srv_aggregate_perf_data 78 78 0 0.00 0 163 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 383 7 0 0
t$perf-norm:srv_make_invnt_saldo 564 564 0 0.00 0 811 17 0 0
t$perf-norm:srv_make_money_saldo 821 821 0 0.00 0 5231 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10348 10060 288 2.78 0 7459 21 0 288
v_all_customers 37026 37026 0 0.00 0 125 1 0 0
v_all_suppliers 29722 29722 0 0.00 0 125 1 0 0
v_all_wares 40410 40410 0 0.00 0 100 1 0 0
v_cancel_adding_invoice 780 780 0 0.00 0 2253 10 0 0
v_cancel_client_order 3456 3456 0 0.00 0 3571 6 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 601 3 0 0
v_cancel_customer_reserve 1312 1312 0 0.00 0 3425 6 0 0
v_cancel_payment_to_supplier 776 776 0 0.00 0 3966 9 0 0
v_cancel_supplier_invoice 792 774 18 2.27 0 1408 21 0 18
v_cancel_supplier_order 858 858 0 0.00 0 627 4 0 0
v_cancel_write_off 1930 1930 0 0.00 0 4555 16 0 0
v_max_id_clo_ord 5400 5400 0 0.00 0 5061 2 0 0
v_max_id_clo_res 924 924 0 0.00 0 2861 16 0 0
v_max_id_ord_sup 5407 5407 0 0.00 0 6657 6 0 0
v_max_non_paid_invoice 1692 1692 0 0.00 0 2202 4 0 0
v_max_non_paid_realizn 3718 3718 0 0.00 0 1211 3 0 0
v_min_id_clo_ord 5400 5400 0 0.00 0 2400 5 0 0
v_min_id_clo_res 924 924 0 0.00 0 8627 19 0 0
v_min_id_ord_sup 5407 5407 0 0.00 0 7782 7 0 0
v_min_non_paid_invoice 5746 5746 0 0.00 0 4502 10 0 0
v_min_non_paid_realizn 7180 7180 0 0.00 0 4984 11 0 0
v_our_firm 10308 10308 0 0.00 0 18 1 0 0
v_reserve_write_off 19604 19604 0 0.00 0 3964 7 0 0
x_lock_depdocs_on_canc_invoice 11833 11833 0 0.00 0 38520 136 0 0
x_lock_depdocs_on_canc_sup_ord 429 429 0 0.00 0 19253 426 0 0
x_make_qstorno_1000_1200 5400 5400 0 0.00 2 28208 1291 0 0
x_make_qstorno_1200_2000 5423 5423 0 0.00 4 89459 3370 0 0
x_make_qstorno_2100_3300 462591 462591 0 0.00 0 61620 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-08-2021 22:38:56 to 08-08-2021 22:39:00.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:07:45 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:46 23057 8192000000 10335593408 10651615232 1388642880 14.494 100 85 170 0 1423004896 5761600 28600416
20:10:12 18501 8192000000 10407948768 10732539904 1452936432 15.064 100 86 172 1 1488253920 6916832 28400656
20:11:34 11135 8192000000 10450355904 10787729408 1512498368 15.585 100 84 168 0 1548079856 8802000 26779488
20:12:47 12334 8192000000 10558098304 10917429248 1614973760 16.467 100 73 146 2 1645590000 8648784 21967456
20:14:03 9928 8192000000 10623305792 10987962368 1674212400 16.969 100 70 140 1 1705526032 11007216 20306416
20:15:17 4989 8192000000 10690385776 11062951936 1750233408 17.604 100 87 174 1 1788413440 13738928 24441104
20:16:23 3698 8192000000 10739244368 11118743552 1796792192 17.988 100 81 162 0 1832785936 12996192 22997552
20:17:28 2313 8192000000 10783322752 11167858688 1842153552 18.358 100 91 183 1 1881426368 12801168 26471648
20:18:31 1705 8192000000 10824851104 11212386304 1877968944 18.649 100 93 185 3 1917542880 14187104 25386832
20:19:33 8398 8192000000 10775621008 11207745536 1872832752 18.607 100 20 40 0 1884219824 5886848 5500224
20:20:42 9185 8192000000 10803515280 11228209152 1887737952 18.728 100 19 40 3 1898416976 5505264 5173760
20:21:53 13936 8192000000 10804234944 11240763392 1901864960 18.841 100 14 28 3 1911472560 5358608 4248992
20:23:09 48602 8192000000 10822335184 11260895232 1910558880 18.911 100 20 40 0 1921058384 4595152 5904352
20:24:58 5273 8192000000 10830739856 11268509696 1917756400 18.969 100 35 70 1 1934825376 6764336 10304640
20:26:04 9557 8192000000 10835066704 11269156864 1917927392 18.970 100 20 40 0 1929565360 5656176 5981792
20:27:16 6566 8192000000 10793249312 11228708864 1855607760 18.468 98 26 52 1 1869276192 6035136 7633296
20:28:26 14383 8192000000 10693401392 11113267200 1746490064 17.572 93 45 88 1 1765404864 5829952 13084848
20:29:42 5197 8192000000 10720285760 11138621440 1768295616 17.753 97 25 50 0 1781628704 5953008 7380080
20:30:53 8867 8192000000 10676119888 11092008960 1703420448 17.214 96 15 30 1 1713207840 5580880 4206512
20:32:07 25667 8192000000 10727602848 11148423168 1771723856 17.781 97 25 51 2 1783381376 4908560 6748960
20:34:07 8717 8192000000 10776688976 11199078400 1852618416 18.443 100 30 60 2 1866865808 5885648 8361744
20:35:19 8652 8192000000 10780134224 11212824576 1867986400 18.568 100 28 57 2 1882193888 5418256 8789232
20:36:28 15239 8192000000 10788212624 11226800128 1879379456 18.660 100 18 36 1 1890228048 5960352 4888240
20:37:45 82883 8192000000 10840365168 11269599232 1907678640 18.888 100 34 66 2 1922657296 5587184 9391472
20:40:11 18857 8192000000 10876799200 11304374272 1939160416 19.140 100 45 90 0 1962968816 11019680 12788720
20:41:33 45455 8192000000 10900060624 11334598656 1957551408 19.287 100 39 78 0 1984125648 15434960 11139280
20:43:35 11800 8192000000 10919711344 11350544384 1979507136 19.461 100 54 106 4 2010444080 15228432 15708512
20:44:51 19947 8192000000 10936133632 11376893952 1995324368 19.586 100 43 86 1 2029611104 21619792 12666944
20:46:18 8529 8192000000 10960132672 11387920384 1999602016 19.620 100 55 108 2 2042975712 27888960 15484736
20:47:31 4277 8192000000 10966188512 11397234688 2002663504 19.644 100 54 108 1 2052035312 32936320 16435488
20:48:46 3186 8192000000 10979282912 11396435968 1996782176 19.597 100 87 174 1 2063987360 40808000 26397184
20:49:55 2089 8192000000 10993712992 11421384704 2017708656 19.762 100 73 145 1 2076916272 37064528 22143088
20:51:03 3332 8192000000 10962416864 11387121664 1989405760 19.539 100 75 145 0 2053766320 41945888 22414672
20:52:14 2714 8192000000 10975542224 11396227072 1988873808 19.535 100 86 169 1 2064495616 48944176 26677632
20:53:22 3993 8192000000 10989827216 11404288000 1991456000 19.555 100 81 158 1 2065509504 49742272 24311232
20:54:30 8768 8192000000 10938883792 11376164864 1957516704 19.286 99 38 77 2 2006434032 35937008 12980320
20:55:47 10071 8192000000 10955325632 11390902272 1975454928 19.429 100 42 84 1 2036227440 46751024 14021488
20:57:09 14194 8192000000 10946021792 11371081728 1948379104 19.214 98 51 100 1 2019397760 54620912 16397744
20:58:33 18394 8192000000 10959529712 11400458240 1985349968 19.507 100 44 87 2 2044920592 45107424 14463200
21:00:08 14009 8192000000 10965805024 11403055104 1961318976 19.317 99 43 86 1 2032489952 56129728 15041248
21:01:27 10507 8192000000 10909661536 11348901888 1915125104 18.948 98 40 80 1 1963847792 34925424 13797264
21:02:39 25057 8192000000 10912335984 11366572032 1955874320 19.273 100 23 46 0 1993997888 30718656 7404912
21:04:41 26055 8192000000 10901252096 11356479488 1910717776 18.912 98 17 34 2 1954467696 37629296 6120624
21:06:15 67653 8192000000 10861151376 11344011264 1893812272 18.776 98 15 30 0 1920582368 22290880 4479216
21:08:26 4131 8192000000 10916429312 11333689344 1957560784 19.287 100 64 123 5 1980107616 4917472 17629360
21:09:38 121379 8192000000 10894712352 11344089088 1939842672 19.146 99 29 56 0 1959214768 11150000 8222096
21:12:40 39176 8192000000 10880505440 11346702336 1940827440 19.153 99 25 50 2 1960082352 12338704 6916208
21:14:21 31184 8192000000 10925631712 11395121152 1991351104 19.554 100 32 64 2 2021716832 20474032 9891696
21:16:05 65294 8192000000 10933461008 11410690048 1995508288 19.587 100 21 42 2 2028892384 26681552 6702544
21:18:24 93218 8192000000 10950160224 11420254208 2004765440 19.660 100 24 48 0 2030434992 18451072 7218480
21:21:20 67555 8192000000 10905915568 11377860608 1963724864 19.336 99 9 18 0 1980165392 13899392 2541136
21:23:30 64553 8192000000 10916363072 11403935744 1995415856 19.587 100 23 46 1 2020255616 17906288 6933472
21:25:38 45814 8192000000 10923928704 11410255872 1978470304 19.453 99 15 30 1 1997925760 15210144 4245312
21:27:24 35990 8192000000 10919740288 11396161536 1962532896 19.326 98 18 36 0 1977484912 9649392 5302624
21:29:10 48933 8192000000 10936095664 11406327808 1983653424 19.494 99 29 58 2 2005074448 12925456 8495568
21:30:59 130367 8192000000 10943764848 11419774976 1963241712 19.332 98 10 20 1 1982182848 16211232 2729904
21:34:11 53859 8192000000 10942285984 11408683008 1961771760 19.320 98 29 57 1 1983065248 13650112 7643376
21:36:05 52752 8192000000 10911218192 11389820928 1949225008 19.220 98 15 30 0 1965658896 12435488 3998400
21:38:09 53846 8192000000 10845832896 11300147200 1818781520 18.168 92 15 30 0 1830345248 7763632 3800096
21:40:03 71762 8192000000 10883552400 11345281024 1923674752 19.016 98 44 88 1 1946390144 9848944 12866448
21:42:19 36719 8192000000 10895502768 11369226240 1950574512 19.231 99 21 42 2 1966650544 10472464 5603568
21:44:02 39802 8192000000 10880814208 11355860992 1925607168 19.032 98 33 66 0 1948856080 14018336 9230576
21:45:44 83166 8192000000 10831712928 11305213952 1825685472 18.224 94 21 42 0 1848073296 15943184 6444640
21:48:07 63977 8192000000 10852640032 11339661312 1919206512 18.980 99 11 22 1 1935263872 12876368 3180992
21:50:25 59629 8192000000 10896606384 11341754368 1904748720 18.864 98 31 62 3 1924842976 10852752 9241504
21:52:25 62977 8192000000 10848263584 11318128640 1855883872 18.470 95 20 40 0 1872007376 10802192 5321312
21:55:05 64682 8192000000 10858848864 11335110656 1881403600 18.676 97 14 27 0 1897905728 12620640 3881488
21:57:13 40119 8192000000 10884895200 11337613312 1906344256 18.877 98 23 46 1 1922305728 9532560 6428912
21:58:57 72106 8192000000 10867349536 11327135744 1864660608 18.541 96 27 54 2 1888440272 16222912 7556752
22:01:12 56746 8192000000 10881032416 11347582976 1899353920 18.821 98 16 32 1 1925463136 21651632 4457584
22:03:11 96901 8192000000 10884901008 11342053376 1934697392 19.104 100 29 58 0 1951446544 7599744 9149408
22:06:01 39664 8192000000 10855382640 11343478784 1916773472 18.961 99 10 20 0 1933702288 14159184 2769632
22:07:42 63391 8192000000 10875563952 11360018432 1949237744 19.220 99 10 20 0 1965019472 13367104 2414624
22:09:46 31521 8192000000 10889480960 11366805504 1969435648 19.381 100 14 25 2 1985874032 12200096 4238288
22:12:19 41504 8192000000 10897458528 11380658176 1961444160 19.318 99 10 20 1 1976241648 11266304 3531184
22:14:03 56940 8192000000 10931382496 11400306688 1975534272 19.429 99 17 34 1 1992129296 11898448 4696576
22:16:07 65848 8192000000 10928970704 11410448384 1973168848 19.411 99 18 36 4 2000167808 21702896 5296064
22:18:16 46564 8192000000 10950519680 11424743424 2007226496 19.680 100 43 86 0 2038100256 19011344 11862416
22:20:09 50333 8192000000 10903112192 11361927168 1897614512 18.807 95 31 62 1 1917026048 10222320 9189216
22:22:00 50647 8192000000 10893229168 11362906112 1903216896 18.852 96 26 50 0 1922020640 11521696 7282048
22:23:52 90953 8192000000 10920198688 11394686976 1940812400 19.153 98 18 36 0 1959228464 13585312 4830752
22:26:28 95776 8192000000 10904377680 11386884096 1947788624 19.209 98 22 44 0 1962530560 8643280 6098656
22:29:04 51724 8192000000 10847086944 11329171456 1888015504 18.730 96 16 32 1 1905821392 13243648 4562240
22:31:42 42052 8192000000 10903724432 11360780288 1946616592 19.200 99 19 38 1 1959350688 6962176 5771920
22:33:25 96110 8192000000 10865112048 11352621056 1881988400 18.681 96 9 18 0 1891994944 7578736 2427808
22:36:01 57961 8192000000 10855072480 11340267520 1900125872 18.827 97 15 28 3 1912916880 9335984 3455024

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 08-08-2021 22:39:02 to 08-08-2021 22:39:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 144 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-08-2021 22:39:17 to 08-08-2021 22:39:19.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          237415
MON$OLDEST_ACTIVE               238853
MON$OLDEST_SNAPSHOT             238853
MON$NEXT_TRANSACTION            238853
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-03 16:41:55.3470
MON$PAGES                       2038112
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33487 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33487 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33487 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33487 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:400: 
:401: mscs-test-161.budzdorov.ru    Sun Aug  8 22:37:49 2021
:402:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:403: 
:404: 
:405: mscs-test-161.budzdorov.ru    Sun Aug  8 22:37:50 2021
:406:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:407: 
--- end of diff output ---

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

08.08.21 22:39:20. There are 3 logs in /var/tmp/logs-oltp30 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-08 22:39:23 No severe PSQL-related problems occured
08.08.21 22:39:24. End of report.