Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.08.2021 01: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 16225668 kB
MemAvailable 30985568 kB
Buffers 0 kB
Cached 15406892 kB
SwapCached 2592 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-10 01:37:47
DTS_END                         2021-08-10 01:37:47.6490
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/55268
TRN_ID                          248870
ATT_ID                          666
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: 9176.37 26061 1101164
customer order: creation 179.55 356 21546
customer order: refuse 15.70 362 1884
order to supplier: creation 47.13 1328 5655
order to supplier: removal 3.32 107340 398
invoice (draft): creation 47.68 3384 5722
invoice (draft): removal 51.73 806 6208
invoice accept: apply 42.53 19068 5104
invoice accept: cancel 46.53 862 5583
customer reserve: creation 3893.41 189 467209
customer reserve: removal 4538.12 61 544574
realization accept: apply 87.18 200 10461
realization accept: cancel 85.98 127 10317
payment from customer: creation 63.81 139 7657
payment from customer: removal 6.18 91 742
payment to supplier: creation 51.11 133 6133
payment to supplier: removal 3.47 99 416
service: total inventory turnovers 4.84 1240 581
service: total monetary turnovers 7.18 30 862
service: aggregate perf. data 0.90 19997 108
service: refresh index statistics 0.03 365402 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 10-08-2021 01:38:45 to 10-08-2021 01:38:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5806
TEST_TIME 2 4994
TEST_TIME 3 4926
TEST_TIME 4 4579
TEST_TIME 5 4570
TEST_TIME 6 4913
TEST_TIME 7 5177
TEST_TIME 8 5400
TEST_TIME 9 6095
TEST_TIME 10 6168
TEST_TIME 11 6192
TEST_TIME 12 6081
TEST_TIME 13 6450
TEST_TIME 14 6452
TEST_TIME 15 6622
TEST_TIME 16 6657
TEST_TIME 17 6806
TEST_TIME 18 7046
TEST_TIME 19 7489
TEST_TIME 20 7788
TEST_TIME 21 7929
TEST_TIME 22 7895
TEST_TIME 23 8206
TEST_TIME 24 8477
TEST_TIME 25 8539
TEST_TIME 26 8472
TEST_TIME 27 9087
TEST_TIME 28 9033
TEST_TIME 29 9272
TEST_TIME 30 9315
TEST_TIME 31 10370
TEST_TIME 32 10392
TEST_TIME 33 10190
TEST_TIME 34 9939
TEST_TIME 35 10112
TEST_TIME 36 9914
TEST_TIME 37 9856
TEST_TIME 38 9803
TEST_TIME 39 9774
TEST_TIME 40 9767
TEST_TIME 41 9601
TEST_TIME 42 9803
TEST_TIME 43 9730
TEST_TIME 44 9616
TEST_TIME 45 9642
TEST_TIME 46 9718
TEST_TIME 47 9641
TEST_TIME 48 9653
TEST_TIME 49 9565
TEST_TIME 50 9640
TEST_TIME 51 9547
TEST_TIME 52 9414
TEST_TIME 53 9310
TEST_TIME 54 9341
TEST_TIME 55 9299
TEST_TIME 56 9211
TEST_TIME 57 9187
TEST_TIME 58 9191
TEST_TIME 59 9181
TEST_TIME 60 9257
TEST_TIME 61 9153
TEST_TIME 62 9117
TEST_TIME 63 8994
TEST_TIME 64 9055
TEST_TIME 65 9027
TEST_TIME 66 9107
TEST_TIME 67 9134
TEST_TIME 68 9190
TEST_TIME 69 9169
TEST_TIME 70 9139
TEST_TIME 71 9148
TEST_TIME 72 9178
TEST_TIME 73 9230
TEST_TIME 74 9253
TEST_TIME 75 9268
TEST_TIME 76 9282
TEST_TIME 77 9314
TEST_TIME 78 9433
TEST_TIME 79 9420
TEST_TIME 80 9418
TEST_TIME 81 9325
TEST_TIME 82 9283
TEST_TIME 83 9270
TEST_TIME 84 9288
TEST_TIME 85 9237
TEST_TIME 86 9204
TEST_TIME 87 9208
TEST_TIME 88 9158
TEST_TIME 89 9145
TEST_TIME 90 9098
TEST_TIME 91 9064
TEST_TIME 92 9063
TEST_TIME 93 9069
TEST_TIME 94 9093
TEST_TIME 95 9078
TEST_TIME 96 9156
TEST_TIME 97 9145
TEST_TIME 98 9121
TEST_TIME 99 9132
TEST_TIME 100 9198
TEST_TIME 101 9196
TEST_TIME 102 9238
TEST_TIME 103 9242
TEST_TIME 104 9309
TEST_TIME 105 9278
TEST_TIME 106 9293
TEST_TIME 107 9224
TEST_TIME 108 9242
TEST_TIME 109 9203
TEST_TIME 110 9153
TEST_TIME 111 9128
TEST_TIME 112 9093
TEST_TIME 113 9079
TEST_TIME 114 9089
TEST_TIME 115 9107
TEST_TIME 116 9106
TEST_TIME 117 9143
TEST_TIME 118 9207
TEST_TIME 119 9189
TEST_TIME 120 9179

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-08-2021 01:38:49 to 10-08-2021 01:38:53.

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 1535080 1535080 0 0.00 0 32371 7 0 0
doc_list_biud 1535080 1535080 0 0.00 0 34568 28 0 0
sp_add_invoice_to_stock 5218 5104 114 2.18 23 193321 19068 0 114
sp_cancel_adding_invoice 5583 5583 0 0.00 6 71067 862 0 0
sp_cancel_client_order 1884 1884 0 0.00 5 10838 363 0 0
sp_cancel_customer_reserve 544574 544574 0 0.00 1 34572 62 0 0
sp_cancel_pay_from_customer 742 742 0 0.00 3 5373 92 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 3 7085 100 0 0
sp_cancel_supplier_invoice 6216 6208 8 0.12 2 31616 807 0 8
sp_cancel_supplier_order 398 398 0 0.00 8 918810 107341 0 0
sp_cancel_write_off 10317 10317 0 0.00 1 17761 128 0 0
sp_client_order 21546 21546 0 0.00 3 56271 356 0 0
sp_customer_reserve 467209 467209 0 0.00 0 97145 190 0 0
sp_fill_shopping_cart 32914 32914 0 0.00 1 13001 43 0 0
sp_fill_shopping_cart_clo_res 467200 467200 0 0.00 0 16872 14 0 0
sp_get_clo_for_invoice 5095 5095 0 0.00 0 23796 321 0 0
sp_kill_cost_storno 17058 17058 0 0.00 0 7386 66 0 0
sp_kill_qstorno_ret_qs2qd 1106071 1106071 0 0.00 0 34555 33 0 0
sp_kill_qty_storno 584518 584518 0 0.00 0 34568 72 0 0
sp_lock_selected_doc 21651 21651 0 0.00 0 7651 6 0 0
sp_make_cost_storno 29344 29344 0 0.00 0 15713 58 0 0
sp_multiply_rows_for_pdistr 29344 29344 0 0.00 0 9391 33 0 0
sp_multiply_rows_for_qdistr 21546 21546 0 0.00 0 14429 155 0 0
sp_pay_from_customer 7657 7657 0 0.00 4 17705 139 0 0
sp_pay_to_supplier 6133 6133 0 0.00 5 13337 134 0 0
sp_payment_common 13790 13790 0 0.00 1 12259 33 0 0
sp_qd_handle_on_cancel_clo 1884 1884 0 0.00 0 10824 334 0 0
sp_qd_handle_on_invoice_upd_sts 10676 10676 0 0.00 0 17216 356 0 0
sp_qd_handle_on_reserve_upd_sts 10461 10461 0 0.00 0 16281 66 0 0
sp_reserve_write_off 10461 10461 0 0.00 6 16354 201 0 0
sp_supplier_invoice 5722 5722 0 0.00 6 80341 3384 0 0
sp_supplier_order 5655 5655 0 0.00 6 34479 1329 0 0
srv_aggregate_perf_data 109 108 1 0.91 2627 169069 19997 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 1720 150081 45932 0 0
srv_find_qd_qs_mism 1052330 1052330 0 0.00 0 32370 6 0 0
srv_make_invnt_saldo 581 581 0 0.00 32 94245 1240 0 0
srv_make_money_saldo 862 862 0 0.00 1 2439 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 58746 611914 365402 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5104 5104 0 0.00 0 16944 168 0 0
t$perf-norm:sp_cancel_adding_invoice 439 439 0 0.00 1 4005 95 0 0
t$perf-norm:sp_cancel_client_order 1884 1884 0 0.00 0 1768 13 0 0
t$perf-norm:sp_cancel_customer_reserve 712 712 0 0.00 0 749 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 742 742 0 0.00 0 4041 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 551 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 451 451 0 0.00 0 1013 17 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 21494 1749 0 0
t$perf-norm:sp_cancel_write_off 1056 1056 0 0.00 0 2678 6 0 0
t$perf-norm:sp_client_order 21546 21546 0 0.00 0 16274 18 0 0
t$perf-norm:sp_customer_reserve 1023 1023 0 0.00 0 2840 9 0 0
t$perf-norm:sp_pay_from_customer 7657 7657 0 0.00 0 4965 10 0 0
t$perf-norm:sp_pay_to_supplier 6133 6133 0 0.00 0 5782 12 0 0
t$perf-norm:sp_reserve_write_off 10461 10461 0 0.00 0 7173 12 0 0
t$perf-norm:sp_supplier_invoice 5722 5722 0 0.00 0 3956 15 0 0
t$perf-norm:sp_supplier_order 5655 5655 0 0.00 0 6988 16 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 382 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 281 9 0 0
t$perf-norm:srv_make_invnt_saldo 581 581 0 0.00 0 1382 25 0 0
t$perf-norm:srv_make_money_saldo 862 862 0 0.00 0 3708 35 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 27 11 0 0
v_add_invoice_to_stock 10414 10186 228 2.18 0 5391 20 0 228
v_all_customers 39652 39652 0 0.00 0 24 1 0 0
v_all_suppliers 31646 31646 0 0.00 0 16 1 0 0
v_all_wares 43090 43090 0 0.00 0 38 1 0 0
v_cancel_adding_invoice 878 878 0 0.00 0 2696 10 0 0
v_cancel_client_order 3768 3768 0 0.00 0 1803 4 0 0
v_cancel_customer_prepayment 1484 1484 0 0.00 0 1123 4 0 0
v_cancel_customer_reserve 1424 1424 0 0.00 0 604 3 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 4962 10 0 0
v_cancel_supplier_invoice 918 902 16 1.74 0 2105 22 0 16
v_cancel_supplier_order 796 796 0 0.00 0 1793 7 0 0
v_cancel_write_off 2112 2112 0 0.00 0 455 12 0 0
v_max_id_clo_ord 5652 5652 0 0.00 0 761 2 0 0
v_max_id_clo_res 1023 1023 0 0.00 0 7970 24 0 0
v_max_id_ord_sup 5715 5715 0 0.00 0 4726 4 0 0
v_max_non_paid_invoice 1677 1677 0 0.00 0 212 3 0 0
v_max_non_paid_realizn 4010 4010 0 0.00 0 7339 10 0 0
v_min_id_clo_ord 5652 5652 0 0.00 0 5535 11 0 0
v_min_id_clo_res 1023 1023 0 0.00 0 4007 10 0 0
v_min_id_ord_sup 5715 5715 0 0.00 0 6995 7 0 0
v_min_non_paid_invoice 6133 6133 0 0.00 0 6107 19 0 0
v_min_non_paid_realizn 7657 7657 0 0.00 0 7333 18 0 0
v_our_firm 10732 10732 0 0.00 0 20 1 0 0
v_reserve_write_off 20920 20920 0 0.00 0 4084 6 0 0
x_lock_depdocs_on_canc_invoice 11340 11340 0 0.00 0 27373 140 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 26543 456 0 0
x_make_qstorno_1000_1200 5655 5655 0 0.00 2 34473 1238 0 0
x_make_qstorno_1200_2000 5722 5722 0 0.00 2 78493 3234 0 0
x_make_qstorno_2100_3300 467209 467209 0 0.00 0 97145 173 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-08-2021 01:38:54 to 10-08-2021 01:38:58.

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
23:07:51 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:52 23216 8192000000 10339001584 10662072320 1401422928 14.608 100 84 167 1 1434852304 5784064 27645312
23:10:19 28132 8192000000 10442684864 10773676032 1489732400 15.387 100 88 176 1 1525610576 7829248 28048928
23:11:58 18048 8192000000 10498281888 10841763840 1544561312 15.863 100 78 156 0 1578179680 9928768 23689600
23:13:20 12214 8192000000 10571949808 10927972352 1626177264 16.562 100 82 164 2 1662653328 12142176 24333888
23:14:36 7675 8192000000 10641007696 11007426560 1705168976 17.228 100 84 168 0 1741745008 11809904 24766128
23:15:47 5092 8192000000 10713734400 11091808256 1775070720 17.809 100 78 155 2 1809289440 12024160 22194560
23:16:56 3937 8192000000 10757962864 11137916928 1811169568 18.105 100 80 161 0 1847486976 13689680 22627728
23:18:01 2525 8192000000 10801211264 11187666944 1856927024 18.478 100 85 171 1 1893327488 12337248 24063216
23:19:05 3856 8192000000 10830050400 11222622208 1882610960 18.686 100 81 163 1 1917936336 13020400 22304976
23:20:11 12611 8192000000 10825163232 11231404032 1892965952 18.770 100 61 121 2 1919978848 9111664 17901232
23:21:24 7791 8192000000 10811492768 11234254848 1898927456 18.818 100 18 34 4 1910142624 5460832 5754336
23:22:33 9485 8192000000 10806293056 11240001536 1904108864 18.859 100 16 32 1 1915080208 5581136 5390208
23:23:47 11293 8192000000 10831208816 11264573440 1916409856 18.958 100 12 24 1 1925058352 5324848 3323648
23:25:01 62757 8192000000 10839510240 11274735616 1923581520 19.016 100 29 58 1 1936952384 5384560 7986304
23:27:08 7894 8192000000 10832413488 11271614464 1917979760 18.971 100 20 40 2 1934541088 10427056 6134272
23:28:17 5077 8192000000 10779434464 11211034624 1847928848 18.405 98 19 38 3 1859005120 5572400 5503872
23:29:25 74614 8192000000 10716780656 11137908736 1609972512 16.424 87 31 60 1 1627818112 9098416 8747184
23:31:42 6297 8192000000 10699317600 11108208640 1763604192 17.714 99 34 68 1 1779654496 6356416 9693888
23:32:49 5195 8192000000 10726870752 11133620224 1783505328 17.878 99 32 64 1 1798380800 5865200 9010272
23:33:58 16155 8192000000 10755516448 11172356096 1832884672 18.283 100 17 34 1 1843225744 5321328 5019744
23:35:14 20270 8192000000 10753516656 11179659264 1839766560 18.339 100 20 39 1 1850523904 5016224 5741120
23:36:35 10146 8192000000 10765422000 11197435904 1859740432 18.501 100 32 64 1 1875900304 6798032 9361840
23:37:45 12523 8192000000 10832838640 11248865280 1900048592 18.827 100 41 82 3 1917591872 5394176 12149104
23:39:03 27020 8192000000 10863619232 11280154624 1924407296 19.022 100 45 90 0 1946488352 8982352 13098704
23:40:34 74248 8192000000 10874893968 11309162496 1936849056 19.122 100 38 76 1 1955898960 7503824 11546080
23:43:01 9819 8192000000 10883789632 11315826688 1953123168 19.251 100 56 112 3 1983782560 14114784 16544608
23:44:16 17796 8192000000 10923627184 11346075648 1974595120 19.422 100 65 130 0 2008823280 15820240 18407920
23:45:38 12091 8192000000 10921072768 11356659712 1982776752 19.487 100 60 117 4 2018239264 18107632 17354880
23:47:06 4371 8192000000 10927896416 11360260096 1970420128 19.389 99 56 108 1 2003291712 16715792 16155792
23:48:17 6825 8192000000 10925391856 11357990912 1980311568 19.467 100 55 109 0 2013965808 17835552 15818688
23:49:32 12437 8192000000 10925144016 11359215616 1979928064 19.464 100 56 112 1 2017999856 20540096 17531696
23:50:49 3131 8192000000 10916225136 11341901824 1967253248 19.364 100 56 111 1 2003407120 19578096 16575776
23:51:55 3083 8192000000 10944183472 11362365440 1985333888 19.507 100 72 143 1 2027818048 21399840 21084320
23:53:01 5286 8192000000 10941099024 11359490048 1961603440 19.319 99 68 132 3 2003999936 22173888 20222608
23:54:13 7698 8192000000 10919614288 11333844992 1946642512 19.200 100 67 132 1 1996957904 30578224 19737168
23:55:35 2163 8192000000 10912547824 11323498496 1934196512 19.100 99 70 138 0 1993077232 37268640 21612080
23:56:41 3665 8192000000 10932553488 11340173312 1947762256 19.209 100 86 170 1 2008869664 35533504 25573904
23:57:54 1719 8192000000 10942803024 11352993792 1957931680 19.290 100 80 157 1 2020162112 38073808 24156624
23:59:04 2578 8192000000 10935397248 11343486976 1896935072 18.802 96 86 172 2 1963924272 40386528 26602672
00:00:12 4200 8192000000 10953728672 11378061312 1976365072 19.436 100 63 126 0 2041457824 44877488 20215264
00:01:24 3757 8192000000 10981799456 11401441280 1995033600 19.584 100 76 151 0 2067694800 48087232 24573968
00:02:35 9922 8192000000 10969156464 11400634368 1971160224 19.395 99 44 88 0 2033422416 47931056 14331136
00:03:54 14671 8192000000 10955279968 11394392064 1974600048 19.422 100 41 81 2 2040395648 51383728 14411872
00:05:11 10017 8192000000 10951604080 11383820288 1973710752 19.415 100 57 108 4 2028021648 37364416 16946480
00:06:23 24289 8192000000 10931787200 11397804032 1968745824 19.375 100 19 38 1 2022572624 47132304 6694496
00:07:51 40451 8192000000 10928680544 11386916864 1963641856 19.335 100 12 24 2 2021062176 52796608 4623712
00:09:33 63658 8192000000 10913324448 11384700928 1977950640 19.448 99 25 46 2 2006027248 21524944 6551664
00:12:18 32463 8192000000 10916367776 11396837376 1975042768 19.425 99 12 24 0 1996980672 18413392 3524512
00:13:52 56899 8192000000 10885782944 11348230144 1914062640 18.939 97 14 28 2 1930791616 12726880 4002096
00:15:56 58656 8192000000 10910780240 11373199360 1976906016 19.440 100 14 28 0 1993124208 12391008 3827184
00:18:05 56114 8192000000 10944759312 11381166080 1988577296 19.533 100 33 66 1 2014400400 14962000 10861104
00:20:02 92021 8192000000 10925392736 11391369216 1944175760 19.180 98 13 27 0 1958627536 11220688 3231088
00:22:43 114451 8192000000 10908780528 11377930240 1910740736 18.913 96 12 24 2 1931759696 18044912 2974048
00:25:43 42644 8192000000 10941522080 11390099456 1995182032 19.585 100 16 32 0 2006650752 7425344 4043376
00:27:29 127008 8192000000 10930658304 11393630208 1997570080 19.604 100 11 22 1 2013224944 13023904 2630960
00:31:18 150080 8192000000 10878445632 11322560512 1780413600 17.853 89 27 54 0 1802754736 14808464 7532672
00:34:50 36737 8192000000 10897279680 11357515776 1923809696 19.017 97 50 97 3 1950909776 12987088 14112992
00:36:33 100756 8192000000 10825708672 11293630464 1852136336 18.439 94 12 22 2 1870387904 15085696 3165872
00:39:22 105306 8192000000 10812352352 11268976640 1755741840 17.649 90 20 40 0 1772925808 11089280 6094688
00:42:08 57999 8192000000 10839640688 11301752832 1857644592 18.484 96 17 33 0 1872947760 10889888 4413280
00:44:14 52041 8192000000 10864703360 11338477568 1940210784 19.148 100 14 28 1 1953082192 8880368 3991040
00:46:08 34431 8192000000 10881717696 11318517760 1855924768 18.470 95 30 60 0 1873102336 8363616 8813952
00:47:49 53988 8192000000 10874372208 11336470528 1908962864 18.898 98 34 68 1 1932245616 13433408 9849344
00:49:44 27036 8192000000 10880122448 11346710528 1936616688 19.120 99 39 76 2 1959785728 11889104 11279936
00:51:11 39163 8192000000 10885014480 11349889024 1915765056 18.953 98 29 58 1 1936367792 12161824 8440912
00:52:53 25553 8192000000 10879198608 11336544256 1903220320 18.852 97 24 48 0 1922362464 12659552 6482592
00:54:20 121350 8192000000 10857095760 11326885888 1815693168 18.142 93 6 12 0 1833025552 15594880 1737504
00:57:21 106505 8192000000 10865803328 11328176128 1888902416 18.737 97 32 64 0 1907712176 9313264 9496496
01:00:15 44988 8192000000 10888732032 11354329088 1949695456 19.224 100 24 47 1 1971475888 14369104 7411328
01:02:01 47408 8192000000 10870938912 11353104384 1937658848 19.128 99 6 12 0 1946438368 6989696 1789824
01:03:49 56565 8192000000 10887260240 11366658048 1950022944 19.227 99 22 44 1 1968165664 11941792 6200928
01:05:50 46066 8192000000 10901818320 11383758848 1979532992 19.461 100 15 30 0 1997457344 13508432 4415920
01:07:36 49599 8192000000 10910970240 11384213504 1971947184 19.401 99 20 38 2 1986350720 9427056 4976480
01:09:26 67345 8192000000 10890556624 11364343808 1888117312 18.731 95 10 20 0 1900536544 9962896 2456336
01:11:36 36561 8192000000 10902668048 11370029056 1922510240 19.007 97 22 44 2 1936419808 7425392 6484176
01:13:13 76843 8192000000 10901528864 11369046016 1950224800 19.228 99 9 18 0 1962249520 9361152 2663568
01:15:35 37029 8192000000 10910769056 11370475520 1970462656 19.389 100 23 46 0 1990419472 13099344 6857472
01:17:14 46781 8192000000 10905246752 11376295936 1951846000 19.241 99 8 16 1 1970597312 16404512 2346800
01:19:06 94708 8192000000 10911908000 11391991808 1898627376 18.815 96 9 18 0 1917482768 16430224 2425168
01:21:41 77653 8192000000 10905352016 11372773376 1920831936 18.994 97 19 38 0 1939059408 13120576 5106896
01:23:59 122013 8192000000 10876776032 11334352896 1860477712 18.507 95 25 50 1 1881616544 13491024 7647808
01:27:05 30045 8192000000 10858160720 11324674048 1885402496 18.709 97 20 41 1 1902890304 11567664 5920144
01:28:36 51509 8192000000 10841528288 11303141376 1854341616 18.457 96 15 30 1 1872879328 14115792 4421920
01:30:32 49619 8192000000 10864160720 11328364544 1925500544 19.031 100 33 65 1 1961262560 25648528 10113488
01:32:35 58502 8192000000 10867425408 11328831488 1900307568 18.829 98 18 36 1 1921717424 16200560 5209296
01:34:38 103567 8192000000 10877299440 11344404480 1926569184 19.039 99 15 30 1 1946567024 15965616 4032224
01:37:34 20044 8192000000 9895546432 10259419136 1025462224 11.125 52 12 19 2 1034033360 5646720 2924416

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-08-2021 01:39:00 to 10-08-2021 01:39:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 114 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-08-2021 01:39:15 to 10-08-2021 01:39:17.

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          249112
MON$OLDEST_ACTIVE               249113
MON$OLDEST_SNAPSHOT             249113
MON$NEXT_TRANSACTION            249113
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                       2038240
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 ---
:473: 
:474: mscs-test-161.budzdorov.ru    Tue Aug 10 01:37:59 2021
:475:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:476: 
:477: 
:478: mscs-test-161.budzdorov.ru    Tue Aug 10 01:37:59 2021
:479:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:480: 
:481: 
:482: mscs-test-161.budzdorov.ru    Tue Aug 10 01:38:00 2021
:483:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:484: 
--- end of diff output ---

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

10.08.21 01:39:18. 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-10 01:39:21 No severe PSQL-related problems occured
10.08.21 01:39:22. End of report.