Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.09.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 15879668 kB
MemAvailable 30777412 kB
Buffers 20 kB
Cached 15760692 kB
SwapCached 696 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-09-20 04:37:07
DTS_END                         2021-09-20 04:37:07.5850
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/59128
TRN_ID                          231416
ATT_ID                          631
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: 9129.41 27551 1095529
customer order: creation 157.68 399 18922
customer order: refuse 14.13 318 1695
order to supplier: creation 43.78 1328 5253
order to supplier: removal 3.38 117229 405
invoice (draft): creation 43.49 3377 5219
invoice (draft): removal 51.79 898 6215
invoice accept: apply 40.70 17313 4884
invoice accept: cancel 46.67 827 5600
customer reserve: creation 3786.65 168 454398
customer reserve: removal 4666.08 68 559930
realization accept: apply 80.49 192 9659
realization accept: cancel 71.69 123 8603
payment from customer: creation 55.83 108 6699
payment from customer: removal 5.65 123 678
payment to supplier: creation 47.40 116 5688
payment to supplier: removal 3.33 89 400
service: total inventory turnovers 3.69 3303 443
service: total monetary turnovers 6.14 462 737
service: aggregate perf. data 0.81 21052 97
service: refresh index statistics 0.03 383532 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-09-2021 04:38:28 to 20-09-2021 04:38:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4337
TEST_TIME 2 3667
TEST_TIME 3 3606
TEST_TIME 4 3869
TEST_TIME 5 4202
TEST_TIME 6 4725
TEST_TIME 7 4757
TEST_TIME 8 4937
TEST_TIME 9 5025
TEST_TIME 10 5253
TEST_TIME 11 5459
TEST_TIME 12 5772
TEST_TIME 13 6007
TEST_TIME 14 6442
TEST_TIME 15 6973
TEST_TIME 16 6988
TEST_TIME 17 7435
TEST_TIME 18 7327
TEST_TIME 19 7618
TEST_TIME 20 8130
TEST_TIME 21 8146
TEST_TIME 22 8564
TEST_TIME 23 8562
TEST_TIME 24 8428
TEST_TIME 25 8912
TEST_TIME 26 10161
TEST_TIME 27 11063
TEST_TIME 28 10802
TEST_TIME 29 10752
TEST_TIME 30 10623
TEST_TIME 31 10431
TEST_TIME 32 10570
TEST_TIME 33 10419
TEST_TIME 34 10310
TEST_TIME 35 10302
TEST_TIME 36 10323
TEST_TIME 37 10264
TEST_TIME 38 10329
TEST_TIME 39 10382
TEST_TIME 40 10343
TEST_TIME 41 10247
TEST_TIME 42 10407
TEST_TIME 43 10323
TEST_TIME 44 10119
TEST_TIME 45 9924
TEST_TIME 46 9765
TEST_TIME 47 9730
TEST_TIME 48 9663
TEST_TIME 49 9621
TEST_TIME 50 9570
TEST_TIME 51 9570
TEST_TIME 52 9465
TEST_TIME 53 9402
TEST_TIME 54 9460
TEST_TIME 55 9327
TEST_TIME 56 9468
TEST_TIME 57 9491
TEST_TIME 58 9454
TEST_TIME 59 9373
TEST_TIME 60 9457
TEST_TIME 61 9489
TEST_TIME 62 9564
TEST_TIME 63 9657
TEST_TIME 64 9622
TEST_TIME 65 9684
TEST_TIME 66 9710
TEST_TIME 67 9656
TEST_TIME 68 9573
TEST_TIME 69 9509
TEST_TIME 70 9455
TEST_TIME 71 9438
TEST_TIME 72 9388
TEST_TIME 73 9374
TEST_TIME 74 9374
TEST_TIME 75 9314
TEST_TIME 76 9316
TEST_TIME 77 9315
TEST_TIME 78 9456
TEST_TIME 79 9436
TEST_TIME 80 9390
TEST_TIME 81 9428
TEST_TIME 82 9522
TEST_TIME 83 9529
TEST_TIME 84 9501
TEST_TIME 85 9574
TEST_TIME 86 9559
TEST_TIME 87 9614
TEST_TIME 88 9516
TEST_TIME 89 9454
TEST_TIME 90 9423
TEST_TIME 91 9430
TEST_TIME 92 9421
TEST_TIME 93 9376
TEST_TIME 94 9336
TEST_TIME 95 9308
TEST_TIME 96 9281
TEST_TIME 97 9280
TEST_TIME 98 9253
TEST_TIME 99 9268
TEST_TIME 100 9279
TEST_TIME 101 9299
TEST_TIME 102 9306
TEST_TIME 103 9347
TEST_TIME 104 9367
TEST_TIME 105 9341
TEST_TIME 106 9458
TEST_TIME 107 9428
TEST_TIME 108 9450
TEST_TIME 109 9407
TEST_TIME 110 9349
TEST_TIME 111 9339
TEST_TIME 112 9314
TEST_TIME 113 9324
TEST_TIME 114 9293
TEST_TIME 115 9270
TEST_TIME 116 9225
TEST_TIME 117 9243
TEST_TIME 118 9226
TEST_TIME 119 9203
TEST_TIME 120 9135

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-09-2021 04:38:32 to 20-09-2021 04:38:35.

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 1515503 1515503 0 0.00 0 21771 6 0 0
doc_list_biud 1515499 1515499 0 0.00 0 36644 31 0 0
sp_add_invoice_to_stock 4954 4884 70 1.41 53 228761 17314 0 70
sp_cancel_adding_invoice 5600 5600 0 0.00 4 62290 828 0 0
sp_cancel_client_order 1695 1695 0 0.00 4 9508 319 0 0
sp_cancel_customer_reserve 559930 559930 0 0.00 1 27248 68 0 0
sp_cancel_pay_from_customer 678 678 0 0.00 3 7059 124 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 3 6080 89 0 0
sp_cancel_supplier_invoice 6222 6215 7 0.11 2 21568 899 0 7
sp_cancel_supplier_order 405 405 0 0.00 11 1044560 117229 0 0
sp_cancel_write_off 8603 8603 0 0.00 1 12473 123 0 0
sp_client_order 18922 18922 0 0.00 4 31413 399 0 0
sp_customer_reserve 454398 454398 0 0.00 0 93342 169 0 0
sp_fill_shopping_cart 29391 29391 0 0.00 1 16789 42 0 0
sp_fill_shopping_cart_clo_res 454383 454383 0 0.00 0 16177 16 0 0
sp_get_clo_for_invoice 4869 4869 0 0.00 0 34238 377 0 0
sp_kill_cost_storno 15281 15281 0 0.00 0 12466 60 0 0
sp_kill_qstorno_ret_qs2qd 1135083 1135083 0 0.00 0 27160 37 0 0
sp_kill_qty_storno 596975 596975 0 0.00 0 36643 78 0 0
sp_lock_selected_doc 20134 20134 0 0.00 0 8429 5 0 0
sp_make_cost_storno 26915 26915 0 0.00 0 21470 58 0 0
sp_multiply_rows_for_pdistr 26915 26915 0 0.00 0 15709 33 0 0
sp_multiply_rows_for_qdistr 18922 18922 0 0.00 0 31400 215 0 0
sp_pay_from_customer 6699 6699 0 0.00 4 14470 109 0 0
sp_pay_to_supplier 5688 5688 0 0.00 5 10016 116 0 0
sp_payment_common 12387 12387 0 0.00 1 7752 22 0 0
sp_qd_handle_on_cancel_clo 1695 1695 0 0.00 0 9491 290 0 0
sp_qd_handle_on_invoice_upd_sts 10468 10468 0 0.00 0 36643 335 0 0
sp_qd_handle_on_reserve_upd_sts 9659 9659 0 0.00 0 21761 59 0 0
sp_reserve_write_off 9659 9659 0 0.00 7 21790 192 0 0
sp_supplier_invoice 5219 5219 0 0.00 6 113655 3377 0 0
sp_supplier_order 5253 5253 0 0.00 6 39718 1328 0 0
srv_aggregate_perf_data 97 97 0 0.00 3056 418798 21053 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1455 165549 50224 0 0
srv_find_qd_qs_mism 1049510 1049510 0 0.00 0 14116 5 0 0
srv_make_invnt_saldo 443 443 0 0.00 41 91383 3304 0 0
srv_make_money_saldo 737 737 0 0.00 1 12493 463 0 0
srv_recalc_idx_stat 4 4 0 0.00 261397 497778 383532 0 0
t$perf-abend:sp_add_invoice_to_stock 70 70 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4884 4884 0 0.00 0 9254 156 0 0
t$perf-norm:sp_cancel_adding_invoice 397 397 0 0.00 1 3957 86 0 0
t$perf-norm:sp_cancel_client_order 1695 1695 0 0.00 0 3372 10 0 0
t$perf-norm:sp_cancel_customer_reserve 651 651 0 0.00 0 1046 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 678 678 0 0.00 0 4317 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 2415 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 594 13 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 27672 1638 0 0
t$perf-norm:sp_cancel_write_off 991 991 0 0.00 0 6050 20 0 0
t$perf-norm:sp_client_order 18922 18922 0 0.00 0 6417 17 0 0
t$perf-norm:sp_customer_reserve 934 934 0 0.00 0 4856 8 0 0
t$perf-norm:sp_pay_from_customer 6699 6699 0 0.00 0 14188 11 0 0
t$perf-norm:sp_pay_to_supplier 5688 5688 0 0.00 0 7249 14 0 0
t$perf-norm:sp_reserve_write_off 9659 9659 0 0.00 0 6784 13 0 0
t$perf-norm:sp_supplier_invoice 5219 5219 0 0.00 0 6874 16 0 0
t$perf-norm:sp_supplier_order 5253 5253 0 0.00 0 8051 21 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 118 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 1670 44 0 0
t$perf-norm:srv_make_invnt_saldo 443 443 0 0.00 0 371 5 0 0
t$perf-norm:srv_make_money_saldo 737 737 0 0.00 0 14532 27 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 19 7 0 0
v_add_invoice_to_stock 9876 9736 140 1.41 0 6980 16 0 140
v_all_customers 34486 34486 0 0.00 0 21 1 0 0
v_all_suppliers 29462 29462 0 0.00 0 19 1 0 0
v_all_wares 37844 37844 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 794 794 0 0.00 0 1045 7 0 0
v_cancel_client_order 3390 3390 0 0.00 0 5085 6 0 0
v_cancel_customer_prepayment 1356 1356 0 0.00 0 7040 17 0 0
v_cancel_customer_reserve 1302 1302 0 0.00 0 2708 7 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 4217 15 0 0
v_cancel_supplier_invoice 794 780 14 1.76 0 3294 20 0 14
v_cancel_supplier_order 810 810 0 0.00 0 3067 11 0 0
v_cancel_write_off 1982 1982 0 0.00 0 2733 14 0 0
v_max_id_clo_ord 5252 5252 0 0.00 0 1106 1 0 0
v_max_id_clo_res 934 934 0 0.00 0 4064 22 0 0
v_max_id_ord_sup 5217 5217 0 0.00 0 6208 4 0 0
v_max_non_paid_invoice 1426 1426 0 0.00 0 373 2 0 0
v_max_non_paid_realizn 3572 3572 0 0.00 0 497 2 0 0
v_min_id_clo_ord 5252 5252 0 0.00 0 3683 6 0 0
v_min_id_clo_res 934 934 0 0.00 0 5866 23 0 0
v_min_id_ord_sup 5217 5217 0 0.00 0 13804 16 0 0
v_min_non_paid_invoice 5688 5688 0 0.00 0 6908 18 0 0
v_min_non_paid_realizn 6699 6699 0 0.00 0 5597 15 0 0
v_our_firm 9612 9612 0 0.00 0 17 1 0 0
v_reserve_write_off 19318 19318 0 0.00 0 7071 9 0 0
x_lock_depdocs_on_canc_invoice 11425 11425 0 0.00 0 13323 116 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 9768 362 0 0
x_make_qstorno_1000_1200 5253 5253 0 0.00 2 39707 1255 0 0
x_make_qstorno_1200_2000 5219 5219 0 0.00 3 113645 3219 0 0
x_make_qstorno_2100_3300 454398 454398 0 0.00 0 93341 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-09-2021 04:38:36 to 20-09-2021 04:38:40.

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:11 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:12 36444 8192000000 9726640336 10023284736 792661232 8.822 100 90 180 0 822339248 7708288 21969728
02:09:52 12853 8192000000 9755743440 10058821632 827314896 9.172 100 87 174 0 861097056 12722224 21059936
02:11:11 18084 8192000000 9796013712 10113867776 862973504 9.530 100 74 148 0 900919360 20935632 17010224
02:12:32 13799 8192000000 9864038576 10194206720 915699536 10.054 100 81 162 1 967232016 34051872 17480608
02:13:53 6164 8192000000 9919922960 10266615808 957654432 10.466 100 77 154 0 1031862112 58000832 16206848
02:15:02 5338 8192000000 9948742320 10308161536 990967056 10.791 100 69 138 0 1071557872 66437408 14153408
02:16:10 10520 8192000000 9965060480 10331463680 1018779984 11.060 100 61 122 2 1080524880 49854656 11890240
02:17:23 2459 8192000000 10039602448 10408452096 1045946288 11.322 100 89 179 0 1154275024 90884880 17443856
02:18:27 1568 8192000000 10027374880 10398531584 1055221984 11.411 100 89 177 1 1140328720 67411824 17694912
02:19:30 2096 8192000000 10004715248 10404061184 1058614016 11.443 100 46 92 1 1127266944 59685712 8967216
02:20:33 8731 8192000000 9975370960 10358710272 1064678416 11.501 100 39 76 4 1098847696 25913904 8255376
02:21:43 11774 8192000000 10003088288 10410291200 1073619072 11.587 100 29 58 1 1103966528 24505104 5842352
02:22:58 55730 8192000000 9990235888 10388574208 1082006608 11.667 100 39 77 2 1109812304 19673456 8132240
02:25:04 8045 8192000000 10013978928 10408288256 1086066592 11.705 100 33 66 0 1133460368 40140608 7253168
02:26:14 3982 8192000000 10004365728 10400710656 1091702704 11.759 100 28 57 1 1116072176 18315152 6054320
02:27:19 13104 8192000000 9999500624 10409226240 1075411312 11.604 99 20 38 1 1096395216 16593136 4390768
02:28:35 15480 8192000000 9976544128 10388041728 1020194272 11.074 95 34 68 0 1053077376 25116992 7766112
02:29:56 4573 8192000000 9965168480 10348969984 1010251984 10.978 96 45 89 1 1043398368 23443312 9703072
02:31:02 7801 8192000000 9939629296 10323009536 965893040 10.547 95 41 79 0 996838016 22586080 8358896
02:32:14 12258 8192000000 9941521296 10328150016 992728288 10.808 97 30 60 0 1035549856 35144928 7676640
02:33:27 30100 8192000000 9962851792 10365186048 1035687728 11.223 100 25 50 0 1075163808 34962272 4513808
02:35:03 2544 8192000000 9977731936 10365399040 1054515024 11.404 100 62 123 1 1085183056 16153808 14514224
02:36:06 7952 8192000000 9991481888 10391269376 1066141648 11.515 100 45 89 3 1094140608 18822400 9176560
02:37:16 12920 8192000000 10004837616 10394841088 1073275312 11.583 100 69 137 2 1113368288 24981408 15111568
02:38:34 109181 8192000000 10021387488 10435059712 1086137024 11.706 100 35 70 1 1156508192 62683824 7687344
02:41:35 17454 8192000000 10069956976 10490093568 1108903904 11.922 100 34 68 2 1192447696 76369792 7174000
02:42:56 17165 8192000000 10099145792 10519388160 1119743984 12.025 100 37 74 2 1211766288 82803152 9219152
02:44:18 22352 8192000000 10148132160 10558062592 1136816416 12.186 100 51 102 2 1249336112 101398960 11120736
02:45:44 9619 8192000000 10209817264 10624532480 1144773072 12.260 100 57 114 1 1316452656 159565024 12114560
02:47:05 10188 8192000000 10255047824 10664751104 1168689168 12.485 100 77 155 0 1367342816 181863008 16790640
02:48:19 9761 8192000000 10287144688 10709798912 1175164272 12.545 100 51 101 0 1397572656 210265984 12142400
02:49:31 2402 8192000000 10325781616 10728505344 1159067264 12.395 99 83 160 3 1411656064 234820016 17768784
02:50:39 4473 8192000000 10338948064 10751737856 1184295936 12.630 100 75 149 2 1441212176 240623120 16293120
02:51:48 2112 8192000000 10338940256 10738003968 1148379728 12.294 98 88 168 3 1423533360 256146400 19007232
02:52:56 2631 8192000000 10343016704 10763304960 1164722000 12.447 100 51 100 1 1456334672 279076768 12535904
02:54:03 1454 8192000000 10407675296 10814345216 1174601568 12.540 100 79 156 2 1503829568 309726064 19501936
02:55:10 2439 8192000000 10403634304 10810515456 1179101600 12.582 100 80 159 0 1497182992 299291776 18789616
02:56:18 2434 8192000000 10460482880 10866606080 1168698224 12.485 100 78 153 0 1558484656 371127552 18658880
02:57:22 5610 8192000000 10448104368 10893262848 1168545376 12.483 100 48 94 2 1549809392 368543664 12720352
02:58:30 5275 8192000000 10443242560 10887360512 1168089360 12.479 100 44 88 0 1539998864 360256080 11653424
02:59:47 10070 8192000000 10425289040 10896728064 1152135072 12.330 98 36 72 0 1508439936 346683056 9621808
03:01:04 35380 8192000000 10496549456 10938728448 1163679168 12.438 100 28 55 2 1573190736 401557248 7954320
03:02:39 32963 8192000000 10451598240 10922934272 1110227632 11.935 98 21 43 0 1579781712 462731808 6822272
03:04:21 6642 8192000000 10073832992 10513002496 1122814576 12.054 100 54 107 4 1162579792 28568832 11196384
03:05:54 47544 8192000000 10060099792 10530914304 1102712176 11.863 98 17 34 0 1161421168 55125520 3583472
03:07:56 60193 8192000000 10130168848 10587955200 1119975312 12.027 99 13 26 0 1210035952 87117296 2943344
03:09:57 69343 8192000000 10066245584 10514423808 1110298992 11.935 98 23 46 1 1155674432 40854320 4521120
03:12:09 106382 8192000000 10067573680 10554773504 1070751760 11.559 95 20 40 0 1142281984 67023040 4507184
03:14:58 165549 8192000000 10056289920 10559852544 1047779712 11.339 93 11 22 1 1096726032 46744704 2201616
03:18:44 139523 8192000000 10055317184 10526470144 1123612608 12.061 99 17 33 2 1208081840 80601568 3867664
03:22:06 120979 8192000000 10100953456 10537820160 1128917552 12.111 99 29 58 0 1157221936 22586096 5718288
03:25:08 47767 8192000000 10133222224 10592485376 1150635536 12.315 100 37 74 1 1200200000 41848784 7715680
03:27:02 93681 8192000000 10131948224 10620383232 1142016944 12.234 98 19 38 0 1221263488 75213200 4033344
03:29:39 131421 8192000000 10165166560 10680139776 1163525472 12.436 100 15 30 0 1256370368 89451440 3393456
03:32:57 50629 8192000000 10135764608 10667851776 1156658384 12.372 100 11 22 0 1266921120 107561152 2701584
03:35:18 31837 8192000000 10078629152 10517938176 1150890032 12.318 100 24 46 0 1172740784 17176960 4673792
03:36:50 65816 8192000000 10161031808 10664521728 1147567568 12.287 98 31 62 2 1266259168 112318576 6373024
03:39:35 104737 8192000000 10122220016 10608975872 1116097904 11.990 96 14 27 2 1175568352 56886640 2583808
03:42:20 43550 8192000000 10096702480 10534412288 1110333712 11.936 97 12 24 0 1165277056 52104336 2839008
03:44:07 34245 8192000000 10118764080 10546581504 1127479392 12.098 99 49 98 0 1172845152 35573408 9792352
03:45:53 80162 8192000000 10072587744 10512216064 1116513200 11.994 98 40 80 0 1164115536 39460736 8141600
03:48:14 57732 8192000000 10092746496 10577330176 1147328384 12.284 100 26 53 1 1222035520 68959344 5747792
03:50:17 25265 8192000000 10089400928 10585726976 1127820288 12.101 99 10 20 0 1191186480 61285952 2080240
03:51:43 92299 8192000000 10125803200 10620944384 1103131232 11.867 96 20 40 0 1175961728 68874080 3956416
03:54:24 45409 8192000000 10134661456 10565156864 1096971152 11.809 97 32 64 0 1184641232 81027312 6642768
03:56:13 54005 8192000000 10063124832 10508492800 1083542544 11.681 97 20 40 0 1112684544 25456704 3685296
03:58:09 81613 8192000000 10072601280 10581110784 1107164448 11.906 99 12 24 0 1215243888 105668560 2410880
04:00:33 41130 8192000000 10076512944 10499018752 1102639968 11.863 98 44 88 1 1142909456 31610240 8659248
04:02:15 53323 8192000000 10112906000 10592264192 1099001856 11.828 98 21 42 1 1201628080 98479440 4146784
04:04:12 117573 8192000000 10079299008 10514395136 1108090464 11.914 98 33 66 1 1160012976 44755424 7167088
04:07:11 22846 8192000000 10073838144 10532691968 1106209568 11.897 98 19 38 0 1157975120 47866288 3899264
04:08:36 32558 8192000000 10055766816 10532884480 1104148576 11.877 98 17 35 1 1160240016 51422016 4669424
04:10:10 72124 8192000000 10067378688 10543235072 1111793200 11.949 99 13 26 1 1155519408 40897024 2829184
04:12:29 52683 8192000000 10060875168 10529599488 1091539680 11.757 97 24 48 2 1149307760 52794576 4973504
04:14:22 41890 8192000000 10058360048 10495655936 1117082720 11.999 100 18 36 0 1148533008 27056496 4393792
04:16:06 33970 8192000000 10091931360 10544758784 1129462304 12.116 100 19 38 0 1189196064 55859808 3873952
04:17:40 73058 8192000000 10094321536 10564767744 1116871440 11.997 98 21 42 1 1184275216 63270560 4133216
04:19:55 97982 8192000000 10113114256 10557894656 1117527936 12.004 98 18 36 1 1172313248 51164128 3621184
04:22:37 30919 8192000000 10166617552 10611359744 1148580976 12.296 100 41 83 1 1245018112 87531632 8905504
04:24:10 63946 8192000000 10091618352 10548482048 1126857792 12.092 98 22 44 0 1166623024 35292624 4472608
04:26:29 105604 8192000000 10073319984 10543112192 1083862432 11.684 95 35 70 1 1124001904 33208864 6930608
04:29:27 27385 8192000000 10082332464 10552352768 1121649568 12.043 100 21 42 0 1187981312 62069648 4262096
04:30:55 60648 8192000000 10052225824 10521935872 1089636528 11.739 96 8 16 0 1118122608 26629936 1856144
04:33:02 55526 8192000000 10084573920 10541117440 1093294080 11.774 97 12 24 1 1161606640 65077840 3234720
04:35:28 58437 8192000000 10038550944 10498437120 1089356816 11.737 97 11 22 0 1122881456 31330464 2194176

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-09-2021 04:38:42 to 20-09-2021 04:38:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 70 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-09-2021 04:38:58 to 20-09-2021 04:39:00.

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          229720
MON$OLDEST_ACTIVE               231896
MON$OLDEST_SNAPSHOT             231896
MON$NEXT_TRANSACTION            231896
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                       2050784
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             795
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

Database statistics

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

Database validation

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

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

--- start of diff output ---
:65: 
:66: mscs-test-161.budzdorov.ru    Mon Sep 20 04:37:12 2021
:67:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:68: 
:69: 
:70: mscs-test-161.budzdorov.ru    Mon Sep 20 04:37:13 2021
:71:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:72: 
:73: 
:74: mscs-test-161.budzdorov.ru    Mon Sep 20 04:37:13 2021
:75:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:76: 
:77: 
:78: mscs-test-161.budzdorov.ru    Mon Sep 20 04:37:13 2021
:79:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:80: 
:81: 
:82: mscs-test-161.budzdorov.ru    Mon Sep 20 04:37:14 2021
:83:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:84: 
:85: 
:86: mscs-test-161.budzdorov.ru    Mon Sep 20 04:37:14 2021
:87:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:88: 
--- end of diff output ---

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

20.09.21 04:39:01. 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-09-20 04:39:03 No severe PSQL-related problems occured
20.09.21 04:39:04. End of report.